2018-12-12T23:30:40.845Z,1544657440.845 [Supervisor](DEBUG): Initializing supervisor.
2018-12-12T23:30:40.848Z,1544657440.848 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-12-12T23:30:40.848Z,1544657440.848 [SyncHandler](INFO): Protected caller Thread ID is 4586
2018-12-12T23:30:40.849Z,1544657440.849 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-12-12T23:30:40.850Z,1544657440.850 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-12-12T23:30:40.850Z,1544657440.850 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4587
2018-12-12T23:30:40.853Z,1544657440.853 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-12-12T23:30:40.864Z,1544657440.864 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-12-12T23:30:40.865Z,1544657440.865 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-12-12T23:30:40.865Z,1544657440.865 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4588
2018-12-12T23:30:40.866Z,1544657440.866 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-12-12T23:30:40.867Z,1544657440.867 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-12-12T23:30:40.867Z,1544657440.867 [logger ThreadHandler](INFO): Protected caller Thread ID is 4589
2018-12-12T23:30:40.869Z,1544657440.869 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-12-12T23:30:40.870Z,1544657440.870 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-12-12T23:30:40.871Z,1544657440.871 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-12-12T23:30:40.969Z,1544657440.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-12-12T23:30:40.969Z,1544657440.969 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-12-12T23:30:41.067Z,1544657441.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-12-12T23:30:41.067Z,1544657441.067 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-12-12T23:30:41.253Z,1544657441.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-12-12T23:30:41.254Z,1544657441.254 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-12-12T23:30:41.601Z,1544657441.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-12-12T23:30:41.602Z,1544657441.602 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-12-12T23:30:41.913Z,1544657441.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-12-12T23:30:41.913Z,1544657441.913 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-12-12T23:30:42.346Z,1544657442.346 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-12-12T23:30:42.347Z,1544657442.347 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-12-12T23:30:42.804Z,1544657442.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-12-12T23:30:42.804Z,1544657442.804 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-12-12T23:30:43.239Z,1544657443.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-12-12T23:30:43.239Z,1544657443.239 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-12-12T23:30:43.319Z,1544657443.319 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-12-12T23:30:43.644Z,1544657443.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-12-12T23:30:43.644Z,1544657443.644 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-12-12T23:30:43.999Z,1544657443.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-12-12T23:30:43.000Z,1544657444.000 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-12-12T23:30:44.210Z,1544657444.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-12-12T23:30:44.211Z,1544657444.211 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-12-12T23:30:44.408Z,1544657444.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-12-12T23:30:44.409Z,1544657444.409 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-12-12T23:30:44.504Z,1544657444.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-12-12T23:30:44.505Z,1544657444.505 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-12-12T23:30:44.645Z,1544657444.645 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-12-12T23:30:44.647Z,1544657444.647 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-12-12T23:30:44.648Z,1544657444.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-12-12T23:30:44.874Z,1544657444.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-12-12T23:30:44.875Z,1544657444.875 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-12-12T23:30:44.976Z,1544657444.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-12-12T23:30:45.122Z,1544657445.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-12-12T23:30:45.213Z,1544657445.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-12-12T23:30:45.297Z,1544657445.297 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-12-12T23:30:45.446Z,1544657445.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-12-12T23:30:45.631Z,1544657445.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-12-12T23:30:45.726Z,1544657445.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-12-12T23:30:45.820Z,1544657445.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-12-12T23:30:45.915Z,1544657445.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-12-12T23:30:45.997Z,1544657445.997 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-12-12T23:30:45.997Z,1544657445.997 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-12-12T23:30:46.001Z,1544657446.001 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-12-12T23:30:46.096Z,1544657446.096 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-12-12T23:30:46.097Z,1544657446.097 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-12-12T23:30:46.110Z,1544657446.110 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-12-12T23:30:46.111Z,1544657446.111 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-12-12T23:30:46.151Z,1544657446.151 [DepthRateCalculator] Loaded
2018-12-12T23:30:46.152Z,1544657446.152 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-12-12T23:30:46.158Z,1544657446.158 [PitchRateCalculator] Loaded
2018-12-12T23:30:46.158Z,1544657446.158 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-12-12T23:30:46.173Z,1544657446.173 [SpeedCalculator] Loaded
2018-12-12T23:30:46.173Z,1544657446.173 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-12-12T23:30:46.193Z,1544657446.193 [TempGradientCalculator] Loaded
2018-12-12T23:30:46.194Z,1544657446.194 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-12-12T23:30:46.199Z,1544657446.199 [YawRateCalculator] Loaded
2018-12-12T23:30:46.200Z,1544657446.200 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-12-12T23:30:46.238Z,1544657446.238 [ElevatorOffsetCalculator] Loaded
2018-12-12T23:30:46.238Z,1544657446.238 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-12-12T23:30:46.239Z,1544657446.239 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-12-12T23:30:46.239Z,1544657446.239 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-12-12T23:30:46.265Z,1544657446.265 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-12-12T23:30:46.265Z,1544657446.265 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-12-12T23:30:46.545Z,1544657446.545 [AcousticModem_Benthos_ATM900] Loaded
2018-12-12T23:30:46.546Z,1544657446.546 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-12-12T23:30:46.614Z,1544657446.614 [DataOverHttps] Loaded
2018-12-12T23:30:46.614Z,1544657446.614 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-12-12T23:30:46.628Z,1544657446.628 [Depth_Keller] Loaded
2018-12-12T23:30:46.628Z,1544657446.628 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-12-12T23:30:46.633Z,1544657446.633 [DropWeight] Loaded
2018-12-12T23:30:46.633Z,1544657446.633 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-12-12T23:30:46.727Z,1544657446.727 [NAL9602] Loaded
2018-12-12T23:30:46.728Z,1544657446.728 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-12-12T23:30:46.743Z,1544657446.743 [Onboard] Loaded
2018-12-12T23:30:46.743Z,1544657446.743 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-12-12T23:30:46.753Z,1544657446.753 [Radio_Surface] Loaded
2018-12-12T23:30:46.753Z,1544657446.753 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-12-12T23:30:46.754Z,1544657446.754 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407CE4E0
2018-12-12T23:30:46.755Z,1544657446.755 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4668
2018-12-12T23:30:46.800Z,1544657446.800 [PNI_TCM] Loaded
2018-12-12T23:30:46.801Z,1544657446.801 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-12-12T23:30:46.858Z,1544657446.858 [Rowe_600LCM] Loaded
2018-12-12T23:30:46.859Z,1544657446.859 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-12-12T23:30:46.860Z,1544657446.860 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407FE4E0
2018-12-12T23:30:46.860Z,1544657446.860 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 4669
2018-12-12T23:30:48.577Z,1544657448.577 [BPC1] Loaded
2018-12-12T23:30:48.577Z,1544657448.577 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-12-12T23:30:48.578Z,1544657448.578 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-12-12T23:30:48.578Z,1544657448.578 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-12-12T23:30:48.708Z,1544657448.708 [SBIT](DEBUG): Construct Startup Built In Test.
2018-12-12T23:30:48.730Z,1544657448.730 [SBIT] Loaded
2018-12-12T23:30:48.730Z,1544657448.730 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-12-12T23:30:48.731Z,1544657448.731 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-12-12T23:30:48.742Z,1544657448.742 [IBIT] Loaded
2018-12-12T23:30:48.743Z,1544657448.743 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-12-12T23:30:48.746Z,1544657448.746 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-12-12T23:30:48.879Z,1544657448.879 [CBIT] Loaded
2018-12-12T23:30:48.879Z,1544657448.879 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-12-12T23:30:48.880Z,1544657448.880 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-12-12T23:30:48.880Z,1544657448.880 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-12-12T23:30:49.187Z,1544657449.187 [Aanderaa_O2] Loaded
2018-12-12T23:30:49.187Z,1544657449.187 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-12-12T23:30:49.293Z,1544657449.293 [CTD_Seabird] Loaded
2018-12-12T23:30:49.293Z,1544657449.293 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-12-12T23:30:49.294Z,1544657449.294 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408DD4E0
2018-12-12T23:30:49.295Z,1544657449.295 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4670
2018-12-12T23:30:49.312Z,1544657449.312 [ESPComponent] Loaded
2018-12-12T23:30:49.312Z,1544657449.312 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-12-12T23:30:49.326Z,1544657449.326 [PAR_Licor] Loaded
2018-12-12T23:30:49.326Z,1544657449.326 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-12-12T23:30:49.373Z,1544657449.373 [WetLabsBB2FL] Loaded
2018-12-12T23:30:49.373Z,1544657449.373 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-12-12T23:30:49.374Z,1544657449.374 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0
2018-12-12T23:30:49.374Z,1544657449.374 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4671
2018-12-12T23:30:49.375Z,1544657449.375 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-12-12T23:30:49.376Z,1544657449.376 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-12-12T23:30:49.607Z,1544657449.607 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-12-12T23:30:49.607Z,1544657449.607 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-12-12T23:30:49.674Z,1544657449.674 [VerticalControl](DEBUG): Construct VerticalControl.
2018-12-12T23:30:49.765Z,1544657449.765 [VerticalControl] Loaded
2018-12-12T23:30:49.765Z,1544657449.765 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-12-12T23:30:49.766Z,1544657449.766 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-12-12T23:30:49.824Z,1544657449.824 [HorizontalControl] Loaded
2018-12-12T23:30:49.825Z,1544657449.825 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-12-12T23:30:49.825Z,1544657449.825 [SpeedControl](DEBUG): Construct SpeedControl.
2018-12-12T23:30:49.830Z,1544657449.830 [SpeedControl] Loaded
2018-12-12T23:30:49.831Z,1544657449.831 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-12-12T23:30:49.831Z,1544657449.831 [LoopControl](DEBUG): Construct LoopControl.
2018-12-12T23:30:49.832Z,1544657449.832 [LoopControl] Loaded
2018-12-12T23:30:49.832Z,1544657449.832 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-12-12T23:30:49.833Z,1544657449.833 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-12-12T23:30:49.833Z,1544657449.833 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-12-12T23:30:49.895Z,1544657449.895 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-12-12T23:30:49.896Z,1544657449.896 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-12-12T23:30:49.992Z,1544657449.992 [BuoyancyServo] Loaded
2018-12-12T23:30:49.992Z,1544657449.992 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-12-12T23:30:50.004Z,1544657450.004 [ElevatorServo] Loaded
2018-12-12T23:30:50.004Z,1544657450.004 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-12-12T23:30:50.015Z,1544657450.015 [MassServo] Loaded
2018-12-12T23:30:50.015Z,1544657450.015 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-12-12T23:30:50.026Z,1544657450.026 [RudderServo] Loaded
2018-12-12T23:30:50.026Z,1544657450.026 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-12-12T23:30:50.036Z,1544657450.036 [ThrusterServo] Loaded
2018-12-12T23:30:50.037Z,1544657450.037 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-12-12T23:30:50.037Z,1544657450.037 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-12-12T23:30:50.038Z,1544657450.038 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-12-12T23:30:50.131Z,1544657450.131 [DeadReckonUsingSpeedCalculator] Loaded
2018-12-12T23:30:50.131Z,1544657450.131 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-12-12T23:30:50.147Z,1544657450.147 [NavChart] Loaded
2018-12-12T23:30:50.147Z,1544657450.147 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-12-12T23:30:50.151Z,1544657450.151 [UniversalFixResidualReporter] Loaded
2018-12-12T23:30:50.151Z,1544657450.151 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-12-12T23:30:50.152Z,1544657450.152 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-12-12T23:30:50.155Z,1544657450.155 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-12-12T23:30:50.156Z,1544657450.156 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-12-12T23:30:50.163Z,1544657450.163 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-12-12T23:30:50.164Z,1544657450.164 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A664E0
2018-12-12T23:30:50.164Z,1544657450.164 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4672
2018-12-12T23:30:50.169Z,1544657450.169 [Supervisor](INFO): Main Thread ID is 4465
2018-12-12T23:30:50.169Z,1544657450.169 [Supervisor](DEBUG): Running supervisor.
2018-12-12T23:30:50.169Z,1544657450.169 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4673
2018-12-12T23:30:50.172Z,1544657450.172 [controlThread ThreadHandler](INFO): Handler Thread ID is 4674
2018-12-12T23:30:50.172Z,1544657450.172 [controlThread](DEBUG): Initializing ControlThread
2018-12-12T23:30:50.173Z,1544657450.173 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-12-12T23:30:50.173Z,1544657450.173 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-12-12T23:30:50.174Z,1544657450.174 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-12-12T23:30:50.174Z,1544657450.174 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-12-12T23:30:50.175Z,1544657450.175 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-12-12T23:30:50.175Z,1544657450.175 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-12-12T23:30:50.179Z,1544657450.179 [SBIT](INFO): Initialize SBIT Component.
2018-12-12T23:30:50.179Z,1544657450.179 [SBIT](IMPORTANT): git: 2018-11-27-15-gd2bbcb2
2018-12-12T23:30:50.180Z,1544657450.180 [SBIT](INFO): git hash: d2bbcb2cc47253c054f62f2101b467732e90375e
2018-12-12T23:30:50.180Z,1544657450.180 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-12-12T23:30:50.180Z,1544657450.180 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-12-12T23:30:50.181Z,1544657450.181 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-12-12T23:30:50.182Z,1544657450.182 [IBIT](INFO): Initialize IBIT Component.
2018-12-12T23:30:50.183Z,1544657450.183 [CBIT](DEBUG): Initialize CBIT Component.
2018-12-12T23:30:50.184Z,1544657450.184 [logger ThreadHandler](INFO): Handler Thread ID is 4675
2018-12-12T23:30:50.197Z,1544657450.197 [CBIT](DEBUG): Initialized mux pins.
2018-12-12T23:30:50.197Z,1544657450.197 [CBIT](DEBUG): Initializing the watchdog timer.
2018-12-12T23:30:50.201Z,1544657450.201 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4676
2018-12-12T23:30:50.207Z,1544657450.207 [Radio_Surface](INFO): Powering up
2018-12-12T23:30:50.217Z,1544657450.217 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 4677
2018-12-12T23:30:50.218Z,1544657450.218 [Rowe_600LCM](INFO): Initializing
2018-12-12T23:30:50.218Z,1544657450.218 [Rowe_600LCM](INFO): Checking LCM
2018-12-12T23:30:50.225Z,1544657450.225 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-12-12T23:30:50.225Z,1544657450.225 [CBIT](DEBUG): Initializing heartbeat.
2018-12-12T23:30:50.227Z,1544657450.227 [Rowe_600LCM](INFO): LCM OK
2018-12-12T23:30:50.227Z,1544657450.227 [Rowe_600LCM](INFO): Powering up
2018-12-12T23:30:50.233Z,1544657450.233 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4678
2018-12-12T23:30:50.234Z,1544657450.234 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-12T23:30:50.237Z,1544657450.237 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-12T23:30:50.238Z,1544657450.238 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4680
2018-12-12T23:30:50.239Z,1544657450.239 [WetLabsBB2FL](INFO): Powering down
2018-12-12T23:30:50.265Z,1544657450.265 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4681
2018-12-12T23:30:50.268Z,1544657450.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-12-12T23:30:50.268Z,1544657450.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-12-12T23:30:50.269Z,1544657450.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-12-12T23:30:50.269Z,1544657450.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-12-12T23:30:50.269Z,1544657450.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-12-12T23:30:50.269Z,1544657450.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-12-12T23:30:50.270Z,1544657450.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-12-12T23:30:50.270Z,1544657450.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-12-12T23:30:50.270Z,1544657450.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-12-12T23:30:50.270Z,1544657450.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-12-12T23:30:50.270Z,1544657450.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-12-12T23:30:50.271Z,1544657450.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-12-12T23:30:50.271Z,1544657450.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-12-12T23:30:50.271Z,1544657450.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-12-12T23:30:50.271Z,1544657450.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-12-12T23:30:50.271Z,1544657450.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-12-12T23:30:50.296Z,1544657450.296 [CBIT](DEBUG): Deactivating GF circuits.
2018-12-12T23:30:50.297Z,1544657450.297 [CBIT](DEBUG): Deactivating emergency mode.
2018-12-12T23:30:50.337Z,1544657450.337 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-12-12T23:30:50.339Z,1544657450.339 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-12-12T23:30:50.339Z,1544657450.339 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-12-12T23:30:50.340Z,1544657450.340 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-12-12T23:30:50.342Z,1544657450.342 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-12T23:30:50.342Z,1544657450.342 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-12T23:30:50.343Z,1544657450.343 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-12T23:30:50.343Z,1544657450.343 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-12-12T23:30:50.343Z,1544657450.343 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-12-12T23:30:50.344Z,1544657450.344 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-12-12T23:30:50.367Z,1544657450.367 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-12T23:30:50.407Z,1544657450.407 [MissionManager](DEBUG):
2018-12-12T23:30:50.409Z,1544657450.409 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-12-12T23:30:50.514Z,1544657450.514 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-12-12T23:30:50.515Z,1544657450.515 [Default:A.Wait](DEBUG): Construct Wait.
2018-12-12T23:30:50.516Z,1544657450.516 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-12T23:30:50.536Z,1544657450.536 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-12-12T23:30:50.562Z,1544657450.562 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-12-12T23:30:50.568Z,1544657450.568 [Default:E.Execute](DEBUG): Construct Execute.
2018-12-12T23:30:50.611Z,1544657450.611 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2018-12-12T23:30:50.621Z,1544657450.621 [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,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-12-12T23:30:50.645Z,1544657450.645 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-12-12T23:30:50.645Z,1544657450.645 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-12-12T23:30:50.671Z,1544657450.671 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-12-12T23:30:50.961Z,1544657450.961 [ESPComponent](INFO): powering down ESP
2018-12-12T23:30:51.454Z,1544657451.454 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-12T23:30:51.461Z,1544657451.461 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-12T23:30:51.483Z,1544657451.483 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-12-12T23:30:51.489Z,1544657451.489 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-12-12T23:30:51.513Z,1544657451.513 [MassServo](DEBUG): Initializing EZServoServo.
2018-12-12T23:30:51.525Z,1544657451.525 [MassServo](DEBUG): Initializing MassServo.
2018-12-12T23:30:51.530Z,1544657451.530 [RudderServo](DEBUG): Initializing EZServoServo.
2018-12-12T23:30:51.537Z,1544657451.537 [RudderServo](DEBUG): Initializing RudderServo.
2018-12-12T23:30:51.558Z,1544657451.558 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-12-12T23:30:51.565Z,1544657451.565 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-12-12T23:30:51.695Z,1544657451.695 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2018-12-12T23:30:51.695Z,1544657451.695 [DropWeight] Hardware Fault, FailCount= 1
2018-12-12T23:30:51.695Z,1544657451.695 [DropWeight](ERROR): Hardware Fault
2018-12-12T23:30:51.821Z,1544657451.821 [CommandLine](FAULT): Scheduling is paused
2018-12-12T23:30:51.822Z,1544657451.822 [CBIT](INFO): Critical error at 20181212T233051
2018-12-12T23:30:51.822Z,1544657451.822 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-12-12T23:30:51.825Z,1544657451.825 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-12-12T23:30:51.825Z,1544657451.825 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-12-12T23:30:52.413Z,1544657452.413 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-12-12T23:30:52.413Z,1544657452.413 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-12-12T23:30:52.413Z,1544657452.413 [BuoyancyServo] Communications Fault, FailCount= 1
2018-12-12T23:30:52.413Z,1544657452.413 [BuoyancyServo](ERROR): Communications Fault
2018-12-12T23:30:52.618Z,1544657452.618 [CBIT](INFO): Critical error at 20181212T233051
2018-12-12T23:30:52.621Z,1544657452.621 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-12-12T23:30:54.671Z,1544657454.671 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-12T23:30:54.843Z,1544657454.843 [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 9600 >& /dev/null &
2018-12-12T23:30:54.863Z,1544657454.863 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-12-12T23:30:54.863Z,1544657454.863 [BuoyancyServo] No Fault, FailCount= 1
2018-12-12T23:30:54.898Z,1544657454.898 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-12T23:30:54.899Z,1544657454.899 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-12T23:30:54.899Z,1544657454.899 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-12T23:30:55.129Z,1544657455.129 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-12T23:30:55.130Z,1544657455.130 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-12T23:30:55.862Z,1544657455.862 [Radio_Surface](INFO): Powering down
2018-12-12T23:30:57.281Z,1544657457.281 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-12T23:30:57.281Z,1544657457.281 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-12T23:30:57.281Z,1544657457.281 [CTD_Seabird](ERROR): Communications Fault
2018-12-12T23:30:57.562Z,1544657457.562 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-12T23:30:57.685Z,1544657457.685 [CTD_Seabird](INFO): Powering down
2018-12-12T23:30:58.230Z,1544657458.230 [AcousticModem_Benthos_ATM900](DEBUG):
2018-12-12T23:30:58.249Z,1544657458.249 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-12T23:30:58.249Z,1544657458.249 [PNI_TCM] Data Fault, FailCount= 1
2018-12-12T23:30:58.249Z,1544657458.249 [PNI_TCM](ERROR): Data Fault
2018-12-12T23:30:58.414Z,1544657458.414 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-12T23:30:58.652Z,1544657458.652 [PNI_TCM](INFO): Powering down
2018-12-12T23:30:59.431Z,1544657459.431 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-12-12T23:30:59.431Z,1544657459.431 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-12-12T23:30:59.432Z,1544657459.432 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-12-12T23:30:59.433Z,1544657459.433 [AcousticModem_Benthos_ATM900](DEBUG): Nov 11 2018 08:58:07
2018-12-12T23:30:59.591Z,1544657459.591 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-12T23:30:59.591Z,1544657459.591 [CTD_Seabird] No Fault, FailCount= 1
2018-12-12T23:30:59.830Z,1544657459.830 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-12-12T23:30:59.926Z,1544657459.926 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-12T23:30:59.926Z,1544657459.926 [PNI_TCM] No Fault, FailCount= 1
2018-12-12T23:31:00.316Z,1544657460.316 [CBIT](CRITICAL): Environmental Failure. Press:14.841669 PSI. Humidity:37%. Temp:26 C. ABORTING MISSION
2018-12-12T23:31:00.541Z,1544657460.541 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-12T23:31:00.542Z,1544657460.542 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-12T23:31:00.630Z,1544657460.630 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-12-12T23:31:00.722Z,1544657460.722 [CBIT](INFO): Critical error at 20181212T233100
2018-12-12T23:31:01.030Z,1544657461.030 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-12-12T23:31:01.030Z,1544657461.030 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-12-12T23:31:01.030Z,1544657461.030 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-12-12T23:31:01.061Z,1544657461.061 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-12T23:31:01.061Z,1544657461.061 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-12-12T23:31:01.061Z,1544657461.061 [Aanderaa_O2](ERROR): Communications Fault
2018-12-12T23:31:01.134Z,1544657461.134 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-12T23:31:01.430Z,1544657461.430 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-12-12T23:31:01.461Z,1544657461.461 [Aanderaa_O2](INFO): Powering down
2018-12-12T23:31:01.830Z,1544657461.830 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-12-12T23:31:01.831Z,1544657461.831 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-12-12T23:31:02.230Z,1544657462.230 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-12-12T23:31:02.333Z,1544657462.333 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-12T23:31:02.333Z,1544657462.333 [Aanderaa_O2] No Fault, FailCount= 1
2018-12-12T23:31:07.448Z,1544657467.448 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-12T23:31:07.448Z,1544657467.448 [PNI_TCM] Data Fault, FailCount= 2
2018-12-12T23:31:07.449Z,1544657467.449 [PNI_TCM](ERROR): Data Fault
2018-12-12T23:31:07.523Z,1544657467.523 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-12T23:31:07.580Z,1544657467.580 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-12T23:31:07.581Z,1544657467.581 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-12T23:31:07.581Z,1544657467.581 [CTD_Seabird](ERROR): Communications Fault
2018-12-12T23:31:07.849Z,1544657467.849 [PNI_TCM](INFO): Powering down
2018-12-12T23:31:07.900Z,1544657467.900 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-12T23:31:07.985Z,1544657467.985 [CTD_Seabird](INFO): Powering down
2018-12-12T23:31:09.125Z,1544657469.125 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-12T23:31:09.125Z,1544657469.125 [PNI_TCM] No Fault, FailCount= 2
2018-12-12T23:31:09.917Z,1544657469.917 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-12T23:31:09.917Z,1544657469.917 [CTD_Seabird] No Fault, FailCount= 2
2018-12-12T23:31:10.813Z,1544657470.813 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-12T23:31:10.814Z,1544657470.814 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-12T23:31:13.049Z,1544657473.049 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-12T23:31:13.049Z,1544657473.049 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-12-12T23:31:13.049Z,1544657473.049 [Aanderaa_O2](ERROR): Communications Fault
2018-12-12T23:31:13.096Z,1544657473.096 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-12T23:31:13.450Z,1544657473.450 [Aanderaa_O2](INFO): Powering down
2018-12-12T23:31:14.297Z,1544657474.297 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-12T23:31:14.298Z,1544657474.298 [Aanderaa_O2] No Fault, FailCount= 2
2018-12-12T23:31:14.696Z,1544657474.696 [SBIT](IMPORTANT): Beginning Startup BIT
2018-12-12T23:31:14.708Z,1544657474.708 [CBIT](IMPORTANT): Beginning ground fault scan
2018-12-12T23:31:16.656Z,1544657476.656 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-12T23:31:16.656Z,1544657476.656 [PNI_TCM] Data Fault, FailCount= 3
2018-12-12T23:31:16.656Z,1544657476.656 [PNI_TCM](ERROR): Data Fault
2018-12-12T23:31:17.123Z,1544657477.123 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-12T23:31:17.137Z,1544657477.137 [NAL9602](INFO): Powering up NAL9602
2018-12-12T23:31:17.180Z,1544657477.180 [PNI_TCM](INFO): Powering down
2018-12-12T23:31:17.854Z,1544657477.854 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-12T23:31:17.854Z,1544657477.854 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-12T23:31:17.854Z,1544657477.854 [CTD_Seabird](ERROR): Communications Fault
2018-12-12T23:31:18.126Z,1544657478.126 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-12T23:31:18.126Z,1544657478.126 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-12T23:31:18.257Z,1544657478.257 [CTD_Seabird](INFO): Powering down
2018-12-12T23:31:18.451Z,1544657478.451 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-12T23:31:18.451Z,1544657478.451 [PNI_TCM] No Fault, FailCount= 3
2018-12-12T23:31:18.513Z,1544657478.513 [Radio_Surface](INFO): Powering up
2018-12-12T23:31:24.749Z,1544657484.749 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-12T23:31:24.749Z,1544657484.749 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-12-12T23:31:24.749Z,1544657484.749 [Aanderaa_O2](ERROR): Communications Fault
2018-12-12T23:31:24.870Z,1544657484.870 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-12T23:31:24.871Z,1544657484.871 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-12-12T23:31:25.265Z,1544657485.265 [Aanderaa_O2](INFO): Powering down
2018-12-12T23:31:25.662Z,1544657485.662 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007087
CHAN A1 (24V): -0.011490
CHAN A2 (12V): -0.005001
CHAN A3 (5V): -0.002825
CHAN B0 (3.3V): -0.001079
CHAN B1 (3.15aV): -0.000955
CHAN B2 (3.15bV): -0.001219
CHAN B3 (GND): 0.000423
OPEN: 0.004368
Full Scale Calc: 4.765 mA, -1.589 mA
2018-12-12T23:31:25.948Z,1544657485.948 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-12T23:31:25.949Z,1544657485.949 [PNI_TCM] Data Fault, FailCount= 4
2018-12-12T23:31:25.949Z,1544657485.949 [PNI_TCM](ERROR): Data Fault
2018-12-12T23:31:26.063Z,1544657486.063 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-12T23:31:26.189Z,1544657486.189 [Radio_Surface](INFO): Powering down
2018-12-12T23:31:26.349Z,1544657486.349 [PNI_TCM](INFO): Powering down
2018-12-12T23:31:27.642Z,1544657487.642 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-12T23:31:27.642Z,1544657487.642 [PNI_TCM] No Fault, FailCount= 4
2018-12-12T23:31:28.334Z,1544657488.334 [NAL9602](INFO): NAL9602 initialized
2018-12-12T23:31:35.393Z,1544657495.393 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-12T23:31:35.393Z,1544657495.393 [PNI_TCM] Data Fault, FailCount= 5
2018-12-12T23:31:35.393Z,1544657495.393 [PNI_TCM](ERROR): Data Fault
2018-12-12T23:31:35.682Z,1544657495.682 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-12T23:31:35.682Z,1544657495.682 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-12-12T23:31:35.797Z,1544657495.797 [PNI_TCM](INFO): Powering down
2018-12-12T23:31:36.075Z,1544657496.075 [CBIT](INFO): Critical error at 20181212T233135
2018-12-12T23:32:08.773Z,1544657528.773 [SBIT](IMPORTANT): SBIT PASSED
2018-12-12T23:32:08.820Z,1544657528.820 [CommandLine](IMPORTANT): got command configSet list
2018-12-12T23:32:08.821Z,1544657528.821 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-12-12T23:32:08.822Z,1544657528.822 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;
2018-12-12T23:32:09.144Z,1544657529.144 [MissionManager](IMPORTANT): Started mission Startup
2018-12-12T23:32:09.144Z,1544657529.144 [Startup] Running Loop=1
2018-12-12T23:32:09.144Z,1544657529.144 [Startup](DEBUG): Aggregate::initialize Startup
2018-12-12T23:32:09.144Z,1544657529.144 [Startup:A.GoToSurface] Running Loop=1
2018-12-12T23:32:09.145Z,1544657529.145 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-12-12T23:32:09.145Z,1544657529.145 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-12-12T23:32:09.146Z,1544657529.146 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-12-12T23:32:09.146Z,1544657529.146 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-12-12T23:32:09.146Z,1544657529.146 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-12-12T23:32:09.147Z,1544657529.147 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-12-12T23:32:09.425Z,1544657529.425 [Radio_Surface](INFO): Powering up
2018-12-12T23:32:50.390Z,1544657570.390 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-12T23:32:50.390Z,1544657570.390 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-12-12T23:32:50.390Z,1544657570.390 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-12T23:32:50.393Z,1544657570.393 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-12T23:32:50.393Z,1544657570.393 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-12-12T23:32:50.393Z,1544657570.393 [Rowe_600LCM](ERROR): Communications Fault
2018-12-12T23:32:50.430Z,1544657570.430 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-12T23:32:50.430Z,1544657570.430 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-12T23:32:50.797Z,1544657570.797 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-12T23:32:50.842Z,1544657570.842 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-12T23:32:50.842Z,1544657570.842 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-12-12T23:32:50.949Z,1544657570.949 [Rowe_600LCM](INFO): Powering down
2018-12-12T23:32:51.190Z,1544657571.190 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-12T23:32:51.191Z,1544657571.191 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-12T23:32:51.191Z,1544657571.191 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-12T23:32:52.403Z,1544657572.403 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-12T23:32:52.403Z,1544657572.403 [Rowe_600LCM] No Fault, FailCount= 1
2018-12-12T23:32:52.565Z,1544657572.565 [Rowe_600LCM](INFO): Initializing
2018-12-12T23:32:52.565Z,1544657572.565 [Rowe_600LCM](INFO): Checking LCM
2018-12-12T23:32:52.565Z,1544657572.565 [Rowe_600LCM](INFO): LCM OK
2018-12-12T23:32:52.565Z,1544657572.565 [Rowe_600LCM](INFO): Powering up
2018-12-12T23:32:56.685Z,1544657576.685 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-12T23:32:56.786Z,1544657576.786 [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 9600 >& /dev/null &
2018-12-12T23:32:56.787Z,1544657576.787 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-12T23:32:56.787Z,1544657576.787 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-12T23:32:56.788Z,1544657576.788 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-12T23:33:02.800Z,1544657582.800 [VerticalControl](CRITICAL): Excessive depth excursion=11.326988 m, failToGoUpDepth_=31.905743 m, depthRate=0.212665 m/s, pitch=nan deg.
2018-12-12T23:33:02.814Z,1544657582.814 [CBIT](INFO): Critical error at 20181212T233302
2018-12-12T23:33:12.383Z,1544657592.383 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.000000 meter
2018-12-12T23:33:12.384Z,1544657592.384 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2018-12-12T23:33:12.441Z,1544657592.441 [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,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-12-12T23:33:12.786Z,1544657592.786 [Startup:StartupSatComms] Running Loop=1
2018-12-12T23:33:12.786Z,1544657592.786 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-12-12T23:33:12.786Z,1544657592.786 [Startup:StartupSatComms:A] Running Loop=1
2018-12-12T23:33:13.188Z,1544657593.188 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-12-12T23:33:16.021Z,1544657596.021 [BPC1](ERROR): BPC1B: No match for serial number FFFF in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2018-12-12T23:33:48.066Z,1544657628.066 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-12T23:33:48.066Z,1544657628.066 [CTD_Seabird] No Fault, FailCount= 3
2018-12-12T23:33:48.261Z,1544657628.261 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-12T23:33:48.262Z,1544657628.262 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-12T23:33:54.849Z,1544657634.849 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-12T23:33:54.849Z,1544657634.849 [Aanderaa_O2] No Fault, FailCount= 3
2018-12-12T23:33:55.304Z,1544657635.304 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-12T23:33:55.305Z,1544657635.305 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-12T23:33:55.305Z,1544657635.305 [CTD_Seabird](ERROR): Communications Fault
2018-12-12T23:33:55.657Z,1544657635.657 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-12T23:33:55.709Z,1544657635.709 [CTD_Seabird](INFO): Powering down
2018-12-12T23:33:58.048Z,1544657638.048 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-12T23:33:58.048Z,1544657638.048 [CTD_Seabird] No Fault, FailCount= 1
2018-12-12T23:33:58.657Z,1544657638.657 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-12T23:33:58.658Z,1544657638.658 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-12T23:34:05.206Z,1544657645.206 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-12T23:34:05.206Z,1544657645.206 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-12-12T23:34:05.206Z,1544657645.206 [Aanderaa_O2](ERROR): Communications Fault
2018-12-12T23:34:05.254Z,1544657645.254 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-12T23:34:05.704Z,1544657645.704 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-12T23:34:05.705Z,1544657645.705 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-12T23:34:05.705Z,1544657645.705 [CTD_Seabird](ERROR): Communications Fault
2018-12-12T23:34:05.725Z,1544657645.725 [Aanderaa_O2](INFO): Powering down
2018-12-12T23:34:05.769Z,1544657645.769 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-12T23:34:06.109Z,1544657646.109 [CTD_Seabird](INFO): Powering down
2018-12-12T23:34:06.425Z,1544657646.425 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-12T23:34:06.425Z,1544657646.425 [Aanderaa_O2] No Fault, FailCount= 1
2018-12-12T23:34:08.058Z,1544657648.058 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-12T23:34:08.058Z,1544657648.058 [CTD_Seabird] No Fault, FailCount= 2
2018-12-12T23:34:09.053Z,1544657649.053 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-12T23:34:09.054Z,1544657649.054 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-12T23:34:12.855Z,1544657652.855 [Startup:StartupSatComms:A](INFO): Timed out from 2018-12-12T23:33:12.8Z
2018-12-12T23:34:12.855Z,1544657652.855 [Startup:StartupSatComms:A] Stopped
2018-12-12T23:34:12.855Z,1544657652.855 [Startup:StartupSatComms:B] Running Loop=1
2018-12-12T23:34:13.236Z,1544657653.236 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-12-12T23:34:16.096Z,1544657656.096 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-12T23:34:16.097Z,1544657656.097 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-12T23:34:16.097Z,1544657656.097 [CTD_Seabird](ERROR): Communications Fault
2018-12-12T23:34:16.438Z,1544657656.438 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-12T23:34:16.438Z,1544657656.438 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-12T23:34:16.501Z,1544657656.501 [CTD_Seabird](INFO): Powering down
2018-12-12T23:34:16.808Z,1544657656.808 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-12T23:34:16.808Z,1544657656.808 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-12-12T23:34:16.808Z,1544657656.808 [Aanderaa_O2](ERROR): Communications Fault
2018-12-12T23:34:16.826Z,1544657656.826 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-12T23:34:17.325Z,1544657657.325 [Aanderaa_O2](INFO): Powering down
2018-12-12T23:34:18.043Z,1544657658.043 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-12T23:34:18.043Z,1544657658.043 [Aanderaa_O2] No Fault, FailCount= 2
2018-12-12T23:34:27.766Z,1544657667.766 [CommandLine](IMPORTANT): got command show variable commandline
2018-12-12T23:34:27.858Z,1544657667.858 [CommandLine](IMPORTANT): CommandLine.platform_conversation (bool)
2018-12-12T23:34:28.418Z,1544657668.418 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-12T23:34:28.418Z,1544657668.418 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-12-12T23:34:28.419Z,1544657668.419 [Aanderaa_O2](ERROR): Communications Fault
2018-12-12T23:34:28.442Z,1544657668.442 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-12T23:34:28.442Z,1544657668.442 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-12-12T23:34:28.937Z,1544657668.937 [Aanderaa_O2](INFO): Powering down
2018-12-12T23:34:51.234Z,1544657691.234 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-12T23:34:51.234Z,1544657691.234 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-12-12T23:34:51.234Z,1544657691.234 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-12T23:34:51.248Z,1544657691.248 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-12T23:34:51.655Z,1544657691.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-12T23:34:51.655Z,1544657691.655 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-12-12T23:34:52.629Z,1544657692.629 [CommandLine](IMPORTANT): got command restart system
2018-12-12T23:34:53.169Z,1544657693.169 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-12T23:34:53.169Z,1544657693.169 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-12-12T23:34:53.169Z,1544657693.169 [Rowe_600LCM](ERROR): Communications Fault
2018-12-12T23:34:53.573Z,1544657693.573 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-12T23:34:53.761Z,1544657693.761 [Rowe_600LCM](INFO): Powering down
2018-12-12T23:34:54.904Z,1544657694.904 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-12T23:34:54.904Z,1544657694.904 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.007Z,1544657695.007 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-12T23:34:55.007Z,1544657695.007 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-12T23:34:55.008Z,1544657695.008 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-12T23:34:55.017Z,1544657695.017 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2018-12-12T23:34:55.017Z,1544657695.017 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.017Z,1544657695.017 [CommandLine](INFO): Join timeout helper Thread ID is 4720
2018-12-12T23:34:55.040Z,1544657695.040 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-12T23:34:55.040Z,1544657695.040 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-12T23:34:55.040Z,1544657695.040 [Rowe_600LCM] No Fault, FailCount= 2
2018-12-12T23:34:55.049Z,1544657695.049 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-12-12T23:34:55.049Z,1544657695.049 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.049Z,1544657695.049 [NavChartDb](INFO): Join timeout helper Thread ID is 4721
2018-12-12T23:34:55.353Z,1544657695.353 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-12T23:34:55.353Z,1544657695.353 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.361Z,1544657695.361 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-12-12T23:34:55.361Z,1544657695.361 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.361Z,1544657695.361 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4722
2018-12-12T23:34:55.373Z,1544657695.373 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-12T23:34:55.373Z,1544657695.373 [WetLabsBB2FL](INFO): Powering down
2018-12-12T23:34:55.374Z,1544657695.374 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.381Z,1544657695.381 [Rowe_600LCM](INFO): Initializing
2018-12-12T23:34:55.381Z,1544657695.381 [Rowe_600LCM](INFO): Checking LCM
2018-12-12T23:34:55.382Z,1544657695.382 [Rowe_600LCM](INFO): LCM OK
2018-12-12T23:34:55.382Z,1544657695.382 [Rowe_600LCM](INFO): Powering up
2018-12-12T23:34:55.389Z,1544657695.389 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-12-12T23:34:55.389Z,1544657695.389 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.389Z,1544657695.389 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4723
2018-12-12T23:34:55.416Z,1544657695.416 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-12T23:34:55.417Z,1544657695.417 [CTD_Seabird](INFO): Powering down
2018-12-12T23:34:55.429Z,1544657695.429 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.441Z,1544657695.441 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-12-12T23:34:55.441Z,1544657695.441 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.441Z,1544657695.441 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 4724
2018-12-12T23:34:55.785Z,1544657695.785 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-12T23:34:55.785Z,1544657695.785 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-12T23:34:55.865Z,1544657695.865 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004915
2018-12-12T23:34:55.939Z,1544657695.939 [Rowe_600LCM](INFO): Powering down
2018-12-12T23:34:55.940Z,1544657695.940 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.950Z,1544657695.950 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-12-12T23:34:55.950Z,1544657695.950 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.950Z,1544657695.950 [Radio_Surface](INFO): Join timeout helper Thread ID is 4726
2018-12-12T23:34:55.965Z,1544657695.965 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-12T23:34:55.965Z,1544657695.965 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.977Z,1544657695.977 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-12-12T23:34:55.978Z,1544657695.978 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.978Z,1544657695.978 [logger](INFO): Join timeout helper Thread ID is 4727
2018-12-12T23:34:55.978Z,1544657695.978 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-12T23:34:55.979Z,1544657695.979 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.980Z,1544657695.980 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-12-12T23:34:55.980Z,1544657695.980 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.980Z,1544657695.980 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-12-12T23:34:55.980Z,1544657695.980 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:55.981Z,1544657695.981 [controlThread](INFO): Join timeout helper Thread ID is 4728
2018-12-12T23:34:56.249Z,1544657696.249 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-12T23:34:56.249Z,1544657696.249 [controlThread](DEBUG): Uninitializing ControlThread
2018-12-12T23:34:56.249Z,1544657696.249 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-12-12T23:34:56.369Z,1544657696.369 [NAL9602](INFO): Powering down
2018-12-12T23:34:56.371Z,1544657696.371 [PNI_TCM](INFO): Powering down
2018-12-12T23:34:56.372Z,1544657696.372 [Aanderaa_O2](INFO): Powering down
2018-12-12T23:34:56.375Z,1544657696.375 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2018-12-12T23:34:56.376Z,1544657696.376 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-12-12T23:34:56.377Z,1544657696.377 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-12-12T23:34:56.377Z,1544657696.377 [MissionManager](INFO): Uninitializing Mission Default
2018-12-12T23:34:56.380Z,1544657696.380 [MissionManager](INFO): Uninitializing Mission Startup
2018-12-12T23:34:56.380Z,1544657696.380 [Startup] Stopped
2018-12-12T23:34:56.380Z,1544657696.380 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-12-12T23:34:56.380Z,1544657696.380 [Startup:A.GoToSurface] Stopped
2018-12-12T23:34:56.380Z,1544657696.380 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-12-12T23:34:56.380Z,1544657696.380 [Startup:StartupSatComms] Stopped
2018-12-12T23:34:56.381Z,1544657696.381 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-12-12T23:34:56.381Z,1544657696.381 [Startup:StartupSatComms:B] Stopped
2018-12-12T23:34:56.383Z,1544657696.383 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-12-12T23:34:56.383Z,1544657696.383 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-12-12T23:34:56.383Z,1544657696.383 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-12-12T23:34:56.383Z,1544657696.383 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-12-12T23:34:56.384Z,1544657696.384 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-12-12T23:34:56.384Z,1544657696.384 [BuoyancyServo](INFO): Powering down
2018-12-12T23:34:56.397Z,1544657696.397 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-12-12T23:34:56.397Z,1544657696.397 [ElevatorServo](INFO): Powering down
2018-12-12T23:34:56.398Z,1544657696.398 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-12-12T23:34:56.398Z,1544657696.398 [MassServo](INFO): Powering down
2018-12-12T23:34:56.399Z,1544657696.399 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-12-12T23:34:56.399Z,1544657696.399 [RudderServo](INFO): Powering down
2018-12-12T23:34:56.400Z,1544657696.400 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-12-12T23:34:56.400Z,1544657696.400 [ThrusterServo](INFO): Powering down
2018-12-12T23:34:56.401Z,1544657696.401 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-12-12T23:34:56.401Z,1544657696.401 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-12-12T23:34:56.401Z,1544657696.401 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-12-12T23:34:56.401Z,1544657696.401 [CBIT](DEBUG): Powering off loads.
2018-12-12T23:34:56.412Z,1544657696.412 [CBIT](DEBUG): Disabling WDT.
2018-12-12T23:34:56.424Z,1544657696.424 [CBIT](DEBUG): Opening all GF detection circuits.
2018-12-12T23:34:56.425Z,1544657696.425 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:56.527Z,1544657696.527 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:56.533Z,1544657696.533 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:56.574Z,1544657696.574 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:56.579Z,1544657696.579 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:56.626Z,1544657696.626 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-12T23:34:56.686Z,1544657696.686 [logger ThreadHandler](INFO): Thread cancelled.