2017-12-12T22:06:01.305Z,1513116361.305 [Supervisor](DEBUG): Initializing supervisor.
2017-12-12T22:06:01.317Z,1513116361.317 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2017-12-12T22:06:01.317Z,1513116361.317 [SyncHandler](INFO): Protected caller Thread ID is 803
2017-12-12T22:06:01.318Z,1513116361.318 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-12-12T22:06:01.329Z,1513116361.329 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2017-12-12T22:06:01.329Z,1513116361.329 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 804
2017-12-12T22:06:01.332Z,1513116361.332 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-12-12T22:06:01.354Z,1513116361.354 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-12-12T22:06:01.365Z,1513116361.365 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2017-12-12T22:06:01.365Z,1513116361.365 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 805
2017-12-12T22:06:01.366Z,1513116361.366 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-12-12T22:06:01.377Z,1513116361.377 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2017-12-12T22:06:01.377Z,1513116361.377 [logger ThreadHandler](INFO): Protected caller Thread ID is 806
2017-12-12T22:06:01.379Z,1513116361.379 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-12-12T22:06:01.379Z,1513116361.379 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-12-12T22:06:01.383Z,1513116361.383 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-12-12T22:06:01.561Z,1513116361.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-12-12T22:06:01.562Z,1513116361.562 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-12-12T22:06:01.732Z,1513116361.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-12-12T22:06:01.734Z,1513116361.734 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-12-12T22:06:02.059Z,1513116362.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-12-12T22:06:02.060Z,1513116362.060 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-12-12T22:06:02.516Z,1513116362.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-12-12T22:06:02.518Z,1513116362.518 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-12-12T22:06:02.839Z,1513116362.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-12-12T22:06:02.839Z,1513116362.839 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-12-12T22:06:03.269Z,1513116363.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-12-12T22:06:03.270Z,1513116363.270 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-12-12T22:06:03.591Z,1513116363.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-12-12T22:06:03.591Z,1513116363.591 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-12-12T22:06:04.283Z,1513116364.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-12-12T22:06:04.284Z,1513116364.284 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-12-12T22:06:04.365Z,1513116364.365 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-12-12T22:06:04.644Z,1513116364.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-12-12T22:06:04.646Z,1513116364.646 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-12-12T22:06:04.785Z,1513116364.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-12-12T22:06:04.787Z,1513116364.787 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-12-12T22:06:04.980Z,1513116364.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-12-12T22:06:04.982Z,1513116364.982 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-12-12T22:06:05.173Z,1513116365.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-12-12T22:06:05.174Z,1513116365.174 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-12-12T22:06:05.270Z,1513116365.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-12-12T22:06:05.270Z,1513116365.270 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-12-12T22:06:05.405Z,1513116365.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-12-12T22:06:05.407Z,1513116365.407 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2017-12-12T22:06:05.411Z,1513116365.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2017-12-12T22:06:05.636Z,1513116365.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-12-12T22:06:05.638Z,1513116365.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2017-12-12T22:06:05.715Z,1513116365.715 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2017-12-12T22:06:05.850Z,1513116365.850 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2017-12-12T22:06:05.942Z,1513116365.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2017-12-12T22:06:06.026Z,1513116366.026 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2017-12-12T22:06:06.156Z,1513116366.156 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2017-12-12T22:06:06.312Z,1513116366.312 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2017-12-12T22:06:06.407Z,1513116366.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2017-12-12T22:06:06.498Z,1513116366.498 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2017-12-12T22:06:06.592Z,1513116366.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2017-12-12T22:06:06.674Z,1513116366.674 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-12-12T22:06:06.679Z,1513116366.679 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-12-12T22:06:06.807Z,1513116366.807 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-12-12T22:06:06.809Z,1513116366.809 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-12-12T22:06:06.833Z,1513116366.833 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-12-12T22:06:06.835Z,1513116366.835 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-12-12T22:06:06.891Z,1513116366.891 [DepthRateCalculator] Loaded
2017-12-12T22:06:06.892Z,1513116366.892 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-12-12T22:06:06.898Z,1513116366.898 [PitchRateCalculator] Loaded
2017-12-12T22:06:06.898Z,1513116366.898 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-12-12T22:06:06.914Z,1513116366.914 [SpeedCalculator] Loaded
2017-12-12T22:06:06.914Z,1513116366.914 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-12-12T22:06:06.935Z,1513116366.935 [TempGradientCalculator] Loaded
2017-12-12T22:06:06.935Z,1513116366.935 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-12-12T22:06:06.951Z,1513116366.951 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-12-12T22:06:06.951Z,1513116366.951 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-12-12T22:06:06.956Z,1513116366.956 [YawRateCalculator] Loaded
2017-12-12T22:06:06.957Z,1513116366.957 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-12-12T22:06:06.957Z,1513116366.957 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-12-12T22:06:06.958Z,1513116366.958 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-12-12T22:06:06.995Z,1513116366.995 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-12-12T22:06:06.996Z,1513116366.996 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-12-12T22:06:07.447Z,1513116367.447 [AcousticModem_Benthos_ATM900] Loaded
2017-12-12T22:06:07.447Z,1513116367.447 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2017-12-12T22:06:07.590Z,1513116367.590 [DataOverHttps] Loaded
2017-12-12T22:06:07.590Z,1513116367.590 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-12-12T22:06:07.604Z,1513116367.604 [Depth_Keller] Loaded
2017-12-12T22:06:07.604Z,1513116367.604 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-12-12T22:06:07.609Z,1513116367.609 [DropWeight] Loaded
2017-12-12T22:06:07.609Z,1513116367.609 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-12-12T22:06:07.706Z,1513116367.706 [NAL9602] Loaded
2017-12-12T22:06:07.706Z,1513116367.706 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-12-12T22:06:07.722Z,1513116367.722 [Onboard] Loaded
2017-12-12T22:06:07.722Z,1513116367.722 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-12-12T22:06:07.732Z,1513116367.732 [Radio_Surface] Loaded
2017-12-12T22:06:07.733Z,1513116367.733 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-12-12T22:06:07.734Z,1513116367.734 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A44E0
2017-12-12T22:06:07.734Z,1513116367.734 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 885
2017-12-12T22:06:07.887Z,1513116367.887 [PNI_TCM] Loaded
2017-12-12T22:06:07.888Z,1513116367.888 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-12-12T22:06:07.948Z,1513116367.948 [Rowe_600LCM] Loaded
2017-12-12T22:06:07.948Z,1513116367.948 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-12-12T22:06:07.949Z,1513116367.949 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D44E0
2017-12-12T22:06:07.949Z,1513116367.949 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 886
2017-12-12T22:06:09.687Z,1513116369.687 [BPC1] Loaded
2017-12-12T22:06:09.688Z,1513116369.688 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-12-12T22:06:09.688Z,1513116369.688 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-12-12T22:06:09.689Z,1513116369.689 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-12-12T22:06:10.147Z,1513116370.147 [SBIT](DEBUG): Construct Startup Built In Test.
2017-12-12T22:06:10.177Z,1513116370.177 [SBIT] Loaded
2017-12-12T22:06:10.177Z,1513116370.177 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-12-12T22:06:10.178Z,1513116370.178 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-12-12T22:06:10.189Z,1513116370.189 [IBIT] Loaded
2017-12-12T22:06:10.190Z,1513116370.190 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-12-12T22:06:10.193Z,1513116370.193 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-12-12T22:06:10.442Z,1513116370.442 [CBIT] Loaded
2017-12-12T22:06:10.442Z,1513116370.442 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-12-12T22:06:10.443Z,1513116370.443 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-12-12T22:06:10.443Z,1513116370.443 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-12-12T22:06:10.629Z,1513116370.629 [Aanderaa_O2] Loaded
2017-12-12T22:06:10.629Z,1513116370.629 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-12-12T22:06:10.706Z,1513116370.706 [CTD_NeilBrown] Loaded
2017-12-12T22:06:10.707Z,1513116370.707 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-12-12T22:06:10.708Z,1513116370.708 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4089A4E0
2017-12-12T22:06:10.708Z,1513116370.708 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 887
2017-12-12T22:06:10.740Z,1513116370.740 [CTD_Seabird] Loaded
2017-12-12T22:06:10.740Z,1513116370.740 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-12-12T22:06:10.741Z,1513116370.741 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408CA4E0
2017-12-12T22:06:10.742Z,1513116370.742 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 888
2017-12-12T22:06:10.758Z,1513116370.758 [ESPComponent] Loaded
2017-12-12T22:06:10.759Z,1513116370.759 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2017-12-12T22:06:10.773Z,1513116370.773 [PAR_Licor] Loaded
2017-12-12T22:06:10.773Z,1513116370.773 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-12-12T22:06:10.821Z,1513116370.821 [WetLabsBB2FL] Loaded
2017-12-12T22:06:10.821Z,1513116370.821 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-12-12T22:06:10.822Z,1513116370.822 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408FA4E0
2017-12-12T22:06:10.823Z,1513116370.823 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 889
2017-12-12T22:06:10.867Z,1513116370.867 [WetLabsSeaOWL_UV_A] Loaded
2017-12-12T22:06:10.867Z,1513116370.867 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2017-12-12T22:06:10.868Z,1513116370.868 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4092A4E0
2017-12-12T22:06:10.868Z,1513116370.868 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 890
2017-12-12T22:06:10.869Z,1513116370.869 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-12-12T22:06:10.870Z,1513116370.870 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-12-12T22:06:11.148Z,1513116371.148 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-12-12T22:06:11.148Z,1513116371.148 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-12-12T22:06:11.236Z,1513116371.236 [VerticalControl](DEBUG): Construct VerticalControl.
2017-12-12T22:06:11.335Z,1513116371.335 [VerticalControl] Loaded
2017-12-12T22:06:11.335Z,1513116371.335 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-12-12T22:06:11.336Z,1513116371.336 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-12-12T22:06:11.396Z,1513116371.396 [HorizontalControl] Loaded
2017-12-12T22:06:11.397Z,1513116371.397 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-12-12T22:06:11.397Z,1513116371.397 [SpeedControl](DEBUG): Construct SpeedControl.
2017-12-12T22:06:11.403Z,1513116371.403 [SpeedControl] Loaded
2017-12-12T22:06:11.403Z,1513116371.403 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-12-12T22:06:11.403Z,1513116371.403 [LoopControl](DEBUG): Construct LoopControl.
2017-12-12T22:06:11.404Z,1513116371.404 [LoopControl] Loaded
2017-12-12T22:06:11.404Z,1513116371.404 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-12-12T22:06:11.405Z,1513116371.405 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-12-12T22:06:11.405Z,1513116371.405 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-12-12T22:06:11.525Z,1513116371.525 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-12-12T22:06:11.525Z,1513116371.525 [StratificationFrontDetector](DEBUG): (re)initializing
2017-12-12T22:06:11.525Z,1513116371.525 [StratificationFrontDetector] Loaded
2017-12-12T22:06:11.525Z,1513116371.525 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-12-12T22:06:11.526Z,1513116371.526 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-12-12T22:06:11.527Z,1513116371.527 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-12-12T22:06:11.645Z,1513116371.645 [BuoyancyServo] Loaded
2017-12-12T22:06:11.645Z,1513116371.645 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-12-12T22:06:11.656Z,1513116371.656 [ElevatorServo] Loaded
2017-12-12T22:06:11.657Z,1513116371.657 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-12-12T22:06:11.667Z,1513116371.667 [MassServo] Loaded
2017-12-12T22:06:11.668Z,1513116371.668 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-12-12T22:06:11.679Z,1513116371.679 [RudderServo] Loaded
2017-12-12T22:06:11.679Z,1513116371.679 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-12-12T22:06:11.690Z,1513116371.690 [ThrusterServo] Loaded
2017-12-12T22:06:11.690Z,1513116371.690 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-12-12T22:06:11.691Z,1513116371.691 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-12-12T22:06:11.691Z,1513116371.691 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-12-12T22:06:11.804Z,1513116371.804 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-12-12T22:06:11.804Z,1513116371.804 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-12-12T22:06:11.859Z,1513116371.859 [DeadReckonUsingSpeedCalculator] Loaded
2017-12-12T22:06:11.860Z,1513116371.860 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-12-12T22:06:11.876Z,1513116371.876 [NavChart] Loaded
2017-12-12T22:06:11.876Z,1513116371.876 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-12-12T22:06:11.880Z,1513116371.880 [UniversalFixResidualReporter] Loaded
2017-12-12T22:06:11.880Z,1513116371.880 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-12-12T22:06:11.881Z,1513116371.881 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-12-12T22:06:11.884Z,1513116371.884 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-12-12T22:06:11.885Z,1513116371.885 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-12-12T22:06:11.892Z,1513116371.892 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-12-12T22:06:11.893Z,1513116371.893 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7C4E0
2017-12-12T22:06:11.893Z,1513116371.893 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 891
2017-12-12T22:06:11.898Z,1513116371.898 [Supervisor](INFO): Main Thread ID is 795
2017-12-12T22:06:11.898Z,1513116371.898 [Supervisor](DEBUG): Running supervisor.
2017-12-12T22:06:11.898Z,1513116371.898 [CommandLine ThreadHandler](INFO): Handler Thread ID is 892
2017-12-12T22:06:11.901Z,1513116371.901 [controlThread ThreadHandler](INFO): Handler Thread ID is 893
2017-12-12T22:06:11.901Z,1513116371.901 [controlThread](DEBUG): Initializing ControlThread
2017-12-12T22:06:11.902Z,1513116371.902 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-12-12T22:06:11.903Z,1513116371.903 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-12-12T22:06:11.903Z,1513116371.903 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-12-12T22:06:11.903Z,1513116371.903 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-12-12T22:06:11.904Z,1513116371.904 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-12-12T22:06:11.904Z,1513116371.904 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-12-12T22:06:11.911Z,1513116371.911 [SBIT](INFO): Initialize SBIT Component.
2017-12-12T22:06:11.912Z,1513116371.912 [SBIT](IMPORTANT): git: 2017-12-11
2017-12-12T22:06:11.912Z,1513116371.912 [SBIT](INFO): git hash: 8651b6fcf04aad5c066f1d23bee794430771ed42
2017-12-12T22:06:11.912Z,1513116371.912 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-12-12T22:06:11.913Z,1513116371.913 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017
2017-12-12T22:06:11.914Z,1513116371.914 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2017-12-12T22:06:11.915Z,1513116371.915 [IBIT](INFO): Initialize IBIT Component.
2017-12-12T22:06:11.916Z,1513116371.916 [CBIT](DEBUG): Initialize CBIT Component.
2017-12-12T22:06:11.916Z,1513116371.916 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-12-12T22:06:11.916Z,1513116371.916 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2017-12-12T22:06:11.917Z,1513116371.917 [logger ThreadHandler](INFO): Handler Thread ID is 894
2017-12-12T22:06:11.937Z,1513116371.937 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 895
2017-12-12T22:06:11.943Z,1513116371.943 [Radio_Surface](INFO): Powering up
2017-12-12T22:06:11.949Z,1513116371.949 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 896
2017-12-12T22:06:11.950Z,1513116371.950 [Rowe_600LCM](INFO): Initializing
2017-12-12T22:06:11.950Z,1513116371.950 [Rowe_600LCM](INFO): Checking LCM
2017-12-12T22:06:12.045Z,1513116372.045 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-12-12T22:06:12.047Z,1513116372.047 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-12-12T22:06:12.047Z,1513116372.047 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-12-12T22:06:12.048Z,1513116372.048 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-12-12T22:06:12.049Z,1513116372.049 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 897
2017-12-12T22:06:12.050Z,1513116372.050 [CTD_NeilBrown](INFO): Powering down
2017-12-12T22:06:12.075Z,1513116372.075 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-12-12T22:06:12.076Z,1513116372.076 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-12-12T22:06:12.076Z,1513116372.076 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-12-12T22:06:12.077Z,1513116372.077 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-12-12T22:06:12.077Z,1513116372.077 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-12-12T22:06:12.078Z,1513116372.078 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-12-12T22:06:12.078Z,1513116372.078 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-12-12T22:06:12.079Z,1513116372.079 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-12-12T22:06:12.080Z,1513116372.080 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-12-12T22:06:12.091Z,1513116372.091 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 898
2017-12-12T22:06:12.092Z,1513116372.092 [CTD_Seabird](INFO): Initializing
2017-12-12T22:06:12.242Z,1513116372.242 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 900
2017-12-12T22:06:12.243Z,1513116372.243 [WetLabsBB2FL](INFO): Powering down
2017-12-12T22:06:12.269Z,1513116372.269 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 901
2017-12-12T22:06:12.277Z,1513116372.277 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-12T22:06:12.290Z,1513116372.290 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 902
2017-12-12T22:06:12.297Z,1513116372.297 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-12-12T22:06:12.298Z,1513116372.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-12-12T22:06:12.298Z,1513116372.298 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-12-12T22:06:12.298Z,1513116372.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-12-12T22:06:12.298Z,1513116372.298 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-12-12T22:06:12.298Z,1513116372.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-12-12T22:06:12.299Z,1513116372.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-12-12T22:06:12.299Z,1513116372.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-12-12T22:06:12.299Z,1513116372.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-12-12T22:06:12.299Z,1513116372.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-12-12T22:06:12.299Z,1513116372.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-12-12T22:06:12.299Z,1513116372.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-12-12T22:06:12.300Z,1513116372.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-12-12T22:06:12.300Z,1513116372.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-12-12T22:06:12.300Z,1513116372.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-12-12T22:06:12.300Z,1513116372.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-12-12T22:06:12.372Z,1513116372.372 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-12T22:06:12.372Z,1513116372.372 [CTD_Seabird](INFO): Powering down
2017-12-12T22:06:12.417Z,1513116372.417 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2017-12-12T22:06:12.418Z,1513116372.418 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-12-12T22:06:12.512Z,1513116372.512 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-12-12T22:06:12.513Z,1513116372.513 [Default:A.Wait](DEBUG): Construct Wait.
2017-12-12T22:06:12.515Z,1513116372.515 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-12T22:06:12.526Z,1513116372.526 [Rowe_600LCM](INFO): LCM OK
2017-12-12T22:06:12.526Z,1513116372.526 [Rowe_600LCM](INFO): Powering up
2017-12-12T22:06:12.557Z,1513116372.557 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-12-12T22:06:12.559Z,1513116372.559 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-12-12T22:06:12.581Z,1513116372.581 [Default:E.Execute](DEBUG): Construct Execute.
2017-12-12T22:06:12.584Z,1513116372.584 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Guidance="Tethys/Guidance"
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2017-12-12T22:06:12.598Z,1513116372.598 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-12-12T22:06:12.621Z,1513116372.621 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-12-12T22:06:12.621Z,1513116372.621 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-12-12T22:06:12.647Z,1513116372.647 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-12-12T22:06:13.027Z,1513116373.027 [ESPComponent](INFO): powering down ESP
2017-12-12T22:06:13.573Z,1513116373.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:13.677Z,1513116373.677 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:13.785Z,1513116373.785 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-12T22:06:13.793Z,1513116373.793 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-12T22:06:13.807Z,1513116373.807 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-12-12T22:06:13.813Z,1513116373.813 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-12-12T22:06:13.827Z,1513116373.827 [MassServo](DEBUG): Initializing EZServoServo.
2017-12-12T22:06:13.833Z,1513116373.833 [MassServo](DEBUG): Initializing MassServo.
2017-12-12T22:06:13.855Z,1513116373.855 [RudderServo](DEBUG): Initializing EZServoServo.
2017-12-12T22:06:13.861Z,1513116373.861 [RudderServo](DEBUG): Initializing RudderServo.
2017-12-12T22:06:13.874Z,1513116373.874 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-12-12T22:06:13.882Z,1513116373.882 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-12-12T22:06:13.917Z,1513116373.917 [CommandLine](FAULT): Scheduling is paused
2017-12-12T22:06:13.917Z,1513116373.917 [CBIT](INFO): Critical error at 20171212T220611
2017-12-12T22:06:13.918Z,1513116373.918 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-12-12T22:06:14.133Z,1513116374.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:14.134Z,1513116374.134 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:14.414Z,1513116374.414 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:14.415Z,1513116374.415 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:14.737Z,1513116374.737 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-12-12T22:06:14.737Z,1513116374.737 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-12-12T22:06:14.737Z,1513116374.737 [BuoyancyServo] Communications Fault, FailCount= 1
2017-12-12T22:06:14.737Z,1513116374.737 [BuoyancyServo](ERROR): Communications Fault
2017-12-12T22:06:14.944Z,1513116374.944 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-12-12T22:06:15.001Z,1513116375.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:15.002Z,1513116375.002 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:15.014Z,1513116375.014 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-12-12T22:06:15.015Z,1513116375.015 [BuoyancyServo](INFO): Powering down
2017-12-12T22:06:15.428Z,1513116375.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:15.429Z,1513116375.429 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:15.804Z,1513116375.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:15.821Z,1513116375.821 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:16.205Z,1513116376.205 [Aanderaa_O2](INFO): Powering down
2017-12-12T22:06:16.358Z,1513116376.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:16.360Z,1513116376.360 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:16.597Z,1513116376.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:16.598Z,1513116376.598 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-12T22:06:16.634Z,1513116376.634 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-12-12T22:06:16.760Z,1513116376.760 [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 &
2017-12-12T22:06:16.844Z,1513116376.844 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-12-12T22:06:16.847Z,1513116376.847 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-12-12T22:06:16.847Z,1513116376.847 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-12-12T22:06:17.122Z,1513116377.122 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-12-12T22:06:17.122Z,1513116377.122 [BuoyancyServo] No Fault, FailCount= 1
2017-12-12T22:06:17.449Z,1513116377.449 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-12T22:06:17.449Z,1513116377.449 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-12T22:06:18.409Z,1513116378.409 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-12-12T22:06:18.409Z,1513116378.409 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-12-12T22:06:18.409Z,1513116378.409 [BuoyancyServo] Communications Fault, FailCount= 2
2017-12-12T22:06:18.409Z,1513116378.409 [BuoyancyServo](ERROR): Communications Fault
2017-12-12T22:06:18.514Z,1513116378.514 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-12-12T22:06:18.613Z,1513116378.613 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-12-12T22:06:18.614Z,1513116378.614 [BuoyancyServo](INFO): Powering down
2017-12-12T22:06:20.128Z,1513116380.128 [AcousticModem_Benthos_ATM900](DEBUG): 
2017-12-12T22:06:20.689Z,1513116380.689 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-12-12T22:06:20.689Z,1513116380.689 [BuoyancyServo] No Fault, FailCount= 2
2017-12-12T22:06:21.006Z,1513116381.006 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-12T22:06:21.007Z,1513116381.007 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-12T22:06:21.925Z,1513116381.925 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-12-12T22:06:21.926Z,1513116381.926 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-12-12T22:06:21.926Z,1513116381.926 [BuoyancyServo] Communications Fault, FailCount= 3
2017-12-12T22:06:21.926Z,1513116381.926 [BuoyancyServo](ERROR): Communications Fault
2017-12-12T22:06:22.045Z,1513116382.045 [CBIT](CRITICAL): Environmental Failure. Press:8.763228 PSI. Humidity:2%. Temp:22 C. ABORTING MISSION
2017-12-12T22:06:22.046Z,1513116382.046 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-12-12T22:06:22.087Z,1513116382.087 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2017-12-12T22:06:22.088Z,1513116382.088 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2017-12-12T22:06:22.098Z,1513116382.098 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2017-12-12T22:06:22.100Z,1513116382.100 [AcousticModem_Benthos_ATM900](DEBUG): Feb  6 2000 04:27:53 
2017-12-12T22:06:22.109Z,1513116382.109 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2017-12-12T22:06:22.318Z,1513116382.318 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-12-12T22:06:22.319Z,1513116382.319 [BuoyancyServo](INFO): Powering down
2017-12-12T22:06:22.447Z,1513116382.447 [CBIT](INFO): Critical error at 20171212T220622
2017-12-12T22:06:22.856Z,1513116382.856 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2017-12-12T22:06:23.263Z,1513116383.263 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2017-12-12T22:06:23.264Z,1513116383.264 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2017-12-12T22:06:23.265Z,1513116383.265 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2017-12-12T22:06:23.747Z,1513116383.747 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2017-12-12T22:06:24.169Z,1513116384.169 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2017-12-12T22:06:24.171Z,1513116384.171 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2017-12-12T22:06:24.465Z,1513116384.465 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-12-12T22:06:24.465Z,1513116384.465 [BuoyancyServo] No Fault, FailCount= 3
2017-12-12T22:06:24.553Z,1513116384.553 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2017-12-12T22:06:24.637Z,1513116384.637 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-12T22:06:24.637Z,1513116384.637 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-12T22:06:38.046Z,1513116398.046 [NAL9602](INFO): Powering up NAL9602
2017-12-12T22:06:49.095Z,1513116409.095 [NAL9602](INFO): NAL9602 initialized
2017-12-12T22:07:10.115Z,1513116430.115 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:07:10.116Z,1513116430.116 [BPC1] Communications Fault, FailCount= 1
2017-12-12T22:07:10.116Z,1513116430.116 [BPC1](ERROR): Communications Fault
2017-12-12T22:07:10.323Z,1513116430.323 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:07:11.357Z,1513116431.357 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:07:11.357Z,1513116431.357 [BPC1] No Fault, FailCount= 1
2017-12-12T22:07:15.470Z,1513116435.470 [SBIT](IMPORTANT): Beginning Startup BIT
2017-12-12T22:07:15.474Z,1513116435.474 [CBIT](IMPORTANT): Beginning ground fault scan
2017-12-12T22:07:26.444Z,1513116446.444 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.066028
CHAN A1 (24V): 0.187945
CHAN A2 (12V): 0.035214
CHAN A3 (5V): 0.000011
CHAN B0 (3.3V): -0.001788
CHAN B1 (3.15aV): -0.000466
CHAN B2 (3.15bV): 0.000220
CHAN B3 (GND): -0.011253
OPEN: 0.011689
Full Scale Calc: 4.765 mA, -1.589 mA
2017-12-12T22:07:28.840Z,1513116448.840 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.773485
2017-12-12T22:08:02.548Z,1513116482.548 [NAL9602](INFO): SBD MO Status=2, MOMSN=19277, MT Status=2, MTMSN=0
2017-12-12T22:08:02.548Z,1513116482.548 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-12T22:08:04.578Z,1513116484.578 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:08:04.578Z,1513116484.578 [BPC1] Communications Fault, FailCount= 1
2017-12-12T22:08:04.578Z,1513116484.578 [BPC1](ERROR): Communications Fault
2017-12-12T22:08:04.678Z,1513116484.678 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:08:06.004Z,1513116486.004 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:08:06.004Z,1513116486.004 [BPC1] No Fault, FailCount= 1
2017-12-12T22:08:09.066Z,1513116489.066 [SBIT](CRITICAL): SBIT FAILED
2017-12-12T22:08:09.067Z,1513116489.067 [CBIT](INFO): Critical error at 20171212T220809
2017-12-12T22:08:09.389Z,1513116489.389 [MissionManager](IMPORTANT): Started mission Startup
2017-12-12T22:08:09.390Z,1513116489.390 [Startup] Running Loop=1
2017-12-12T22:08:09.390Z,1513116489.390 [Startup](DEBUG): Aggregate::initialize Startup
2017-12-12T22:08:09.390Z,1513116489.390 [Startup:A.GoToSurface] Running Loop=1
2017-12-12T22:08:09.390Z,1513116489.390 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-12T22:08:09.391Z,1513116489.391 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-12-12T22:08:09.391Z,1513116489.391 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-12-12T22:08:09.391Z,1513116489.391 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-12-12T22:08:09.392Z,1513116489.392 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-12T22:08:09.392Z,1513116489.392 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-12T22:08:09.398Z,1513116489.398 [Startup:StartupSatComms] Running Loop=1
2017-12-12T22:08:09.398Z,1513116489.398 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-12-12T22:08:09.398Z,1513116489.398 [Startup:StartupSatComms:A] Running Loop=1
2017-12-12T22:08:09.883Z,1513116489.883 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-12-12T22:08:13.153Z,1513116493.153 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-12-12T22:08:13.153Z,1513116493.153 [Rowe_600LCM] Communications Fault, FailCount= 1
2017-12-12T22:08:13.153Z,1513116493.153 [Rowe_600LCM](ERROR): Communications Fault
2017-12-12T22:08:13.558Z,1513116493.558 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-12-12T22:08:13.608Z,1513116493.608 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-12-12T22:08:14.351Z,1513116494.351 [Rowe_600LCM](INFO): Powering down
2017-12-12T22:08:15.931Z,1513116495.931 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-12-12T22:08:15.931Z,1513116495.931 [Rowe_600LCM] No Fault, FailCount= 1
2017-12-12T22:08:15.969Z,1513116495.969 [Rowe_600LCM](INFO): Initializing
2017-12-12T22:08:15.970Z,1513116495.970 [Rowe_600LCM](INFO): Checking LCM
2017-12-12T22:08:15.970Z,1513116495.970 [Rowe_600LCM](INFO): LCM OK
2017-12-12T22:08:15.970Z,1513116495.970 [Rowe_600LCM](INFO): Powering up
2017-12-12T22:08:20.093Z,1513116500.093 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-12-12T22:08:20.164Z,1513116500.164 [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 &
2017-12-12T22:08:20.182Z,1513116500.182 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-12-12T22:08:20.186Z,1513116500.186 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-12-12T22:08:20.187Z,1513116500.187 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-12-12T22:08:58.651Z,1513116538.651 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:08:58.651Z,1513116538.651 [BPC1] Communications Fault, FailCount= 1
2017-12-12T22:08:58.651Z,1513116538.651 [BPC1](ERROR): Communications Fault
2017-12-12T22:08:58.830Z,1513116538.830 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:08:59.889Z,1513116539.889 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:08:59.889Z,1513116539.889 [BPC1] No Fault, FailCount= 1
2017-12-12T22:09:09.487Z,1513116549.487 [Startup:StartupSatComms:A](INFO): Timed out from 2017-12-12T22:08:09.4Z
2017-12-12T22:09:09.487Z,1513116549.487 [Startup:StartupSatComms:A] Stopped
2017-12-12T22:09:09.487Z,1513116549.487 [Startup:StartupSatComms:B] Running Loop=1
2017-12-12T22:09:09.844Z,1513116549.844 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-12-12T22:09:10.585Z,1513116550.585 [NAL9602](INFO): SBD MO Status=2, MOMSN=19277, MT Status=2, MTMSN=0
2017-12-12T22:09:10.586Z,1513116550.586 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-12T22:09:12.207Z,1513116552.207 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-12T22:09:12.208Z,1513116552.208 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-12-12T22:09:12.208Z,1513116552.208 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-12-12T22:09:12.212Z,1513116552.212 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-12T22:09:12.212Z,1513116552.212 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-12-12T22:09:12.212Z,1513116552.212 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-12-12T22:09:12.359Z,1513116552.359 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-12-12T22:09:12.360Z,1513116552.360 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-12-12T22:09:12.838Z,1513116552.838 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-12-12T22:09:12.838Z,1513116552.838 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-12-12T22:09:12.840Z,1513116552.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-12-12T22:09:12.840Z,1513116552.840 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-12-12T22:09:13.079Z,1513116553.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-12-12T22:09:13.080Z,1513116553.080 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-12-12T22:09:13.085Z,1513116553.085 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-12-12T22:09:13.086Z,1513116553.086 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-12-12T22:09:13.087Z,1513116553.087 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-12-12T22:09:13.088Z,1513116553.088 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-12-12T22:09:13.402Z,1513116553.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-12-12T22:09:13.808Z,1513116553.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-12-12T22:09:14.223Z,1513116554.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-12-12T22:09:14.702Z,1513116554.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2017-12-12T22:09:15.111Z,1513116555.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2017-12-12T22:09:15.430Z,1513116555.430 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-12-12T22:09:15.807Z,1513116555.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-12-12T22:09:16.203Z,1513116556.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-12-12T22:09:16.741Z,1513116556.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2017-12-12T22:09:17.090Z,1513116557.090 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2017-12-12T22:09:17.431Z,1513116557.431 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-12-12T22:09:17.852Z,1513116557.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-12-12T22:09:18.209Z,1513116558.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-12-12T22:09:18.722Z,1513116558.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-12-12T22:09:19.066Z,1513116559.066 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-12-12T22:09:19.477Z,1513116559.477 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2017-12-12T22:09:19.799Z,1513116559.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-12-12T22:09:20.201Z,1513116560.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-12-12T22:09:20.607Z,1513116560.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-12-12T22:09:21.108Z,1513116561.108 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2017-12-12T22:09:21.472Z,1513116561.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2017-12-12T22:09:21.847Z,1513116561.847 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-12-12T22:09:22.224Z,1513116562.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-12-12T22:09:22.607Z,1513116562.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-12-12T22:09:23.080Z,1513116563.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2017-12-12T22:09:23.476Z,1513116563.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2017-12-12T22:09:23.883Z,1513116563.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2017-12-12T22:09:24.203Z,1513116564.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-12-12T22:09:24.702Z,1513116564.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2017-12-12T22:09:25.098Z,1513116565.098 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s.
2017-12-12T22:09:25.422Z,1513116565.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-12-12T22:09:25.801Z,1513116565.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-12-12T22:09:26.284Z,1513116566.284 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2017-12-12T22:09:26.688Z,1513116566.688 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2017-12-12T22:09:27.095Z,1513116567.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2017-12-12T22:09:27.396Z,1513116567.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-12-12T22:09:27.800Z,1513116567.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-12-12T22:09:28.197Z,1513116568.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-12-12T22:09:28.691Z,1513116568.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2017-12-12T22:09:29.087Z,1513116569.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2017-12-12T22:09:29.415Z,1513116569.415 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-12-12T22:09:29.812Z,1513116569.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-12-12T22:09:30.208Z,1513116570.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-12-12T22:09:30.694Z,1513116570.694 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2017-12-12T22:09:31.092Z,1513116571.092 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2017-12-12T22:09:31.426Z,1513116571.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-12-12T22:09:31.809Z,1513116571.809 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-12-12T22:09:47.897Z,1513116587.897 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003549
2017-12-12T22:09:49.846Z,1513116589.846 [NAL9602](INFO): SBD MO Status=2, MOMSN=19277, MT Status=2, MTMSN=0
2017-12-12T22:09:49.846Z,1513116589.846 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-12T22:09:55.413Z,1513116595.413 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171212T215117/Courier0008.lzma
2017-12-12T22:09:56.219Z,1513116596.219 [DataOverHttps](INFO): Moved sent file to Logs/20171212T215117/Courier0008.lzma.bak
2017-12-12T22:09:56.219Z,1513116596.219 [DataOverHttps](INFO): SBD MOMSN=5432042
2017-12-12T22:10:02.398Z,1513116602.398 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:10:02.398Z,1513116602.398 [BPC1] Communications Fault, FailCount= 1
2017-12-12T22:10:02.398Z,1513116602.398 [BPC1](ERROR): Communications Fault
2017-12-12T22:10:02.442Z,1513116602.442 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:10:03.461Z,1513116603.461 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:10:03.461Z,1513116603.461 [BPC1] No Fault, FailCount= 1
2017-12-12T22:10:11.039Z,1513116611.039 [Startup:StartupSatComms:B](INFO): Timed out from 2017-12-12T22:09:09.5Z
2017-12-12T22:10:11.039Z,1513116611.039 [Startup:StartupSatComms:B] Stopped
2017-12-12T22:10:11.039Z,1513116611.039 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-12-12T22:10:11.040Z,1513116611.040 [Startup:StartupSatComms] Stopped
2017-12-12T22:10:11.040Z,1513116611.040 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-12-12T22:10:11.058Z,1513116611.058 [Startup](INFO): Completed Startup
2017-12-12T22:10:11.059Z,1513116611.059 [MissionManager](INFO): Startup is completed.
2017-12-12T22:10:11.059Z,1513116611.059 [MissionManager](INFO): Uninitializing Mission Startup
2017-12-12T22:10:11.060Z,1513116611.060 [Startup] Stopped
2017-12-12T22:10:11.060Z,1513116611.060 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-12-12T22:10:11.060Z,1513116611.060 [Startup:A.GoToSurface] Stopped
2017-12-12T22:10:11.061Z,1513116611.061 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-12-12T22:10:11.235Z,1513116611.235 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171212T220250/Courier0000.lzma
2017-12-12T22:10:11.382Z,1513116611.382 [MissionManager](IMPORTANT): Started mission Default
2017-12-12T22:10:11.382Z,1513116611.382 [Default] Running Loop=1
2017-12-12T22:10:11.382Z,1513116611.382 [Default](DEBUG): Aggregate::initialize Default
2017-12-12T22:10:11.382Z,1513116611.382 [Default:B.GoToSurface] Running Loop=1
2017-12-12T22:10:11.383Z,1513116611.383 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-12T22:10:11.383Z,1513116611.383 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-12-12T22:10:11.383Z,1513116611.383 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-12-12T22:10:11.383Z,1513116611.383 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-12-12T22:10:11.384Z,1513116611.384 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-12T22:10:11.384Z,1513116611.384 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-12T22:10:11.384Z,1513116611.384 [Default:A.Wait] Running Loop=1
2017-12-12T22:10:11.384Z,1513116611.384 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-12-12T22:10:12.007Z,1513116612.007 [DataOverHttps](INFO): Moved sent file to Logs/20171212T220250/Courier0000.lzma.bak
2017-12-12T22:10:12.008Z,1513116612.008 [DataOverHttps](INFO): SBD MOMSN=5432044
2017-12-12T22:10:16.573Z,1513116616.573 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-12-12T22:10:16.573Z,1513116616.573 [Rowe_600LCM] Communications Fault, FailCount= 2
2017-12-12T22:10:16.573Z,1513116616.573 [Rowe_600LCM](ERROR): Communications Fault
2017-12-12T22:10:16.981Z,1513116616.981 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-12-12T22:10:17.858Z,1513116617.858 [Rowe_600LCM](INFO): Powering down
2017-12-12T22:10:22.161Z,1513116622.161 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-12-12T22:10:22.161Z,1513116622.161 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-12-12T22:10:22.162Z,1513116622.162 [Rowe_600LCM] No Fault, FailCount= 2
2017-12-12T22:10:22.301Z,1513116622.301 [Rowe_600LCM](INFO): Initializing
2017-12-12T22:10:22.302Z,1513116622.302 [Rowe_600LCM](INFO): Checking LCM
2017-12-12T22:10:22.302Z,1513116622.302 [Rowe_600LCM](INFO): LCM OK
2017-12-12T22:10:22.302Z,1513116622.302 [Rowe_600LCM](INFO): Powering up
2017-12-12T22:10:24.615Z,1513116624.615 [Default:A.Wait](INFO): Done Waiting.
2017-12-12T22:10:24.615Z,1513116624.615 [Default:A.Wait] Stopped
2017-12-12T22:10:24.615Z,1513116624.615 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-12-12T22:10:25.015Z,1513116625.015 [Default:CheckIn] Running Loop=1
2017-12-12T22:10:25.015Z,1513116625.015 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-12T22:10:25.016Z,1513116625.016 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-12T22:10:25.418Z,1513116625.418 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-12-12T22:10:26.512Z,1513116626.512 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-12-12T22:10:26.735Z,1513116626.735 [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 &
2017-12-12T22:10:26.736Z,1513116626.736 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-12-12T22:10:26.737Z,1513116626.737 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-12-12T22:10:26.738Z,1513116626.738 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-12-12T22:10:55.071Z,1513116655.071 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:10:55.071Z,1513116655.071 [BPC1] Communications Fault, FailCount= 1
2017-12-12T22:10:55.071Z,1513116655.071 [BPC1](ERROR): Communications Fault
2017-12-12T22:10:55.094Z,1513116655.094 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:10:56.388Z,1513116656.388 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:10:56.388Z,1513116656.388 [BPC1] No Fault, FailCount= 1
2017-12-12T22:11:01.030Z,1513116661.030 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:01.416Z,1513116661.416 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:01.817Z,1513116661.817 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:02.262Z,1513116662.262 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:02.661Z,1513116662.661 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:03.027Z,1513116663.027 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:03.431Z,1513116663.431 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:03.839Z,1513116663.839 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:04.243Z,1513116664.243 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:04.644Z,1513116664.644 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:05.057Z,1513116665.057 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:05.437Z,1513116665.437 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:05.850Z,1513116665.850 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:06.270Z,1513116666.270 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:06.616Z,1513116666.616 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:07.017Z,1513116667.017 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:07.463Z,1513116667.463 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:07.849Z,1513116667.849 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:08.263Z,1513116668.263 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:08.618Z,1513116668.618 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:09.029Z,1513116669.029 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:09.483Z,1513116669.483 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:09.880Z,1513116669.880 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:10.244Z,1513116670.244 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:10.626Z,1513116670.626 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:11.037Z,1513116671.037 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:11.428Z,1513116671.428 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:11.467Z,1513116671.467 [PNI_TCM](ERROR): CRC does not match. Expected:0x49712 got:0x7304
2017-12-12T22:11:11.852Z,1513116671.852 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:12.262Z,1513116672.262 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:12.626Z,1513116672.626 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:13.025Z,1513116673.025 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:23.116Z,1513116683.116 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:23.272Z,1513116683.272 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:11:44.859Z,1513116704.859 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:11:44.859Z,1513116704.859 [BPC1] Communications Fault, FailCount= 1
2017-12-12T22:11:44.859Z,1513116704.859 [BPC1](ERROR): Communications Fault
2017-12-12T22:11:44.930Z,1513116704.930 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:11:46.240Z,1513116706.240 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:11:46.240Z,1513116706.240 [BPC1] No Fault, FailCount= 1
2017-12-12T22:11:51.619Z,1513116711.619 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-12-12T22:11:56.831Z,1513116716.831 [NAL9602](INFO): GPS fix at 20171212T221155: (36.802771, -121.788035)
2017-12-12T22:11:56.894Z,1513116716.894 [Default:CheckIn:Read_GPS] Stopped
2017-12-12T22:11:56.894Z,1513116716.894 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-12T22:11:57.272Z,1513116717.272 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-12-12T22:12:06.577Z,1513116726.577 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20171212T220601/Courier0000.lzma
2017-12-12T22:12:07.350Z,1513116727.350 [DataOverHttps](INFO): Moved sent file to Logs/20171212T220601/Courier0000.lzma.bak
2017-12-12T22:12:07.350Z,1513116727.350 [DataOverHttps](INFO): SBD MOMSN=5432051
2017-12-12T22:12:07.395Z,1513116727.395 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:12:07.395Z,1513116727.395 [BPC1] Communications Fault, FailCount= 2
2017-12-12T22:12:07.395Z,1513116727.395 [BPC1](ERROR): Communications Fault
2017-12-12T22:12:07.433Z,1513116727.433 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:12:14.558Z,1513116734.558 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:12:14.558Z,1513116734.558 [BPC1] No Fault, FailCount= 2
2017-12-12T22:12:16.323Z,1513116736.323 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:12:17.118Z,1513116737.118 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:12:21.748Z,1513116741.748 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20171212T220601/Courier0004.lzma
2017-12-12T22:12:22.496Z,1513116742.496 [DataOverHttps](INFO): Moved sent file to Logs/20171212T220601/Courier0004.lzma.bak
2017-12-12T22:12:22.497Z,1513116742.497 [DataOverHttps](INFO): SBD MOMSN=5432057
2017-12-12T22:12:23.081Z,1513116743.081 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-12-12T22:12:23.081Z,1513116743.081 [Rowe_600LCM] Communications Fault, FailCount= 3
2017-12-12T22:12:23.081Z,1513116743.081 [Rowe_600LCM](ERROR): Communications Fault
2017-12-12T22:12:23.485Z,1513116743.485 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-12-12T22:12:23.639Z,1513116743.639 [Rowe_600LCM](INFO): Powering down
2017-12-12T22:12:24.151Z,1513116744.151 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-12-12T22:12:27.558Z,1513116747.558 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-12-12T22:12:27.558Z,1513116747.558 [Rowe_600LCM] No Fault, FailCount= 3
2017-12-12T22:12:27.685Z,1513116747.685 [Rowe_600LCM](INFO): Initializing
2017-12-12T22:12:27.686Z,1513116747.686 [Rowe_600LCM](INFO): Checking LCM
2017-12-12T22:12:27.686Z,1513116747.686 [Rowe_600LCM](INFO): LCM OK
2017-12-12T22:12:27.686Z,1513116747.686 [Rowe_600LCM](INFO): Powering up
2017-12-12T22:12:31.897Z,1513116751.897 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-12-12T22:12:32.008Z,1513116752.008 [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 &
2017-12-12T22:12:32.009Z,1513116752.009 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-12-12T22:12:32.010Z,1513116752.010 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-12-12T22:12:32.011Z,1513116752.011 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-12-12T22:12:32.861Z,1513116752.861 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171212T215117/Express0009.lzma
2017-12-12T22:12:33.638Z,1513116753.638 [DataOverHttps](INFO): Moved sent file to Logs/20171212T215117/Express0009.lzma.bak
2017-12-12T22:12:33.638Z,1513116753.638 [DataOverHttps](INFO): SBD MOMSN=5432060
2017-12-12T22:12:39.611Z,1513116759.611 [NAL9602](INFO): Powering down
2017-12-12T22:12:45.878Z,1513116765.878 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171212T220250/Express0001.lzma
2017-12-12T22:12:46.680Z,1513116766.680 [DataOverHttps](INFO): Moved sent file to Logs/20171212T220250/Express0001.lzma.bak
2017-12-12T22:12:46.681Z,1513116766.681 [DataOverHttps](INFO): SBD MOMSN=5432062
2017-12-12T22:12:59.963Z,1513116779.963 [DataOverHttps](INFO): Sending 754 bytes from file Logs/20171212T220601/Express0001.lzma
2017-12-12T22:13:00.761Z,1513116780.761 [DataOverHttps](INFO): Moved sent file to Logs/20171212T220601/Express0001.lzma.bak
2017-12-12T22:13:00.762Z,1513116780.762 [DataOverHttps](INFO): SBD MOMSN=5432064
2017-12-12T22:13:13.962Z,1513116793.962 [DataOverHttps](INFO): Sending 359 bytes from file Logs/20171212T220601/Express0005.lzma
2017-12-12T22:13:14.748Z,1513116794.748 [DataOverHttps](INFO): Moved sent file to Logs/20171212T220601/Express0005.lzma.bak
2017-12-12T22:13:14.749Z,1513116794.749 [DataOverHttps](INFO): SBD MOMSN=5432080
2017-12-12T22:13:16.562Z,1513116796.562 [Default:CheckIn:Read_Iridium] Stopped
2017-12-12T22:13:16.562Z,1513116796.562 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-12T22:13:16.562Z,1513116796.562 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-12T22:13:48.990Z,1513116828.990 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:13:48.990Z,1513116828.990 [BPC1] Communications Fault, FailCount= 1
2017-12-12T22:13:48.990Z,1513116828.990 [BPC1](ERROR): Communications Fault
2017-12-12T22:13:49.135Z,1513116829.135 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:13:50.148Z,1513116830.148 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:13:50.148Z,1513116830.148 [BPC1] No Fault, FailCount= 1
2017-12-12T22:13:59.292Z,1513116839.292 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:14:00.085Z,1513116840.085 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:14:23.463Z,1513116863.463 [PNI_TCM](ERROR): CRC does not match. Expected:0x674 got:0x52620
2017-12-12T22:14:28.461Z,1513116868.461 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-12-12T22:14:28.461Z,1513116868.461 [Rowe_600LCM] Communications Fault, FailCount= 4
2017-12-12T22:14:28.461Z,1513116868.461 [Rowe_600LCM](ERROR): Communications Fault
2017-12-12T22:14:28.679Z,1513116868.679 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-12-12T22:14:28.865Z,1513116868.865 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-12-12T22:14:29.479Z,1513116869.479 [Rowe_600LCM](INFO): Powering down
2017-12-12T22:14:31.085Z,1513116871.085 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-12-12T22:14:31.085Z,1513116871.085 [Rowe_600LCM] No Fault, FailCount= 4
2017-12-12T22:14:31.093Z,1513116871.093 [Rowe_600LCM](INFO): Initializing
2017-12-12T22:14:31.093Z,1513116871.093 [Rowe_600LCM](INFO): Checking LCM
2017-12-12T22:14:31.094Z,1513116871.094 [Rowe_600LCM](INFO): LCM OK
2017-12-12T22:14:31.094Z,1513116871.094 [Rowe_600LCM](INFO): Powering up
2017-12-12T22:14:35.227Z,1513116875.227 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-12-12T22:14:35.300Z,1513116875.300 [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 &
2017-12-12T22:14:35.303Z,1513116875.303 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-12-12T22:14:35.304Z,1513116875.304 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-12-12T22:14:35.305Z,1513116875.305 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-12-12T22:14:44.185Z,1513116884.185 [AcousticModem_Benthos_ATM900](INFO): received an acoustic signal
2017-12-12T22:14:44.186Z,1513116884.186 [AcousticModem_Benthos_ATM900](INFO): received a range request message
2017-12-12T22:14:44.292Z,1513116884.292 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:14:44.292Z,1513116884.292 [BPC1] Communications Fault, FailCount= 1
2017-12-12T22:14:44.292Z,1513116884.292 [BPC1](ERROR): Communications Fault
2017-12-12T22:14:44.456Z,1513116884.456 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:14:45.477Z,1513116885.477 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:14:45.477Z,1513116885.477 [BPC1] No Fault, FailCount= 1
2017-12-12T22:14:57.211Z,1513116897.211 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:14:58.005Z,1513116898.005 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:05.175Z,1513116905.175 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:05.969Z,1513116905.969 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:06.213Z,1513116906.213 [BPC1](ERROR): Failed to receive battery data
2017-12-12T22:15:06.213Z,1513116906.213 [BPC1] Communications Fault, FailCount= 2
2017-12-12T22:15:06.214Z,1513116906.214 [BPC1](ERROR): Communications Fault
2017-12-12T22:15:06.290Z,1513116906.290 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-12T22:15:07.665Z,1513116907.665 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-12T22:15:07.666Z,1513116907.666 [BPC1] No Fault, FailCount= 2
2017-12-12T22:15:08.362Z,1513116908.362 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:09.162Z,1513116909.162 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:09.958Z,1513116909.958 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:10.755Z,1513116910.755 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:11.552Z,1513116911.552 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:12.346Z,1513116912.346 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:13.144Z,1513116913.144 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:13.938Z,1513116913.938 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:14.734Z,1513116914.734 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:15.531Z,1513116915.531 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:16.325Z,1513116916.325 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:17.123Z,1513116917.123 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:17.917Z,1513116917.917 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:18.715Z,1513116918.715 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:19.511Z,1513116919.511 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:20.306Z,1513116920.306 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:21.101Z,1513116921.101 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:21.899Z,1513116921.899 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:22.695Z,1513116922.695 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:23.491Z,1513116923.491 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:24.285Z,1513116924.285 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:25.082Z,1513116925.082 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:25.877Z,1513116925.877 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:26.677Z,1513116926.677 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:27.131Z,1513116927.131 [DataOverHttps](IMPORTANT): SBD MTMSN=20171212T221526
2017-12-12T22:15:27.474Z,1513116927.474 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:28.269Z,1513116928.269 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:29.066Z,1513116929.066 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:29.862Z,1513116929.862 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:30.657Z,1513116930.657 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:31.459Z,1513116931.459 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:32.257Z,1513116932.257 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:32.443Z,1513116932.443 [DataOverHttps](INFO): Received command:restart sys
2017-12-12T22:15:32.507Z,1513116932.507 [CommandLine](IMPORTANT): got command restart system
2017-12-12T22:15:33.054Z,1513116933.054 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:33.852Z,1513116933.852 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:34.645Z,1513116934.645 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2017-12-12T22:15:36.388Z,1513116936.388 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:36.389Z,1513116936.389 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:36.545Z,1513116936.545 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-12-12T22:15:36.545Z,1513116936.545 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:36.546Z,1513116936.546 [CommandLine](INFO): Join timeout helper Thread ID is 961
2017-12-12T22:15:36.546Z,1513116936.546 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-12-12T22:15:36.546Z,1513116936.546 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:36.547Z,1513116936.547 [NavChartDb](INFO): Join timeout helper Thread ID is 962
2017-12-12T22:15:36.865Z,1513116936.865 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:36.866Z,1513116936.866 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:36.877Z,1513116936.877 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2017-12-12T22:15:36.878Z,1513116936.878 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:36.879Z,1513116936.879 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 963
2017-12-12T22:15:36.943Z,1513116936.943 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:36.943Z,1513116936.943 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:36.945Z,1513116936.945 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-12-12T22:15:36.945Z,1513116936.945 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:36.946Z,1513116936.946 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 964
2017-12-12T22:15:37.321Z,1513116937.321 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:37.322Z,1513116937.322 [WetLabsBB2FL](INFO): Powering down
2017-12-12T22:15:37.323Z,1513116937.323 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:37.335Z,1513116937.335 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2017-12-12T22:15:37.335Z,1513116937.335 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:37.336Z,1513116937.336 [CTD_Seabird](INFO): Join timeout helper Thread ID is 965
2017-12-12T22:15:37.738Z,1513116937.738 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:37.960Z,1513116937.960 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-12T22:15:37.960Z,1513116937.960 [CTD_Seabird](INFO): Powering down
2017-12-12T22:15:37.961Z,1513116937.961 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:37.974Z,1513116937.974 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-12-12T22:15:37.974Z,1513116937.974 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:37.975Z,1513116937.975 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 967
2017-12-12T22:15:38.261Z,1513116938.261 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:38.261Z,1513116938.261 [CTD_NeilBrown](INFO): Powering down
2017-12-12T22:15:38.262Z,1513116938.262 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:38.270Z,1513116938.270 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-12-12T22:15:38.270Z,1513116938.270 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:38.270Z,1513116938.270 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 968
2017-12-12T22:15:38.625Z,1513116938.625 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:38.625Z,1513116938.625 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-12-12T22:15:38.899Z,1513116938.899 [Rowe_600LCM](INFO): Powering down
2017-12-12T22:15:38.900Z,1513116938.900 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:38.921Z,1513116938.921 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-12-12T22:15:38.921Z,1513116938.921 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:38.922Z,1513116938.922 [Radio_Surface](INFO): Join timeout helper Thread ID is 970
2017-12-12T22:15:39.026Z,1513116939.026 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:39.026Z,1513116939.026 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.042Z,1513116939.042 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-12-12T22:15:39.042Z,1513116939.042 [logger ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.043Z,1513116939.043 [logger](INFO): Join timeout helper Thread ID is 971
2017-12-12T22:15:39.050Z,1513116939.050 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:39.050Z,1513116939.050 [logger ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.069Z,1513116939.069 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-12-12T22:15:39.070Z,1513116939.070 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.070Z,1513116939.070 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-12-12T22:15:39.070Z,1513116939.070 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.070Z,1513116939.070 [controlThread](INFO): Join timeout helper Thread ID is 972
2017-12-12T22:15:39.309Z,1513116939.309 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-12T22:15:39.309Z,1513116939.309 [controlThread](DEBUG): Uninitializing ControlThread
2017-12-12T22:15:39.310Z,1513116939.310 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-12-12T22:15:39.430Z,1513116939.430 [NAL9602](INFO): Powering down
2017-12-12T22:15:39.431Z,1513116939.431 [PNI_TCM](INFO): Powering down
2017-12-12T22:15:39.518Z,1513116939.518 [Aanderaa_O2](INFO): Powering down
2017-12-12T22:15:39.520Z,1513116939.520 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-12-12T22:15:39.522Z,1513116939.522 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-12-12T22:15:39.522Z,1513116939.522 [MissionManager](INFO): Uninitializing Mission Default
2017-12-12T22:15:39.522Z,1513116939.522 [Default] Stopped
2017-12-12T22:15:39.523Z,1513116939.523 [Default](DEBUG): Aggregate::uninitialize Default
2017-12-12T22:15:39.523Z,1513116939.523 [Default:B.GoToSurface] Stopped
2017-12-12T22:15:39.523Z,1513116939.523 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-12-12T22:15:39.523Z,1513116939.523 [Default:CheckIn] Stopped
2017-12-12T22:15:39.523Z,1513116939.523 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-12T22:15:39.523Z,1513116939.523 [Default:CheckIn:C.Wait] Stopped
2017-12-12T22:15:39.523Z,1513116939.523 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-12T22:15:39.526Z,1513116939.526 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-12-12T22:15:39.527Z,1513116939.527 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-12-12T22:15:39.527Z,1513116939.527 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-12-12T22:15:39.527Z,1513116939.527 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-12-12T22:15:39.528Z,1513116939.528 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-12-12T22:15:39.528Z,1513116939.528 [BuoyancyServo](INFO): Powering down
2017-12-12T22:15:39.541Z,1513116939.541 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-12-12T22:15:39.541Z,1513116939.541 [ElevatorServo](INFO): Powering down
2017-12-12T22:15:39.542Z,1513116939.542 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-12-12T22:15:39.542Z,1513116939.542 [MassServo](INFO): Powering down
2017-12-12T22:15:39.543Z,1513116939.543 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-12-12T22:15:39.543Z,1513116939.543 [RudderServo](INFO): Powering down
2017-12-12T22:15:39.544Z,1513116939.544 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-12-12T22:15:39.544Z,1513116939.544 [ThrusterServo](INFO): Powering down
2017-12-12T22:15:39.545Z,1513116939.545 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-12-12T22:15:39.545Z,1513116939.545 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-12-12T22:15:39.545Z,1513116939.545 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-12-12T22:15:39.546Z,1513116939.546 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.626Z,1513116939.626 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.632Z,1513116939.632 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.667Z,1513116939.667 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.670Z,1513116939.670 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.674Z,1513116939.674 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.676Z,1513116939.676 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.722Z,1513116939.722 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-12-12T22:15:39.786Z,1513116939.786 [logger ThreadHandler](INFO): Thread cancelled.