2018-01-23T16:30:57.052Z,1516725057.052 [Supervisor](DEBUG): Initializing supervisor.
2018-01-23T16:30:57.055Z,1516725057.055 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-01-23T16:30:57.056Z,1516725057.056 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-01-23T16:30:57.056Z,1516725057.056 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-01-23T16:30:57.057Z,1516725057.057 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-01-23T16:30:57.057Z,1516725057.057 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-01-23T16:30:57.060Z,1516725057.060 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-01-23T16:30:57.071Z,1516725057.071 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-01-23T16:30:57.072Z,1516725057.072 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-01-23T16:30:57.072Z,1516725057.072 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-01-23T16:30:57.073Z,1516725057.073 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-01-23T16:30:57.074Z,1516725057.074 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-01-23T16:30:57.074Z,1516725057.074 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-01-23T16:30:57.076Z,1516725057.076 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-01-23T16:30:57.076Z,1516725057.076 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-01-23T16:30:57.081Z,1516725057.081 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-01-23T16:30:57.380Z,1516725057.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-01-23T16:30:57.383Z,1516725057.383 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-01-23T16:30:57.561Z,1516725057.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-01-23T16:30:57.563Z,1516725057.563 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-01-23T16:30:57.839Z,1516725057.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-01-23T16:30:57.840Z,1516725057.840 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-01-23T16:30:57.977Z,1516725057.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-01-23T16:30:57.978Z,1516725057.978 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-01-23T16:30:58.074Z,1516725058.074 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-01-23T16:30:58.076Z,1516725058.076 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-01-23T16:30:58.508Z,1516725058.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-01-23T16:30:58.509Z,1516725058.509 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-01-23T16:30:58.833Z,1516725058.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-01-23T16:30:58.834Z,1516725058.834 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-01-23T16:30:59.255Z,1516725059.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-01-23T16:30:59.257Z,1516725059.257 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-01-23T16:30:59.359Z,1516725059.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-01-23T16:30:59.361Z,1516725059.361 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-01-23T16:30:59.456Z,1516725059.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-01-23T16:30:59.457Z,1516725059.457 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-01-23T16:30:59.649Z,1516725059.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-01-23T16:30:59.651Z,1516725059.651 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-01-23T16:30:59.841Z,1516725059.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-01-23T16:30:59.842Z,1516725059.842 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-01-23T16:31:00.175Z,1516725060.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-01-23T16:31:00.176Z,1516725060.176 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-01-23T16:31:00.311Z,1516725060.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-01-23T16:31:00.312Z,1516725060.312 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-01-23T16:31:00.393Z,1516725060.393 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-01-23T16:31:00.397Z,1516725060.397 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-01-23T16:31:00.924Z,1516725060.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-01-23T16:31:01.131Z,1516725061.131 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-01-23T16:31:01.223Z,1516725061.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-01-23T16:31:01.307Z,1516725061.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-01-23T16:31:01.449Z,1516725061.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-01-23T16:31:01.615Z,1516725061.615 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-01-23T16:31:01.690Z,1516725061.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-01-23T16:31:01.915Z,1516725061.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-01-23T16:31:01.916Z,1516725061.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-01-23T16:31:01.998Z,1516725061.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-01-23T16:31:02.086Z,1516725062.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-01-23T16:31:02.182Z,1516725062.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-01-23T16:31:02.301Z,1516725062.301 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-01-23T16:31:02.307Z,1516725062.307 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-01-23T16:31:02.585Z,1516725062.585 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-01-23T16:31:02.586Z,1516725062.586 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-01-23T16:31:02.642Z,1516725062.642 [DepthRateCalculator] Loaded
2018-01-23T16:31:02.642Z,1516725062.642 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-01-23T16:31:02.648Z,1516725062.648 [PitchRateCalculator] Loaded
2018-01-23T16:31:02.648Z,1516725062.648 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-01-23T16:31:02.665Z,1516725062.665 [SpeedCalculator] Loaded
2018-01-23T16:31:02.665Z,1516725062.665 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-01-23T16:31:02.686Z,1516725062.686 [TempGradientCalculator] Loaded
2018-01-23T16:31:02.686Z,1516725062.686 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-01-23T16:31:02.702Z,1516725062.702 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-01-23T16:31:02.702Z,1516725062.702 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-01-23T16:31:02.708Z,1516725062.708 [YawRateCalculator] Loaded
2018-01-23T16:31:02.708Z,1516725062.708 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-01-23T16:31:02.709Z,1516725062.709 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-01-23T16:31:02.709Z,1516725062.709 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-01-23T16:31:02.828Z,1516725062.828 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-01-23T16:31:02.828Z,1516725062.828 [StratificationFrontDetector](DEBUG): (re)initializing
2018-01-23T16:31:02.828Z,1516725062.828 [StratificationFrontDetector] Loaded
2018-01-23T16:31:02.828Z,1516725062.828 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-01-23T16:31:02.829Z,1516725062.829 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-01-23T16:31:02.831Z,1516725062.831 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-01-23T16:31:02.966Z,1516725062.966 [BuoyancyServo] Loaded
2018-01-23T16:31:02.966Z,1516725062.966 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-01-23T16:31:02.981Z,1516725062.981 [ElevatorServo] Loaded
2018-01-23T16:31:02.981Z,1516725062.981 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-01-23T16:31:02.996Z,1516725062.996 [MassServo] Loaded
2018-01-23T16:31:02.996Z,1516725062.996 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-01-23T16:31:03.012Z,1516725063.012 [RudderServo] Loaded
2018-01-23T16:31:03.012Z,1516725063.012 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-01-23T16:31:03.027Z,1516725063.027 [ThrusterServo] Loaded
2018-01-23T16:31:03.027Z,1516725063.027 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-01-23T16:31:03.027Z,1516725063.027 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-01-23T16:31:03.028Z,1516725063.028 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-01-23T16:31:03.052Z,1516725063.052 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-01-23T16:31:03.052Z,1516725063.052 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-01-23T16:31:03.176Z,1516725063.176 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-01-23T16:31:03.177Z,1516725063.177 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-01-23T16:31:03.714Z,1516725063.714 [DataOverHttps] Loaded
2018-01-23T16:31:03.714Z,1516725063.714 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-01-23T16:31:03.728Z,1516725063.728 [Depth_Keller] Loaded
2018-01-23T16:31:03.728Z,1516725063.728 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-01-23T16:31:03.733Z,1516725063.733 [DropWeight] Loaded
2018-01-23T16:31:03.734Z,1516725063.734 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-01-23T16:31:03.832Z,1516725063.832 [NAL9602] Loaded
2018-01-23T16:31:03.832Z,1516725063.832 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-01-23T16:31:03.849Z,1516725063.849 [Onboard] Loaded
2018-01-23T16:31:03.849Z,1516725063.849 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-01-23T16:31:03.859Z,1516725063.859 [Radio_Surface] Loaded
2018-01-23T16:31:03.860Z,1516725063.860 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-01-23T16:31:03.861Z,1516725063.861 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4086B4E0
2018-01-23T16:31:03.861Z,1516725063.861 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 884
2018-01-23T16:31:04.015Z,1516725064.015 [PNI_TCM] Loaded
2018-01-23T16:31:04.016Z,1516725064.016 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-01-23T16:31:04.077Z,1516725064.077 [Rowe_600LCM] Loaded
2018-01-23T16:31:04.077Z,1516725064.077 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-01-23T16:31:04.078Z,1516725064.078 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 4089B4E0
2018-01-23T16:31:04.078Z,1516725064.078 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 885
2018-01-23T16:31:06.216Z,1516725066.216 [BPC1] Loaded
2018-01-23T16:31:06.216Z,1516725066.216 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-01-23T16:31:06.216Z,1516725066.216 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-01-23T16:31:06.218Z,1516725066.218 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-01-23T16:31:06.372Z,1516725066.372 [SBIT](DEBUG): Construct Startup Built In Test.
2018-01-23T16:31:06.398Z,1516725066.398 [SBIT] Loaded
2018-01-23T16:31:06.398Z,1516725066.398 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-01-23T16:31:06.399Z,1516725066.399 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-01-23T16:31:06.410Z,1516725066.410 [IBIT] Loaded
2018-01-23T16:31:06.410Z,1516725066.410 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-01-23T16:31:06.414Z,1516725066.414 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-01-23T16:31:06.570Z,1516725066.570 [CBIT] Loaded
2018-01-23T16:31:06.570Z,1516725066.570 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-01-23T16:31:06.571Z,1516725066.571 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-01-23T16:31:06.571Z,1516725066.571 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-01-23T16:31:06.756Z,1516725066.756 [Aanderaa_O2] Loaded
2018-01-23T16:31:06.756Z,1516725066.756 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-01-23T16:31:06.768Z,1516725066.768 [CANONSampler] Loaded
2018-01-23T16:31:06.769Z,1516725066.769 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2018-01-23T16:31:06.848Z,1516725066.848 [CTD_NeilBrown] Loaded
2018-01-23T16:31:06.849Z,1516725066.849 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-01-23T16:31:06.850Z,1516725066.850 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2018-01-23T16:31:06.850Z,1516725066.850 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 886
2018-01-23T16:31:06.883Z,1516725066.883 [CTD_Seabird] Loaded
2018-01-23T16:31:06.884Z,1516725066.884 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-01-23T16:31:06.885Z,1516725066.885 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2018-01-23T16:31:06.885Z,1516725066.885 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 887
2018-01-23T16:31:06.899Z,1516725066.899 [PAR_Licor] Loaded
2018-01-23T16:31:06.900Z,1516725066.900 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-01-23T16:31:06.950Z,1516725066.950 [WetLabsBB2FL] Loaded
2018-01-23T16:31:06.950Z,1516725066.950 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-01-23T16:31:06.951Z,1516725066.951 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409C14E0
2018-01-23T16:31:06.952Z,1516725066.952 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 888
2018-01-23T16:31:06.996Z,1516725066.996 [WetLabsSeaOWL_UV_A] Loaded
2018-01-23T16:31:06.996Z,1516725066.996 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-01-23T16:31:06.997Z,1516725066.997 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409F14E0
2018-01-23T16:31:06.997Z,1516725066.997 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 889
2018-01-23T16:31:06.998Z,1516725066.998 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-01-23T16:31:06.999Z,1516725066.999 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-01-23T16:31:07.085Z,1516725067.085 [VerticalControl](DEBUG): Construct VerticalControl.
2018-01-23T16:31:07.173Z,1516725067.173 [VerticalControl] Loaded
2018-01-23T16:31:07.174Z,1516725067.174 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-01-23T16:31:07.174Z,1516725067.174 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-01-23T16:31:07.233Z,1516725067.233 [HorizontalControl] Loaded
2018-01-23T16:31:07.233Z,1516725067.233 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-01-23T16:31:07.234Z,1516725067.234 [SpeedControl](DEBUG): Construct SpeedControl.
2018-01-23T16:31:07.235Z,1516725067.235 [SpeedControl] Loaded
2018-01-23T16:31:07.236Z,1516725067.236 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-01-23T16:31:07.236Z,1516725067.236 [LoopControl](DEBUG): Construct LoopControl.
2018-01-23T16:31:07.237Z,1516725067.237 [LoopControl] Loaded
2018-01-23T16:31:07.237Z,1516725067.237 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-01-23T16:31:07.238Z,1516725067.238 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-01-23T16:31:07.238Z,1516725067.238 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-01-23T16:31:07.275Z,1516725067.275 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-01-23T16:31:07.276Z,1516725067.276 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-01-23T16:31:07.384Z,1516725067.384 [DeadReckonUsingSpeedCalculator] Loaded
2018-01-23T16:31:07.384Z,1516725067.384 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-01-23T16:31:07.441Z,1516725067.441 [DeadReckonWithRespectToSeafloor] Loaded
2018-01-23T16:31:07.441Z,1516725067.441 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-01-23T16:31:07.457Z,1516725067.457 [NavChart] Loaded
2018-01-23T16:31:07.457Z,1516725067.457 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-01-23T16:31:07.461Z,1516725067.461 [UniversalFixResidualReporter] Loaded
2018-01-23T16:31:07.462Z,1516725067.462 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-01-23T16:31:07.462Z,1516725067.462 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-01-23T16:31:07.466Z,1516725067.466 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-01-23T16:31:07.466Z,1516725067.466 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-01-23T16:31:07.473Z,1516725067.473 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-01-23T16:31:07.474Z,1516725067.474 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7C4E0
2018-01-23T16:31:07.475Z,1516725067.475 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 890
2018-01-23T16:31:07.479Z,1516725067.479 [Supervisor](INFO): Main Thread ID is 795
2018-01-23T16:31:07.479Z,1516725067.479 [Supervisor](DEBUG): Running supervisor.
2018-01-23T16:31:07.480Z,1516725067.480 [CommandLine ThreadHandler](INFO): Handler Thread ID is 891
2018-01-23T16:31:07.482Z,1516725067.482 [controlThread ThreadHandler](INFO): Handler Thread ID is 892
2018-01-23T16:31:07.483Z,1516725067.483 [controlThread](DEBUG): Initializing ControlThread
2018-01-23T16:31:07.484Z,1516725067.484 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-01-23T16:31:07.484Z,1516725067.484 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-01-23T16:31:07.484Z,1516725067.484 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-01-23T16:31:07.485Z,1516725067.485 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-01-23T16:31:07.485Z,1516725067.485 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-01-23T16:31:07.486Z,1516725067.486 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-01-23T16:31:07.491Z,1516725067.491 [SBIT](INFO): Initialize SBIT Component.
2018-01-23T16:31:07.492Z,1516725067.492 [SBIT](IMPORTANT): git: 2017-12-12
2018-01-23T16:31:07.492Z,1516725067.492 [SBIT](INFO): git hash: 18638d31f033e3fa0cb90b26256f5481d37f00cc
2018-01-23T16:31:07.492Z,1516725067.492 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-01-23T16:31:07.493Z,1516725067.493 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-01-23T16:31:07.494Z,1516725067.494 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-01-23T16:31:07.495Z,1516725067.495 [IBIT](INFO): Initialize IBIT Component.
2018-01-23T16:31:07.496Z,1516725067.496 [CBIT](DEBUG): Initialize CBIT Component.
2018-01-23T16:31:07.496Z,1516725067.496 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-01-23T16:31:07.496Z,1516725067.496 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-01-23T16:31:07.497Z,1516725067.497 [logger ThreadHandler](INFO): Handler Thread ID is 893
2018-01-23T16:31:07.517Z,1516725067.517 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 894
2018-01-23T16:31:07.523Z,1516725067.523 [Radio_Surface](INFO): Powering up
2018-01-23T16:31:07.529Z,1516725067.529 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 895
2018-01-23T16:31:07.530Z,1516725067.530 [Rowe_600LCM](INFO): Initializing
2018-01-23T16:31:07.530Z,1516725067.530 [Rowe_600LCM](INFO): Checking LCM
2018-01-23T16:31:07.585Z,1516725067.585 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-01-23T16:31:07.587Z,1516725067.587 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-01-23T16:31:07.587Z,1516725067.587 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-01-23T16:31:07.588Z,1516725067.588 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-01-23T16:31:07.588Z,1516725067.588 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-23T16:31:07.635Z,1516725067.635 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:31:07.636Z,1516725067.636 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:31:07.636Z,1516725067.636 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:31:07.637Z,1516725067.637 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:31:07.637Z,1516725067.637 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:31:07.637Z,1516725067.637 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-01-23T16:31:07.638Z,1516725067.638 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-01-23T16:31:07.639Z,1516725067.639 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-01-23T16:31:07.649Z,1516725067.649 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 896
2018-01-23T16:31:07.651Z,1516725067.651 [CTD_NeilBrown](INFO): Powering down
2018-01-23T16:31:07.750Z,1516725067.750 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 897
2018-01-23T16:31:07.753Z,1516725067.753 [CTD_Seabird](INFO): Initializing
2018-01-23T16:31:07.786Z,1516725067.786 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 899
2018-01-23T16:31:07.787Z,1516725067.787 [WetLabsBB2FL](INFO): Powering down
2018-01-23T16:31:07.811Z,1516725067.811 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-01-23T16:31:07.895Z,1516725067.895 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 900
2018-01-23T16:31:07.905Z,1516725067.905 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 901
2018-01-23T16:31:07.908Z,1516725067.908 [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>


2018-01-23T16:31:07.913Z,1516725067.913 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-01-23T16:31:07.913Z,1516725067.913 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-01-23T16:31:07.913Z,1516725067.913 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-01-23T16:31:07.913Z,1516725067.913 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-01-23T16:31:07.914Z,1516725067.914 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-01-23T16:31:07.914Z,1516725067.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-01-23T16:31:07.914Z,1516725067.914 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-01-23T16:31:07.914Z,1516725067.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-01-23T16:31:07.914Z,1516725067.914 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-01-23T16:31:07.915Z,1516725067.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-01-23T16:31:07.915Z,1516725067.915 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-01-23T16:31:07.915Z,1516725067.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-01-23T16:31:07.915Z,1516725067.915 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-01-23T16:31:07.915Z,1516725067.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-01-23T16:31:07.916Z,1516725067.916 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-01-23T16:31:07.916Z,1516725067.916 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-01-23T16:31:07.929Z,1516725067.929 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-01-23T16:31:07.995Z,1516725067.995 [Rowe_600LCM](INFO): LCM OK
2018-01-23T16:31:07.995Z,1516725067.995 [Rowe_600LCM](INFO): Powering up
2018-01-23T16:31:08.054Z,1516725068.054 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-01-23T16:31:08.055Z,1516725068.055 [Default:A.Wait](DEBUG): Construct Wait.
2018-01-23T16:31:08.057Z,1516725068.057 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-01-23T16:31:08.094Z,1516725068.094 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-01-23T16:31:08.117Z,1516725068.117 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-01-23T16:31:08.122Z,1516725068.122 [Default:E.Execute](DEBUG): Construct Execute.
2018-01-23T16:31:08.151Z,1516725068.151 [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>


2018-01-23T16:31:08.156Z,1516725068.156 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-01-23T16:31:08.171Z,1516725068.171 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-01-23T16:31:08.171Z,1516725068.171 [CTD_Seabird](INFO): Powering down
2018-01-23T16:31:08.206Z,1516725068.206 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-01-23T16:31:08.231Z,1516725068.231 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-01-23T16:31:08.451Z,1516725068.451 [CANONSampler](INFO): Powering down
2018-01-23T16:31:08.637Z,1516725068.637 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-01-23T16:31:08.721Z,1516725068.721 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:08.834Z,1516725068.834 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:08.934Z,1516725068.934 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-01-23T16:31:08.942Z,1516725068.942 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-01-23T16:31:08.961Z,1516725068.961 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-01-23T16:31:08.969Z,1516725068.969 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-01-23T16:31:08.980Z,1516725068.980 [MassServo](DEBUG): Initializing EZServoServo.
2018-01-23T16:31:08.985Z,1516725068.985 [MassServo](DEBUG): Initializing MassServo.
2018-01-23T16:31:09.003Z,1516725069.003 [RudderServo](DEBUG): Initializing EZServoServo.
2018-01-23T16:31:09.009Z,1516725069.009 [RudderServo](DEBUG): Initializing RudderServo.
2018-01-23T16:31:09.031Z,1516725069.031 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-01-23T16:31:09.037Z,1516725069.037 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-01-23T16:31:09.071Z,1516725069.071 [CommandLine](FAULT): Scheduling is paused
2018-01-23T16:31:09.071Z,1516725069.071 [CBIT](INFO): Critical error at 20180123T163107
2018-01-23T16:31:09.071Z,1516725069.071 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-01-23T16:31:09.339Z,1516725069.339 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:09.340Z,1516725069.340 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:09.575Z,1516725069.575 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:09.575Z,1516725069.575 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:09.921Z,1516725069.921 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-01-23T16:31:09.921Z,1516725069.921 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-01-23T16:31:09.921Z,1516725069.921 [BuoyancyServo] Communications Fault, FailCount= 1
2018-01-23T16:31:09.921Z,1516725069.921 [BuoyancyServo](ERROR): Communications Fault
2018-01-23T16:31:10.120Z,1516725070.120 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-01-23T16:31:10.163Z,1516725070.163 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:10.164Z,1516725070.164 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:10.185Z,1516725070.185 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-01-23T16:31:10.186Z,1516725070.186 [BuoyancyServo](INFO): Powering down
2018-01-23T16:31:10.575Z,1516725070.575 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:10.576Z,1516725070.576 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:10.980Z,1516725070.980 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:10.981Z,1516725070.981 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:11.378Z,1516725071.378 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:11.379Z,1516725071.379 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:11.767Z,1516725071.767 [Aanderaa_O2](INFO): Powering down
2018-01-23T16:31:11.861Z,1516725071.861 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:11.878Z,1516725071.878 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-23T16:31:12.109Z,1516725072.109 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-01-23T16:31:12.282Z,1516725072.282 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-01-23T16:31:12.282Z,1516725072.282 [BuoyancyServo] No Fault, FailCount= 1
2018-01-23T16:31:12.285Z,1516725072.285 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2018-01-23T16:31:12.410Z,1516725072.410 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-01-23T16:31:12.411Z,1516725072.411 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-01-23T16:31:12.411Z,1516725072.411 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-01-23T16:31:12.660Z,1516725072.660 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-01-23T16:31:12.660Z,1516725072.660 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-01-23T16:31:31.081Z,1516725091.081 [SBIT](IMPORTANT): Beginning Startup BIT
2018-01-23T16:31:31.092Z,1516725091.092 [CBIT](IMPORTANT): Beginning ground fault scan
2018-01-23T16:31:33.903Z,1516725093.903 [NAL9602](INFO): Powering up NAL9602
2018-01-23T16:31:42.024Z,1516725102.024 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.012670
CHAN A1 (24V): 0.119034
CHAN A2 (12V): -0.001867
CHAN A3 (5V): -0.001398
CHAN B0 (3.3V): 0.000291
CHAN B1 (3.15aV): 0.000411
CHAN B2 (3.15bV): -0.000513
CHAN B3 (GND): 0.000645
OPEN: -0.000482
Full Scale Calc: 4.765 mA, -1.589 mA
2018-01-23T16:31:44.703Z,1516725104.703 [NAL9602](INFO): NAL9602 initialized
2018-01-23T16:31:50.657Z,1516725110.657 [PNI_TCM](ERROR): CRC does not match. Expected:0x10509 got:0x62232
2018-01-23T16:31:55.174Z,1516725115.174 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:31:55.834Z,1516725115.834 [PNI_TCM](ERROR): CRC does not match. Expected:0x51162 got:0x35867
2018-01-23T16:31:55.969Z,1516725115.969 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:31:56.765Z,1516725116.765 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:31:57.377Z,1516725117.377 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:31:58.173Z,1516725118.173 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:31:58.643Z,1516725118.643 [PNI_TCM](ERROR): CRC does not match. Expected:0x24427 got:0x36068
2018-01-23T16:31:58.972Z,1516725118.972 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:31:59.429Z,1516725119.429 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:00.226Z,1516725120.226 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:00.857Z,1516725120.857 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:01.653Z,1516725121.653 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:02.450Z,1516725122.450 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:02.853Z,1516725122.853 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:03.649Z,1516725123.649 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:04.446Z,1516725124.446 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:04.849Z,1516725124.849 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:05.032Z,1516725125.032 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:32:05.032Z,1516725125.032 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:32:05.032Z,1516725125.032 [BPC1](ERROR): Communications Fault
2018-01-23T16:32:05.103Z,1516725125.103 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:32:05.645Z,1516725125.645 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:06.302Z,1516725126.302 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:32:06.302Z,1516725126.302 [BPC1] No Fault, FailCount= 1
2018-01-23T16:32:06.443Z,1516725126.443 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:06.893Z,1516725126.893 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:07.689Z,1516725127.689 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:08.485Z,1516725128.485 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:09.133Z,1516725129.133 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:09.929Z,1516725129.929 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:10.725Z,1516725130.725 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:11.361Z,1516725131.361 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:12.157Z,1516725132.157 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:12.954Z,1516725132.954 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:13.358Z,1516725133.358 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:14.153Z,1516725134.153 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:14.853Z,1516725134.853 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:15.650Z,1516725135.650 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:16.446Z,1516725136.446 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:16.890Z,1516725136.890 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:17.686Z,1516725137.686 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:18.482Z,1516725138.482 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:18.885Z,1516725138.885 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:19.683Z,1516725139.683 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:20.478Z,1516725140.478 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:20.906Z,1516725140.906 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:21.702Z,1516725141.702 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:22.497Z,1516725142.497 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:23.073Z,1516725143.073 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:23.869Z,1516725143.869 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:24.665Z,1516725144.665 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:24.878Z,1516725144.878 [SBIT](IMPORTANT): SBIT PASSED
2018-01-23T16:32:25.165Z,1516725145.165 [MissionManager](IMPORTANT): Started mission Startup
2018-01-23T16:32:25.165Z,1516725145.165 [Startup] Running Loop=1
2018-01-23T16:32:25.166Z,1516725145.166 [Startup](DEBUG): Aggregate::initialize Startup
2018-01-23T16:32:25.166Z,1516725145.166 [Startup:A.GoToSurface] Running Loop=1
2018-01-23T16:32:25.166Z,1516725145.166 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-01-23T16:32:25.167Z,1516725145.167 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-01-23T16:32:25.167Z,1516725145.167 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-01-23T16:32:25.167Z,1516725145.167 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-01-23T16:32:25.168Z,1516725145.168 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-01-23T16:32:25.168Z,1516725145.168 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-01-23T16:32:25.198Z,1516725145.198 [Startup:StartupSatComms] Running Loop=1
2018-01-23T16:32:25.198Z,1516725145.198 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-01-23T16:32:25.198Z,1516725145.198 [Startup:StartupSatComms:A] Running Loop=1
2018-01-23T16:32:25.369Z,1516725145.369 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:25.601Z,1516725145.601 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-01-23T16:32:26.165Z,1516725146.165 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:26.962Z,1516725146.962 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:27.365Z,1516725147.365 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:28.161Z,1516725148.161 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:28.842Z,1516725148.842 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:29.637Z,1516725149.637 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:30.433Z,1516725150.433 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:30.870Z,1516725150.870 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:31.668Z,1516725151.668 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:32.462Z,1516725152.462 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:32.865Z,1516725152.865 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:33.662Z,1516725153.662 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:34.459Z,1516725154.459 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:34.901Z,1516725154.901 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:35.697Z,1516725155.697 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:36.493Z,1516725156.493 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:37.061Z,1516725157.061 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:37.859Z,1516725157.859 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:38.653Z,1516725158.653 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:39.361Z,1516725159.361 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:40.157Z,1516725160.157 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:40.954Z,1516725160.954 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:41.156Z,1516725161.156 [BPC1](ERROR): Failed to parse bank B battery data
2018-01-23T16:32:41.156Z,1516725161.156 [BPC1] Data Fault, FailCount= 1
2018-01-23T16:32:41.156Z,1516725161.156 [BPC1](ERROR): Data Fault
2018-01-23T16:32:41.281Z,1516725161.281 [CBIT](ERROR): Data Fault in component: BPC1
2018-01-23T16:32:41.361Z,1516725161.361 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:42.157Z,1516725162.157 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:42.554Z,1516725162.554 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:32:42.554Z,1516725162.554 [BPC1] No Fault, FailCount= 1
2018-01-23T16:32:42.849Z,1516725162.849 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:43.645Z,1516725163.645 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:44.441Z,1516725164.441 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:44.853Z,1516725164.853 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:45.650Z,1516725165.650 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:46.446Z,1516725166.446 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:46.856Z,1516725166.856 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:47.649Z,1516725167.649 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:48.448Z,1516725168.448 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:48.901Z,1516725168.901 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:49.697Z,1516725169.697 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:50.493Z,1516725170.493 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:51.061Z,1516725171.061 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:51.857Z,1516725171.857 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:52.657Z,1516725172.657 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:53.377Z,1516725173.377 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:54.173Z,1516725174.173 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:54.970Z,1516725174.970 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:55.373Z,1516725175.373 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:56.169Z,1516725176.169 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:56.862Z,1516725176.862 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:57.657Z,1516725177.657 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:58.454Z,1516725178.454 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:58.865Z,1516725178.865 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:32:59.662Z,1516725179.662 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:00.458Z,1516725180.458 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:00.869Z,1516725180.869 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:01.665Z,1516725181.665 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:02.462Z,1516725182.462 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:02.901Z,1516725182.901 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:03.164Z,1516725183.164 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:33:03.164Z,1516725183.164 [BPC1] Communications Fault, FailCount= 2
2018-01-23T16:33:03.164Z,1516725183.164 [BPC1](ERROR): Communications Fault
2018-01-23T16:33:03.297Z,1516725183.297 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:33:03.697Z,1516725183.697 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:04.494Z,1516725184.494 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:04.501Z,1516725184.501 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:33:04.501Z,1516725184.501 [BPC1] No Fault, FailCount= 2
2018-01-23T16:33:05.065Z,1516725185.065 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:05.861Z,1516725185.861 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:06.657Z,1516725186.657 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:07.361Z,1516725187.361 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:08.157Z,1516725188.157 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-01-23T16:33:08.549Z,1516725188.549 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-01-23T16:33:08.549Z,1516725188.549 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-01-23T16:33:08.549Z,1516725188.549 [Rowe_600LCM](ERROR): Communications Fault
2018-01-23T16:33:08.917Z,1516725188.917 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-01-23T16:33:08.958Z,1516725188.958 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-01-23T16:33:09.085Z,1516725189.085 [Rowe_600LCM](INFO): Powering down
2018-01-23T16:33:09.893Z,1516725189.893 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-01-23T16:33:09.893Z,1516725189.893 [Rowe_600LCM] Hardware Fault, FailCount= 1
2018-01-23T16:33:09.893Z,1516725189.893 [Rowe_600LCM](ERROR): Hardware Fault
2018-01-23T16:33:10.453Z,1516725190.453 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-01-23T16:33:10.453Z,1516725190.453 [Rowe_600LCM] No Fault, FailCount= 1
2018-01-23T16:33:10.701Z,1516725190.701 [Rowe_600LCM](INFO): Initializing
2018-01-23T16:33:10.701Z,1516725190.701 [Rowe_600LCM](INFO): Checking LCM
2018-01-23T16:33:10.702Z,1516725190.702 [Rowe_600LCM](INFO): LCM OK
2018-01-23T16:33:10.702Z,1516725190.702 [Rowe_600LCM](INFO): Powering up
2018-01-23T16:33:14.998Z,1516725194.998 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-01-23T16:33:15.068Z,1516725195.068 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2018-01-23T16:33:15.072Z,1516725195.072 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-01-23T16:33:15.075Z,1516725195.075 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-01-23T16:33:15.076Z,1516725195.076 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-01-23T16:33:25.209Z,1516725205.209 [Startup:StartupSatComms:A](INFO): Timed out from 2018-01-23T16:32:25.2Z
2018-01-23T16:33:25.209Z,1516725205.209 [Startup:StartupSatComms:A] Stopped
2018-01-23T16:33:25.209Z,1516725205.209 [Startup:StartupSatComms:B] Running Loop=1
2018-01-23T16:33:25.604Z,1516725205.604 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-01-23T16:34:07.594Z,1516725247.594 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:34:07.594Z,1516725247.594 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-01-23T16:34:07.594Z,1516725247.594 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-01-23T16:34:07.689Z,1516725247.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-01-23T16:34:07.980Z,1516725247.980 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:34:07.980Z,1516725247.980 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-01-23T16:34:07.980Z,1516725247.980 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-01-23T16:34:08.070Z,1516725248.070 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-01-23T16:34:08.070Z,1516725248.070 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-01-23T16:34:08.071Z,1516725248.071 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-01-23T16:34:08.375Z,1516725248.375 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-23T16:34:08.375Z,1516725248.375 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:34:08.375Z,1516725248.375 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:34:08.474Z,1516725248.474 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-01-23T16:34:08.474Z,1516725248.474 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-01-23T16:34:08.763Z,1516725248.763 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:34:08.764Z,1516725248.764 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:34:08.764Z,1516725248.764 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:34:25.582Z,1516725265.582 [Startup:StartupSatComms:B](INFO): Timed out from 2018-01-23T16:33:25.2Z
2018-01-23T16:34:25.582Z,1516725265.582 [Startup:StartupSatComms:B] Stopped
2018-01-23T16:34:25.582Z,1516725265.582 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-01-23T16:34:25.582Z,1516725265.582 [Startup:StartupSatComms] Stopped
2018-01-23T16:34:25.582Z,1516725265.582 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-01-23T16:34:25.583Z,1516725265.583 [Startup](INFO): Completed Startup
2018-01-23T16:34:25.583Z,1516725265.583 [MissionManager](INFO): Startup is completed.
2018-01-23T16:34:25.583Z,1516725265.583 [MissionManager](INFO): Uninitializing Mission Startup
2018-01-23T16:34:25.583Z,1516725265.583 [Startup] Stopped
2018-01-23T16:34:25.607Z,1516725265.607 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-01-23T16:34:25.608Z,1516725265.608 [Startup:A.GoToSurface] Stopped
2018-01-23T16:34:25.608Z,1516725265.608 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-01-23T16:34:25.996Z,1516725265.996 [MissionManager](IMPORTANT): Started mission Default
2018-01-23T16:34:25.996Z,1516725265.996 [Default] Running Loop=1
2018-01-23T16:34:25.996Z,1516725265.996 [Default](DEBUG): Aggregate::initialize Default
2018-01-23T16:34:26.001Z,1516725266.001 [Default:B.GoToSurface] Running Loop=1
2018-01-23T16:34:26.001Z,1516725266.001 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-01-23T16:34:26.001Z,1516725266.001 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-01-23T16:34:26.001Z,1516725266.001 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-01-23T16:34:26.002Z,1516725266.002 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-01-23T16:34:26.002Z,1516725266.002 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-01-23T16:34:26.002Z,1516725266.002 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-01-23T16:34:26.002Z,1516725266.002 [Default:A.Wait] Running Loop=1
2018-01-23T16:34:26.003Z,1516725266.003 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-01-23T16:34:39.179Z,1516725279.179 [Default:A.Wait](INFO): Done Waiting.
2018-01-23T16:34:39.179Z,1516725279.179 [Default:A.Wait] Stopped
2018-01-23T16:34:39.179Z,1516725279.179 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-01-23T16:34:39.579Z,1516725279.579 [Default:CheckIn] Running Loop=1
2018-01-23T16:34:39.579Z,1516725279.579 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-01-23T16:34:39.579Z,1516725279.579 [Default:CheckIn:Read_GPS] Running Loop=1
2018-01-23T16:34:39.970Z,1516725279.970 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-01-23T16:34:52.781Z,1516725292.781 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:34:52.781Z,1516725292.781 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:34:52.782Z,1516725292.782 [BPC1](ERROR): Communications Fault
2018-01-23T16:34:52.865Z,1516725292.865 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:34:54.077Z,1516725294.077 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:34:54.077Z,1516725294.077 [BPC1] No Fault, FailCount= 1
2018-01-23T16:35:40.593Z,1516725340.593 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003714
2018-01-23T16:35:44.637Z,1516725344.637 [PNI_TCM](ERROR): CRC does not match. Expected:0x61927 got:0x1797
2018-01-23T16:35:50.634Z,1516725350.634 [PNI_TCM](ERROR): CRC does not match. Expected:0x6313 got:0x50688
2018-01-23T16:35:51.429Z,1516725351.429 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:35:51.429Z,1516725351.429 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:35:51.429Z,1516725351.429 [BPC1](ERROR): Communications Fault
2018-01-23T16:35:51.513Z,1516725351.513 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:35:52.729Z,1516725352.729 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:35:52.729Z,1516725352.729 [BPC1] No Fault, FailCount= 1
2018-01-23T16:35:57.028Z,1516725357.028 [PNI_TCM](ERROR): CRC does not match. Expected:0x51916 got:0x61611
2018-01-23T16:35:58.622Z,1516725358.622 [PNI_TCM](ERROR): CRC does not match. Expected:0x10971 got:0x47310
2018-01-23T16:35:59.032Z,1516725359.032 [PNI_TCM](ERROR): CRC does not match. Expected:0x3151 got:0x4
2018-01-23T16:36:02.218Z,1516725362.218 [PNI_TCM](ERROR): CRC does not match. Expected:0x34223 got:0x50752
2018-01-23T16:36:26.240Z,1516725386.240 [PNI_TCM](ERROR): CRC does not match. Expected:0x31294 got:0x17211
2018-01-23T16:36:28.285Z,1516725388.285 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-01-23T16:36:28.285Z,1516725388.285 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-01-23T16:36:28.285Z,1516725388.285 [Rowe_600LCM](ERROR): Communications Fault
2018-01-23T16:36:28.333Z,1516725388.333 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-01-23T16:36:28.697Z,1516725388.697 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-01-23T16:36:28.843Z,1516725388.843 [Rowe_600LCM](INFO): Powering down
2018-01-23T16:36:29.034Z,1516725389.034 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2018-01-23T16:36:30.361Z,1516725390.361 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-01-23T16:36:30.361Z,1516725390.361 [Rowe_600LCM] No Fault, FailCount= 2
2018-01-23T16:36:30.457Z,1516725390.457 [Rowe_600LCM](INFO): Initializing
2018-01-23T16:36:30.457Z,1516725390.457 [Rowe_600LCM](INFO): Checking LCM
2018-01-23T16:36:30.458Z,1516725390.458 [Rowe_600LCM](INFO): LCM OK
2018-01-23T16:36:30.458Z,1516725390.458 [Rowe_600LCM](INFO): Powering up
2018-01-23T16:36:30.627Z,1516725390.627 [PNI_TCM](ERROR): CRC does not match. Expected:0x23425 got:0x3698
2018-01-23T16:36:33.831Z,1516725393.831 [PNI_TCM](ERROR): CRC does not match. Expected:0x62581 got:0x7197
2018-01-23T16:36:34.616Z,1516725394.616 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-01-23T16:36:34.780Z,1516725394.780 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2018-01-23T16:36:34.783Z,1516725394.783 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-01-23T16:36:34.786Z,1516725394.786 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-01-23T16:36:34.787Z,1516725394.787 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-01-23T16:36:44.568Z,1516725404.568 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:36:44.568Z,1516725404.568 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:36:44.568Z,1516725404.568 [BPC1](ERROR): Communications Fault
2018-01-23T16:36:44.701Z,1516725404.701 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:36:45.897Z,1516725405.897 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:36:45.897Z,1516725405.897 [BPC1] No Fault, FailCount= 1
2018-01-23T16:36:48.547Z,1516725408.547 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-01-23T16:37:08.602Z,1516725428.602 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:37:08.603Z,1516725428.603 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-01-23T16:37:08.603Z,1516725428.603 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-01-23T16:37:08.685Z,1516725428.685 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-01-23T16:37:08.998Z,1516725428.998 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:37:08.998Z,1516725428.998 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-01-23T16:37:08.999Z,1516725428.999 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-01-23T16:37:09.077Z,1516725429.077 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-01-23T16:37:09.077Z,1516725429.077 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-01-23T16:37:09.078Z,1516725429.078 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-01-23T16:37:09.450Z,1516725429.450 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-23T16:37:09.450Z,1516725429.450 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:37:09.451Z,1516725429.451 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:37:09.541Z,1516725429.541 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-01-23T16:37:09.541Z,1516725429.541 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-01-23T16:37:09.808Z,1516725429.808 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:37:09.809Z,1516725429.809 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:37:09.809Z,1516725429.809 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:37:43.295Z,1516725463.295 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:37:43.296Z,1516725463.296 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:37:43.296Z,1516725463.296 [BPC1](ERROR): Communications Fault
2018-01-23T16:37:43.385Z,1516725463.385 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:37:44.601Z,1516725464.601 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:37:44.601Z,1516725464.601 [BPC1] No Fault, FailCount= 1
2018-01-23T16:38:05.298Z,1516725485.298 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:38:05.299Z,1516725485.299 [BPC1] Communications Fault, FailCount= 2
2018-01-23T16:38:05.299Z,1516725485.299 [BPC1](ERROR): Communications Fault
2018-01-23T16:38:05.481Z,1516725485.481 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:38:06.598Z,1516725486.598 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:38:06.598Z,1516725486.598 [BPC1] No Fault, FailCount= 2
2018-01-23T16:38:07.919Z,1516725487.919 [CommandLine](IMPORTANT): got command burn 10.000000
2018-01-23T16:38:17.937Z,1516725497.937 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-01-23T16:38:25.087Z,1516725505.087 [CommandLine](IMPORTANT): got command burn on
2018-01-23T16:38:25.087Z,1516725505.087 [CommandLine](IMPORTANT): Activating dropweight wire
2018-01-23T16:38:30.741Z,1516725510.741 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-01-23T16:38:30.741Z,1516725510.741 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-01-23T16:38:30.741Z,1516725510.741 [Rowe_600LCM](ERROR): Communications Fault
2018-01-23T16:38:30.997Z,1516725510.997 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-01-23T16:38:31.145Z,1516725511.145 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-01-23T16:38:31.246Z,1516725511.246 [Rowe_600LCM](INFO): Powering down
2018-01-23T16:38:32.601Z,1516725512.601 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-01-23T16:38:32.602Z,1516725512.602 [Rowe_600LCM] No Fault, FailCount= 3
2018-01-23T16:38:32.862Z,1516725512.862 [Rowe_600LCM](INFO): Initializing
2018-01-23T16:38:32.864Z,1516725512.864 [Rowe_600LCM](INFO): Checking LCM
2018-01-23T16:38:32.864Z,1516725512.864 [Rowe_600LCM](INFO): LCM OK
2018-01-23T16:38:32.864Z,1516725512.864 [Rowe_600LCM](INFO): Powering up
2018-01-23T16:38:37.120Z,1516725517.120 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-01-23T16:38:37.192Z,1516725517.192 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2018-01-23T16:38:37.195Z,1516725517.195 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-01-23T16:38:37.196Z,1516725517.196 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-01-23T16:38:37.197Z,1516725517.197 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-01-23T16:38:46.866Z,1516725526.866 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:38:46.866Z,1516725526.866 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:38:46.866Z,1516725526.866 [BPC1](ERROR): Communications Fault
2018-01-23T16:38:47.008Z,1516725527.008 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:38:48.165Z,1516725528.165 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:38:48.165Z,1516725528.165 [BPC1] No Fault, FailCount= 1
2018-01-23T16:38:49.391Z,1516725529.391 [CommandLine](IMPORTANT): got command burn off
2018-01-23T16:38:49.391Z,1516725529.391 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-01-23T16:39:07.221Z,1516725547.221 [CommandLine](IMPORTANT): got command burn 10.000000
2018-01-23T16:39:17.253Z,1516725557.253 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-01-23T16:39:40.949Z,1516725580.949 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:39:40.949Z,1516725580.949 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:39:40.949Z,1516725580.949 [BPC1](ERROR): Communications Fault
2018-01-23T16:39:40.960Z,1516725580.960 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-01-23T16:34:39.6Z
2018-01-23T16:39:40.960Z,1516725580.960 [Default:CheckIn:Read_GPS] Stopped
2018-01-23T16:39:40.960Z,1516725580.960 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-01-23T16:39:41.036Z,1516725581.036 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:39:41.127Z,1516725581.127 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-01-23T16:39:42.065Z,1516725582.065 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:39:42.065Z,1516725582.065 [BPC1] No Fault, FailCount= 1
2018-01-23T16:39:46.665Z,1516725586.665 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180123T161914/Courier0004.lzma
2018-01-23T16:39:47.462Z,1516725587.462 [DataOverHttps](INFO): Moved sent file to Logs/20180123T161914/Courier0004.lzma.bak
2018-01-23T16:39:47.462Z,1516725587.462 [DataOverHttps](INFO): SBD MOMSN=7755903
2018-01-23T16:39:56.782Z,1516725596.782 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180123T161914/Courier0008.lzma
2018-01-23T16:39:57.582Z,1516725597.582 [DataOverHttps](INFO): Moved sent file to Logs/20180123T161914/Courier0008.lzma.bak
2018-01-23T16:39:57.582Z,1516725597.582 [DataOverHttps](INFO): SBD MOMSN=7755905
2018-01-23T16:40:02.803Z,1516725602.803 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:40:02.803Z,1516725602.803 [BPC1] Communications Fault, FailCount= 2
2018-01-23T16:40:02.803Z,1516725602.803 [BPC1](ERROR): Communications Fault
2018-01-23T16:40:02.893Z,1516725602.893 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:40:06.981Z,1516725606.981 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:40:06.981Z,1516725606.981 [BPC1] No Fault, FailCount= 2
2018-01-23T16:40:07.010Z,1516725607.010 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20180123T163057/Courier0000.lzma
2018-01-23T16:40:07.789Z,1516725607.789 [DataOverHttps](INFO): Moved sent file to Logs/20180123T163057/Courier0000.lzma.bak
2018-01-23T16:40:07.790Z,1516725607.790 [DataOverHttps](INFO): SBD MOMSN=7755907
2018-01-23T16:40:11.992Z,1516725611.992 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:40:11.992Z,1516725611.992 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-01-23T16:40:11.992Z,1516725611.992 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-01-23T16:40:11.995Z,1516725611.995 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:40:11.995Z,1516725611.995 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-01-23T16:40:11.995Z,1516725611.995 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-01-23T16:40:12.105Z,1516725612.105 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-01-23T16:40:12.105Z,1516725612.105 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-01-23T16:40:12.309Z,1516725612.309 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-01-23T16:40:12.309Z,1516725612.309 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-01-23T16:40:12.310Z,1516725612.310 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-01-23T16:40:12.310Z,1516725612.310 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-01-23T16:40:12.603Z,1516725612.603 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-23T16:40:12.603Z,1516725612.603 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:40:12.603Z,1516725612.603 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:40:12.604Z,1516725612.604 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:40:12.604Z,1516725612.604 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:40:12.604Z,1516725612.604 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:40:17.154Z,1516725617.154 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180123T163057/Courier0004.lzma
2018-01-23T16:40:17.954Z,1516725617.954 [DataOverHttps](INFO): Moved sent file to Logs/20180123T163057/Courier0004.lzma.bak
2018-01-23T16:40:17.954Z,1516725617.954 [DataOverHttps](INFO): SBD MOMSN=7755911
2018-01-23T16:40:29.183Z,1516725629.183 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20180123T161914/Express0005.lzma
2018-01-23T16:40:29.973Z,1516725629.973 [DataOverHttps](INFO): Moved sent file to Logs/20180123T161914/Express0005.lzma.bak
2018-01-23T16:40:29.974Z,1516725629.974 [DataOverHttps](INFO): SBD MOMSN=7755913
2018-01-23T16:40:33.537Z,1516725633.537 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-01-23T16:40:33.537Z,1516725633.537 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-01-23T16:40:33.537Z,1516725633.537 [Rowe_600LCM](ERROR): Communications Fault
2018-01-23T16:40:33.941Z,1516725633.941 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-01-23T16:40:34.119Z,1516725634.119 [Rowe_600LCM](INFO): Powering down
2018-01-23T16:40:34.457Z,1516725634.457 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-01-23T16:40:35.419Z,1516725635.419 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-01-23T16:40:35.419Z,1516725635.419 [Rowe_600LCM] No Fault, FailCount= 4
2018-01-23T16:40:35.733Z,1516725635.733 [Rowe_600LCM](INFO): Initializing
2018-01-23T16:40:35.734Z,1516725635.734 [Rowe_600LCM](INFO): Checking LCM
2018-01-23T16:40:35.734Z,1516725635.734 [Rowe_600LCM](INFO): LCM OK
2018-01-23T16:40:35.734Z,1516725635.734 [Rowe_600LCM](INFO): Powering up
2018-01-23T16:40:39.828Z,1516725639.828 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180123T161914/Express0009.lzma
2018-01-23T16:40:40.051Z,1516725640.051 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-01-23T16:40:40.116Z,1516725640.116 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2018-01-23T16:40:40.120Z,1516725640.120 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-01-23T16:40:40.121Z,1516725640.121 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-01-23T16:40:40.122Z,1516725640.122 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-01-23T16:40:40.621Z,1516725640.621 [DataOverHttps](INFO): Moved sent file to Logs/20180123T161914/Express0009.lzma.bak
2018-01-23T16:40:40.622Z,1516725640.622 [DataOverHttps](INFO): SBD MOMSN=7755922
2018-01-23T16:40:50.437Z,1516725650.437 [DataOverHttps](INFO): Sending 746 bytes from file Logs/20180123T163057/Express0001.lzma
2018-01-23T16:40:51.229Z,1516725651.229 [DataOverHttps](INFO): Moved sent file to Logs/20180123T163057/Express0001.lzma.bak
2018-01-23T16:40:51.229Z,1516725651.229 [DataOverHttps](INFO): SBD MOMSN=7755924
2018-01-23T16:41:00.666Z,1516725660.666 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20180123T163057/Express0005.lzma
2018-01-23T16:41:01.467Z,1516725661.467 [DataOverHttps](INFO): Moved sent file to Logs/20180123T163057/Express0005.lzma.bak
2018-01-23T16:41:01.467Z,1516725661.467 [DataOverHttps](INFO): SBD MOMSN=7755939
2018-01-23T16:41:01.933Z,1516725661.933 [Default:CheckIn:Read_Iridium] Stopped
2018-01-23T16:41:01.933Z,1516725661.933 [Default:CheckIn:C.Wait] Running Loop=1
2018-01-23T16:41:01.933Z,1516725661.933 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-01-23T16:41:07.586Z,1516725667.586 [CBIT](DEBUG): EFC running - data check-sum false
2018-01-23T16:41:27.864Z,1516725687.864 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:41:27.864Z,1516725687.864 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:41:27.864Z,1516725687.864 [BPC1](ERROR): Communications Fault
2018-01-23T16:41:27.961Z,1516725687.961 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:41:29.141Z,1516725689.141 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:41:29.141Z,1516725689.141 [BPC1] No Fault, FailCount= 1
2018-01-23T16:41:45.816Z,1516725705.816 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-01-23T16:41:45.816Z,1516725705.816 [NAL9602] Data Fault, FailCount= 1
2018-01-23T16:41:45.816Z,1516725705.816 [NAL9602](ERROR): Data Fault
2018-01-23T16:41:45.989Z,1516725705.989 [CBIT](ERROR): Data Fault in component: NAL9602
2018-01-23T16:41:46.214Z,1516725706.214 [NAL9602](INFO): Powering down
2018-01-23T16:41:47.147Z,1516725707.147 [CBIT](INFO): Clearing failed state for component NAL9602
2018-01-23T16:41:47.147Z,1516725707.147 [NAL9602] No Fault, FailCount= 1
2018-01-23T16:42:16.559Z,1516725736.559 [NAL9602](INFO): Powering up NAL9602
2018-01-23T16:42:27.758Z,1516725747.758 [NAL9602](INFO): NAL9602 initialized
2018-01-23T16:42:35.941Z,1516725755.941 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-01-23T16:42:35.941Z,1516725755.941 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-01-23T16:42:35.941Z,1516725755.941 [Rowe_600LCM](ERROR): Communications Fault
2018-01-23T16:42:36.228Z,1516725756.228 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-01-23T16:42:36.228Z,1516725756.228 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-01-23T16:42:36.345Z,1516725756.345 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-01-23T16:42:36.446Z,1516725756.446 [Rowe_600LCM](INFO): Powering down
2018-01-23T16:43:12.670Z,1516725792.670 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:43:12.670Z,1516725792.670 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-01-23T16:43:12.670Z,1516725792.670 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-01-23T16:43:12.672Z,1516725792.672 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:43:12.672Z,1516725792.672 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-01-23T16:43:12.672Z,1516725792.672 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-01-23T16:43:12.683Z,1516725792.683 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-01-23T16:43:12.684Z,1516725792.684 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-01-23T16:43:13.081Z,1516725793.081 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-01-23T16:43:13.081Z,1516725793.081 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-01-23T16:43:13.082Z,1516725793.082 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-01-23T16:43:13.082Z,1516725793.082 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-01-23T16:43:13.476Z,1516725793.476 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-23T16:43:13.477Z,1516725793.477 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:43:13.477Z,1516725793.477 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:43:13.477Z,1516725793.477 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:43:13.478Z,1516725793.478 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:43:13.478Z,1516725793.478 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:43:21.456Z,1516725801.456 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:43:21.456Z,1516725801.456 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:43:21.456Z,1516725801.456 [BPC1](ERROR): Communications Fault
2018-01-23T16:43:21.478Z,1516725801.478 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:43:22.706Z,1516725802.706 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:43:22.706Z,1516725802.706 [BPC1] No Fault, FailCount= 1
2018-01-23T16:44:48.326Z,1516725888.326 [BPC1](ERROR): Failed to parse bank A battery data
2018-01-23T16:44:48.326Z,1516725888.326 [BPC1] Data Fault, FailCount= 1
2018-01-23T16:44:48.326Z,1516725888.326 [BPC1](ERROR): Data Fault
2018-01-23T16:44:48.347Z,1516725888.347 [CBIT](ERROR): Data Fault in component: BPC1
2018-01-23T16:44:49.536Z,1516725889.536 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:44:49.536Z,1516725889.536 [BPC1] No Fault, FailCount= 1
2018-01-23T16:45:10.410Z,1516725910.410 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:45:10.410Z,1516725910.410 [BPC1] Communications Fault, FailCount= 2
2018-01-23T16:45:10.410Z,1516725910.410 [BPC1](ERROR): Communications Fault
2018-01-23T16:45:10.431Z,1516725910.431 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:45:11.639Z,1516725911.639 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:45:11.639Z,1516725911.639 [BPC1] No Fault, FailCount= 2
2018-01-23T16:45:43.630Z,1516725943.630 [CommandLine](IMPORTANT): got command ibit
2018-01-23T16:45:43.644Z,1516725943.644 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-01-23T16:45:43.644Z,1516725943.644 [IBIT](IMPORTANT): Beginning control surface checks.
2018-01-23T16:45:43.656Z,1516725943.656 [CBIT](IMPORTANT): Beginning ground fault scan
2018-01-23T16:45:54.440Z,1516725954.440 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121826
CHAN A1 (24V): 0.119017
CHAN A2 (12V): -0.002189
CHAN A3 (5V): -0.001130
CHAN B0 (3.3V): -0.000065
CHAN B1 (3.15aV): -0.000301
CHAN B2 (3.15bV): 0.000002
CHAN B3 (GND): -0.000085
OPEN: -0.000498
Full Scale Calc: 4.765 mA, -1.589 mA
2018-01-23T16:46:06.398Z,1516725966.398 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:46:06.398Z,1516725966.398 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:46:06.398Z,1516725966.398 [BPC1](ERROR): Communications Fault
2018-01-23T16:46:06.599Z,1516725966.599 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:46:07.483Z,1516725967.483 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:46:07.483Z,1516725967.483 [BPC1] No Fault, FailCount= 1
2018-01-23T16:46:13.860Z,1516725973.860 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:46:13.860Z,1516725973.860 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-01-23T16:46:13.860Z,1516725973.860 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-01-23T16:46:13.866Z,1516725973.866 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:46:13.866Z,1516725973.866 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2018-01-23T16:46:13.866Z,1516725973.866 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-01-23T16:46:13.906Z,1516725973.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-01-23T16:46:13.906Z,1516725973.906 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-01-23T16:46:14.269Z,1516725974.269 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-01-23T16:46:14.269Z,1516725974.269 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-01-23T16:46:14.270Z,1516725974.270 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-01-23T16:46:14.270Z,1516725974.270 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2018-01-23T16:46:14.674Z,1516725974.674 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-23T16:46:14.675Z,1516725974.675 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:46:14.675Z,1516725974.675 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:46:14.675Z,1516725974.675 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:46:14.676Z,1516725974.676 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:46:14.676Z,1516725974.676 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:47:06.653Z,1516726026.653 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:47:06.653Z,1516726026.653 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:47:06.653Z,1516726026.653 [BPC1](ERROR): Communications Fault
2018-01-23T16:47:06.761Z,1516726026.761 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:47:07.706Z,1516726027.706 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:47:07.706Z,1516726027.706 [BPC1] No Fault, FailCount= 1
2018-01-23T16:47:34.486Z,1516726054.486 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2018-01-23T16:47:34.895Z,1516726054.895 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 337.972015
Voltage: 15.556597
2018-01-23T16:47:34.895Z,1516726054.895 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2018-01-23T16:47:34.895Z,1516726054.895 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2018-01-23T16:47:35.303Z,1516726055.303 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-01-23T16:47:35.303Z,1516726055.303 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-01-23T16:47:35.304Z,1516726055.304 [IBIT](IMPORTANT): Pressure:9.472821 PSI
2018-01-23T16:47:35.304Z,1516726055.304 [IBIT](IMPORTANT): Humidity:15.010376 %
2018-01-23T16:47:35.750Z,1516726055.750 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-01-23T16:47:35.750Z,1516726055.750 [IBIT](IMPORTANT): buoyancyNeutral: 315.000000 cc
2018-01-23T16:47:35.750Z,1516726055.750 [IBIT](IMPORTANT): massDefault: -0.075000 cm
2018-01-23T16:47:35.751Z,1516726055.751 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2018-01-23T16:47:35.751Z,1516726055.751 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2018-01-23T16:47:35.751Z,1516726055.751 [IBIT](IMPORTANT): IBIT FAILED
2018-01-23T16:47:36.042Z,1516726056.042 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-01-23T16:47:36.042Z,1516726056.042 [Default:CheckIn:C.Wait] Stopped
2018-01-23T16:47:36.042Z,1516726056.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-01-23T16:47:36.042Z,1516726056.042 [Default:CheckIn:D] Running Loop=1
2018-01-23T16:47:36.443Z,1516726056.443 [Default:CheckIn:D] Stopped
2018-01-23T16:47:36.443Z,1516726056.443 [Default:CheckIn:E] Running Loop=1
2018-01-23T16:47:36.834Z,1516726056.834 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.174074 min 
2018-01-23T16:47:36.836Z,1516726056.836 [Default:CheckIn:E] Stopped
2018-01-23T16:47:36.836Z,1516726056.836 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-01-23T16:47:36.836Z,1516726056.836 [Default:CheckIn] Stopped
2018-01-23T16:47:36.836Z,1516726056.836 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-01-23T16:47:36.836Z,1516726056.836 [Default:CheckIn](INFO): Running loop #2
2018-01-23T16:47:36.836Z,1516726056.836 [Default:CheckIn] Running Loop=2
2018-01-23T16:47:36.836Z,1516726056.836 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-01-23T16:47:36.836Z,1516726056.836 [Default:CheckIn:Read_GPS] Running Loop=1
2018-01-23T16:47:59.599Z,1516726079.599 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:47:59.599Z,1516726079.599 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:47:59.599Z,1516726079.599 [BPC1](ERROR): Communications Fault
2018-01-23T16:47:59.701Z,1516726079.701 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:48:00.917Z,1516726080.917 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:48:00.917Z,1516726080.917 [BPC1] No Fault, FailCount= 1
2018-01-23T16:48:55.785Z,1516726135.785 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:48:55.785Z,1516726135.785 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:48:55.785Z,1516726135.785 [BPC1](ERROR): Communications Fault
2018-01-23T16:48:55.889Z,1516726135.889 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:48:57.097Z,1516726137.097 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:48:57.097Z,1516726137.097 [BPC1] No Fault, FailCount= 1
2018-01-23T16:49:14.740Z,1516726154.740 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:49:14.740Z,1516726154.740 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-01-23T16:49:14.740Z,1516726154.740 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-01-23T16:49:14.743Z,1516726154.743 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:49:14.743Z,1516726154.743 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2018-01-23T16:49:14.743Z,1516726154.743 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-01-23T16:49:14.833Z,1516726154.833 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-01-23T16:49:14.834Z,1516726154.834 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-01-23T16:49:15.237Z,1516726155.237 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-01-23T16:49:15.237Z,1516726155.237 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-01-23T16:49:15.237Z,1516726155.237 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-01-23T16:49:15.238Z,1516726155.238 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2018-01-23T16:49:15.541Z,1516726155.541 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-23T16:49:15.542Z,1516726155.542 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:49:15.542Z,1516726155.542 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:49:15.542Z,1516726155.542 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:49:15.543Z,1516726155.543 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:49:15.543Z,1516726155.543 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:49:51.120Z,1516726191.120 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:49:51.120Z,1516726191.120 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:49:51.120Z,1516726191.120 [BPC1](ERROR): Communications Fault
2018-01-23T16:49:51.221Z,1516726191.221 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:49:52.433Z,1516726192.433 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:49:52.433Z,1516726192.433 [BPC1] No Fault, FailCount= 1
2018-01-23T16:50:52.859Z,1516726252.859 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:50:52.859Z,1516726252.859 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:50:52.859Z,1516726252.859 [BPC1](ERROR): Communications Fault
2018-01-23T16:50:53.014Z,1516726253.014 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:50:54.170Z,1516726254.170 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:50:54.170Z,1516726254.170 [BPC1] No Fault, FailCount= 1
2018-01-23T16:51:07.918Z,1516726267.918 [CBIT](DEBUG): EFC running - data check-sum false
2018-01-23T16:51:21.820Z,1516726281.820 [PNI_TCM](ERROR): CRC does not match. Expected:0x23392 got:0x48679
2018-01-23T16:51:26.627Z,1516726286.627 [PNI_TCM](ERROR): CRC does not match. Expected:0x30114 got:0x6207
2018-01-23T16:51:29.818Z,1516726289.818 [PNI_TCM](ERROR): CRC does not match. Expected:0x16468 got:0x29720
2018-01-23T16:51:44.232Z,1516726304.232 [PNI_TCM](ERROR): CRC does not match. Expected:0x4088 got:0x6194
2018-01-23T16:51:45.810Z,1516726305.810 [PNI_TCM](ERROR): CRC does not match. Expected:0x25900 got:0x1347
2018-01-23T16:51:46.213Z,1516726306.213 [PNI_TCM](ERROR): CRC does not match. Expected:0x10339 got:0x43660
2018-01-23T16:51:52.212Z,1516726312.212 [PNI_TCM](ERROR): CRC does not match. Expected:0x20063 got:0x48927
2018-01-23T16:51:52.610Z,1516726312.610 [PNI_TCM](ERROR): CRC does not match. Expected:0x43740 got:0x16842
2018-01-23T16:51:53.818Z,1516726313.818 [PNI_TCM](ERROR): CRC does not match. Expected:0x65351 got:0x6464
2018-01-23T16:51:55.404Z,1516726315.404 [PNI_TCM](ERROR): CRC does not match. Expected:0x61927 got:0x1797
2018-01-23T16:51:55.817Z,1516726315.817 [PNI_TCM](ERROR): CRC does not match. Expected:0x38683 got:0x41756
2018-01-23T16:51:57.411Z,1516726317.411 [PNI_TCM](ERROR): CRC does not match. Expected:0x30485 got:0x16369
2018-01-23T16:52:00.603Z,1516726320.603 [PNI_TCM](ERROR): CRC does not match. Expected:0x14091 got:0x16949
2018-01-23T16:52:13.085Z,1516726333.085 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:52:13.085Z,1516726333.085 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:52:13.085Z,1516726333.085 [BPC1](ERROR): Communications Fault
2018-01-23T16:52:13.146Z,1516726333.146 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:52:14.307Z,1516726334.307 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:52:14.307Z,1516726334.307 [BPC1] No Fault, FailCount= 1
2018-01-23T16:52:15.895Z,1516726335.895 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:52:15.895Z,1516726335.895 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-01-23T16:52:15.895Z,1516726335.895 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-01-23T16:52:15.897Z,1516726335.897 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:52:15.897Z,1516726335.897 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2018-01-23T16:52:15.897Z,1516726335.897 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-01-23T16:52:15.921Z,1516726335.921 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-01-23T16:52:15.922Z,1516726335.922 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-01-23T16:52:16.343Z,1516726336.343 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-01-23T16:52:16.343Z,1516726336.343 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-01-23T16:52:16.343Z,1516726336.343 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-01-23T16:52:16.343Z,1516726336.343 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2018-01-23T16:52:16.689Z,1516726336.689 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-23T16:52:16.689Z,1516726336.689 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:52:16.689Z,1516726336.689 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:52:16.690Z,1516726336.690 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:52:16.690Z,1516726336.690 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:52:16.690Z,1516726336.690 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:52:30.638Z,1516726350.638 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-01-23T16:52:30.639Z,1516726350.639 [NAL9602] Data Fault, FailCount= 2
2018-01-23T16:52:30.639Z,1516726350.639 [NAL9602](ERROR): Data Fault
2018-01-23T16:52:30.748Z,1516726350.748 [CBIT](ERROR): Data Fault in component: NAL9602
2018-01-23T16:52:31.038Z,1516726351.038 [NAL9602](INFO): Powering down
2018-01-23T16:52:31.917Z,1516726351.917 [CBIT](INFO): Clearing failed state for component NAL9602
2018-01-23T16:52:31.917Z,1516726351.917 [NAL9602] No Fault, FailCount= 2
2018-01-23T16:52:36.302Z,1516726356.302 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-01-23T16:52:36.302Z,1516726356.302 [Rowe_600LCM] No Fault, FailCount= 5
2018-01-23T16:52:36.673Z,1516726356.673 [Rowe_600LCM](INFO): Initializing
2018-01-23T16:52:36.673Z,1516726356.673 [Rowe_600LCM](INFO): Checking LCM
2018-01-23T16:52:36.673Z,1516726356.673 [Rowe_600LCM](INFO): LCM OK
2018-01-23T16:52:36.673Z,1516726356.673 [Rowe_600LCM](INFO): Powering up
2018-01-23T16:52:37.085Z,1516726357.085 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-01-23T16:47:36.8Z
2018-01-23T16:52:37.085Z,1516726357.085 [Default:CheckIn:Read_GPS] Stopped
2018-01-23T16:52:37.085Z,1516726357.085 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-01-23T16:52:40.885Z,1516726360.885 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-01-23T16:52:41.020Z,1516726361.020 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2018-01-23T16:52:41.029Z,1516726361.029 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-01-23T16:52:41.030Z,1516726361.030 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-01-23T16:52:41.031Z,1516726361.031 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-01-23T16:52:42.169Z,1516726362.169 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180123T163057/Courier0008.lzma
2018-01-23T16:52:42.962Z,1516726362.962 [DataOverHttps](INFO): Moved sent file to Logs/20180123T163057/Courier0008.lzma.bak
2018-01-23T16:52:42.962Z,1516726362.962 [DataOverHttps](INFO): SBD MOMSN=7755965
2018-01-23T16:52:52.551Z,1516726372.551 [DataOverHttps](INFO): Sending 814 bytes from file Logs/20180123T163057/Express0009.lzma
2018-01-23T16:52:53.346Z,1516726373.346 [DataOverHttps](INFO): Moved sent file to Logs/20180123T163057/Express0009.lzma.bak
2018-01-23T16:52:53.346Z,1516726373.346 [DataOverHttps](INFO): SBD MOMSN=7755967
2018-01-23T16:52:53.813Z,1516726373.813 [Default:CheckIn:Read_Iridium] Stopped
2018-01-23T16:52:53.813Z,1516726373.813 [Default:CheckIn:C.Wait] Running Loop=1
2018-01-23T16:52:53.814Z,1516726373.814 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-01-23T16:53:01.098Z,1516726381.098 [NAL9602](INFO): Powering up NAL9602
2018-01-23T16:53:12.298Z,1516726392.298 [NAL9602](INFO): NAL9602 initialized
2018-01-23T16:53:22.734Z,1516726402.734 [BPC1](ERROR): Failed to parse message.
2018-01-23T16:53:22.734Z,1516726402.734 [BPC1](ERROR): Failed to parse bank B battery data
2018-01-23T16:53:22.734Z,1516726402.734 [BPC1] Data Fault, FailCount= 1
2018-01-23T16:53:22.734Z,1516726402.734 [BPC1](ERROR): Data Fault
2018-01-23T16:53:22.816Z,1516726402.816 [CBIT](ERROR): Data Fault in component: BPC1
2018-01-23T16:53:23.971Z,1516726403.971 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:53:23.971Z,1516726403.971 [BPC1] No Fault, FailCount= 1
2018-01-23T16:53:59.440Z,1516726439.440 [BPC1](ERROR): Failed to parse bank B battery data
2018-01-23T16:53:59.440Z,1516726439.440 [BPC1] Data Fault, FailCount= 1
2018-01-23T16:53:59.440Z,1516726439.440 [BPC1](ERROR): Data Fault
2018-01-23T16:53:59.513Z,1516726439.513 [CBIT](ERROR): Data Fault in component: BPC1
2018-01-23T16:54:00.632Z,1516726440.632 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:54:00.632Z,1516726440.632 [BPC1] No Fault, FailCount= 1
2018-01-23T16:54:33.414Z,1516726473.414 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:54:33.414Z,1516726473.414 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:54:33.414Z,1516726473.414 [BPC1](ERROR): Communications Fault
2018-01-23T16:54:33.505Z,1516726473.505 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:54:34.588Z,1516726474.588 [PNI_TCM](ERROR): CRC does not match. Expected:0x3522 got:0x33383
2018-01-23T16:54:34.672Z,1516726474.672 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:54:34.672Z,1516726474.672 [BPC1] No Fault, FailCount= 1
2018-01-23T16:54:57.961Z,1516726497.961 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:54:57.961Z,1516726497.961 [BPC1] Communications Fault, FailCount= 2
2018-01-23T16:54:57.961Z,1516726497.961 [BPC1](ERROR): Communications Fault
2018-01-23T16:54:58.039Z,1516726498.039 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:54:58.918Z,1516726498.918 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:54:58.918Z,1516726498.918 [BPC1] No Fault, FailCount= 2
2018-01-23T16:55:16.907Z,1516726516.907 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:55:16.907Z,1516726516.907 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-01-23T16:55:16.907Z,1516726516.907 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-01-23T16:55:16.913Z,1516726516.913 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:55:16.913Z,1516726516.913 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2018-01-23T16:55:16.913Z,1516726516.913 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-01-23T16:55:16.954Z,1516726516.954 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-01-23T16:55:16.954Z,1516726516.954 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-01-23T16:55:17.361Z,1516726517.361 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-01-23T16:55:17.361Z,1516726517.361 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-01-23T16:55:17.362Z,1516726517.362 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-01-23T16:55:17.362Z,1516726517.362 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2018-01-23T16:55:17.701Z,1516726517.701 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-23T16:55:17.701Z,1516726517.701 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:55:17.702Z,1516726517.702 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:55:17.702Z,1516726517.702 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:55:17.702Z,1516726517.702 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:55:17.703Z,1516726517.703 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:55:36.459Z,1516726536.459 [NAL9602](INFO): GPS fix at 20180123T165530: (36.802504, -121.787511)
2018-01-23T16:55:36.936Z,1516726536.936 [DeadReckonWithRespectToSeafloor](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-23T16:55:36.936Z,1516726536.936 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-01-23T16:55:36.936Z,1516726536.936 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-01-23T16:55:36.972Z,1516726536.972 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-01-23T16:55:37.375Z,1516726537.375 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-01-23T16:55:37.375Z,1516726537.375 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-01-23T16:55:37.702Z,1516726537.702 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-23T16:55:37.703Z,1516726537.703 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-23T16:55:37.703Z,1516726537.703 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-23T16:55:58.341Z,1516726558.341 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:55:58.341Z,1516726558.341 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:55:58.341Z,1516726558.341 [BPC1](ERROR): Communications Fault
2018-01-23T16:55:58.369Z,1516726558.369 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:55:59.246Z,1516726559.246 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:55:59.246Z,1516726559.246 [BPC1] No Fault, FailCount= 1
2018-01-23T16:56:08.782Z,1516726568.782 [NAL9602](INFO): Powering down
2018-01-23T16:56:58.749Z,1516726618.749 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:56:58.749Z,1516726618.749 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:56:58.749Z,1516726618.749 [BPC1](ERROR): Communications Fault
2018-01-23T16:56:58.842Z,1516726618.842 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:56:59.719Z,1516726619.719 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:56:59.719Z,1516726619.719 [BPC1] No Fault, FailCount= 1
2018-01-23T16:57:52.078Z,1516726672.078 [BPC1](ERROR): Failed to receive battery data
2018-01-23T16:57:52.078Z,1516726672.078 [BPC1] Communications Fault, FailCount= 1
2018-01-23T16:57:52.078Z,1516726672.078 [BPC1](ERROR): Communications Fault
2018-01-23T16:57:52.213Z,1516726672.213 [CBIT](ERROR): Communications Fault in component: BPC1
2018-01-23T16:57:53.309Z,1516726673.309 [CBIT](INFO): Clearing failed state for component BPC1
2018-01-23T16:57:53.309Z,1516726673.309 [BPC1] No Fault, FailCount= 1
2018-01-23T16:57:54.503Z,1516726674.503 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-01-23T16:57:54.503Z,1516726674.503 [Default:CheckIn:C.Wait] Stopped
2018-01-23T16:57:54.503Z,1516726674.503 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-01-23T16:57:54.503Z,1516726674.503 [Default:CheckIn:D] Running Loop=1
2018-01-23T16:57:54.897Z,1516726674.897 [Default:CheckIn:D] Stopped
2018-01-23T16:57:54.897Z,1516726674.897 [Default:CheckIn:E] Running Loop=1
2018-01-23T16:57:55.347Z,1516726675.347 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.481675 min 
2018-01-23T16:57:55.348Z,1516726675.348 [Default:CheckIn:E] Stopped
2018-01-23T16:57:55.348Z,1516726675.348 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-01-23T16:57:55.348Z,1516726675.348 [Default:CheckIn] Stopped
2018-01-23T16:57:55.348Z,1516726675.348 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-01-23T16:57:55.357Z,1516726675.357 [Default:CheckIn](INFO): Running loop #3
2018-01-23T16:57:55.357Z,1516726675.357 [Default:CheckIn] Running Loop=3
2018-01-23T16:57:55.357Z,1516726675.357 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-01-23T16:57:55.357Z,1516726675.357 [Default:CheckIn:Read_GPS] Running Loop=1
2018-01-23T16:57:58.658Z,1516726678.658 [NAL9602](INFO): Powering up
2018-01-23T16:58:01.749Z,1516726681.749 [CommandLine](IMPORTANT): got command restart system
2018-01-23T16:58:04.109Z,1516726684.109 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:04.110Z,1516726684.110 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:04.121Z,1516726684.121 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-01-23T16:58:04.121Z,1516726684.121 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:04.122Z,1516726684.122 [CommandLine](INFO): Join timeout helper Thread ID is 1022
2018-01-23T16:58:04.122Z,1516726684.122 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-01-23T16:58:04.123Z,1516726684.123 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:04.123Z,1516726684.123 [NavChartDb](INFO): Join timeout helper Thread ID is 1023
2018-01-23T16:58:04.413Z,1516726684.413 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:04.413Z,1516726684.413 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:04.429Z,1516726684.429 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-01-23T16:58:04.429Z,1516726684.429 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:04.429Z,1516726684.429 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1024
2018-01-23T16:58:04.529Z,1516726684.529 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:04.529Z,1516726684.529 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:04.549Z,1516726684.549 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-01-23T16:58:04.549Z,1516726684.549 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:04.549Z,1516726684.549 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1025
2018-01-23T16:58:04.765Z,1516726684.765 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:04.765Z,1516726684.765 [WetLabsBB2FL](INFO): Powering down
2018-01-23T16:58:04.766Z,1516726684.766 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:04.769Z,1516726684.769 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-01-23T16:58:04.769Z,1516726684.769 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:04.769Z,1516726684.769 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1026
2018-01-23T16:58:05.041Z,1516726685.041 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:05.274Z,1516726685.274 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-01-23T16:58:05.274Z,1516726685.274 [CTD_Seabird](INFO): Powering down
2018-01-23T16:58:05.275Z,1516726685.275 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:05.278Z,1516726685.278 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-01-23T16:58:05.278Z,1516726685.278 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:05.279Z,1516726685.279 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1028
2018-01-23T16:58:05.517Z,1516726685.517 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:05.517Z,1516726685.517 [CTD_NeilBrown](INFO): Powering down
2018-01-23T16:58:05.518Z,1516726685.518 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:05.526Z,1516726685.526 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-01-23T16:58:05.526Z,1516726685.526 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:05.526Z,1516726685.526 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1029
2018-01-23T16:58:05.633Z,1516726685.633 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:05.633Z,1516726685.633 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-01-23T16:58:05.731Z,1516726685.731 [Rowe_600LCM](INFO): Powering down
2018-01-23T16:58:05.732Z,1516726685.732 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:05.746Z,1516726685.746 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-01-23T16:58:05.746Z,1516726685.746 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:05.746Z,1516726685.746 [Radio_Surface](INFO): Join timeout helper Thread ID is 1031
2018-01-23T16:58:06.093Z,1516726686.093 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:06.093Z,1516726686.093 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.106Z,1516726686.106 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-01-23T16:58:06.106Z,1516726686.106 [logger ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.106Z,1516726686.106 [logger](INFO): Join timeout helper Thread ID is 1032
2018-01-23T16:58:06.109Z,1516726686.109 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:06.109Z,1516726686.109 [logger ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.126Z,1516726686.126 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-01-23T16:58:06.126Z,1516726686.126 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.126Z,1516726686.126 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-01-23T16:58:06.126Z,1516726686.126 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.127Z,1516726686.127 [controlThread](INFO): Join timeout helper Thread ID is 1033
2018-01-23T16:58:06.225Z,1516726686.225 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-23T16:58:06.225Z,1516726686.225 [controlThread](DEBUG): Uninitializing ControlThread
2018-01-23T16:58:06.226Z,1516726686.226 [NAL9602](INFO): Powering down
2018-01-23T16:58:06.227Z,1516726686.227 [PNI_TCM](INFO): Powering down
2018-01-23T16:58:06.314Z,1516726686.314 [Aanderaa_O2](INFO): Powering down
2018-01-23T16:58:06.316Z,1516726686.316 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-01-23T16:58:06.317Z,1516726686.317 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-01-23T16:58:06.318Z,1516726686.318 [MissionManager](INFO): Uninitializing Mission Default
2018-01-23T16:58:06.318Z,1516726686.318 [Default] Stopped
2018-01-23T16:58:06.318Z,1516726686.318 [Default](DEBUG): Aggregate::uninitialize Default
2018-01-23T16:58:06.318Z,1516726686.318 [Default:B.GoToSurface] Stopped
2018-01-23T16:58:06.318Z,1516726686.318 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-01-23T16:58:06.318Z,1516726686.318 [Default:CheckIn] Stopped
2018-01-23T16:58:06.318Z,1516726686.318 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-01-23T16:58:06.318Z,1516726686.318 [Default:CheckIn:Read_GPS] Stopped
2018-01-23T16:58:06.322Z,1516726686.322 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-01-23T16:58:06.322Z,1516726686.322 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-01-23T16:58:06.322Z,1516726686.322 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-01-23T16:58:06.323Z,1516726686.323 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-01-23T16:58:06.323Z,1516726686.323 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-01-23T16:58:06.323Z,1516726686.323 [BuoyancyServo](INFO): Powering down
2018-01-23T16:58:06.337Z,1516726686.337 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-01-23T16:58:06.337Z,1516726686.337 [ElevatorServo](INFO): Powering down
2018-01-23T16:58:06.338Z,1516726686.338 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-01-23T16:58:06.338Z,1516726686.338 [MassServo](INFO): Powering down
2018-01-23T16:58:06.339Z,1516726686.339 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-01-23T16:58:06.339Z,1516726686.339 [RudderServo](INFO): Powering down
2018-01-23T16:58:06.340Z,1516726686.340 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-01-23T16:58:06.340Z,1516726686.340 [ThrusterServo](INFO): Powering down
2018-01-23T16:58:06.341Z,1516726686.341 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-01-23T16:58:06.341Z,1516726686.341 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-01-23T16:58:06.341Z,1516726686.341 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-01-23T16:58:06.342Z,1516726686.342 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.443Z,1516726686.443 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.449Z,1516726686.449 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.484Z,1516726686.484 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.486Z,1516726686.486 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.489Z,1516726686.489 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.491Z,1516726686.491 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.518Z,1516726686.518 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-01-23T16:58:06.585Z,1516726686.585 [logger ThreadHandler](INFO): Thread cancelled.