2018-12-07T23:26:39.515Z,1544225199.515 [Supervisor](DEBUG): Initializing supervisor.
2018-12-07T23:26:39.519Z,1544225199.519 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-12-07T23:26:39.520Z,1544225199.520 [SyncHandler](INFO): Protected caller Thread ID is 800
2018-12-07T23:26:39.521Z,1544225199.521 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-12-07T23:26:39.524Z,1544225199.524 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-12-07T23:26:39.525Z,1544225199.525 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 801
2018-12-07T23:26:39.527Z,1544225199.527 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-12-07T23:26:39.540Z,1544225199.540 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-12-07T23:26:39.541Z,1544225199.541 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-12-07T23:26:39.542Z,1544225199.542 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802
2018-12-07T23:26:39.544Z,1544225199.544 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-12-07T23:26:39.546Z,1544225199.546 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-12-07T23:26:39.546Z,1544225199.546 [logger ThreadHandler](INFO): Protected caller Thread ID is 803
2018-12-07T23:26:39.550Z,1544225199.550 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-12-07T23:26:39.550Z,1544225199.550 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-12-07T23:26:39.555Z,1544225199.555 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-12-07T23:26:39.689Z,1544225199.689 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-12-07T23:26:39.691Z,1544225199.691 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-12-07T23:26:39.839Z,1544225199.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-12-07T23:26:39.841Z,1544225199.841 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-12-07T23:26:40.086Z,1544225200.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-12-07T23:26:40.087Z,1544225200.087 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-12-07T23:26:40.506Z,1544225200.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-12-07T23:26:40.507Z,1544225200.507 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-12-07T23:26:40.940Z,1544225200.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-12-07T23:26:40.942Z,1544225200.942 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-12-07T23:26:41.479Z,1544225201.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-12-07T23:26:41.481Z,1544225201.481 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-12-07T23:26:42.039Z,1544225202.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-12-07T23:26:42.041Z,1544225202.041 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-12-07T23:26:42.676Z,1544225202.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-12-07T23:26:42.678Z,1544225202.678 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-12-07T23:26:42.778Z,1544225202.778 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-12-07T23:26:43.155Z,1544225203.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-12-07T23:26:43.157Z,1544225203.157 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-12-07T23:26:43.340Z,1544225203.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-12-07T23:26:43.341Z,1544225203.341 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-12-07T23:26:43.591Z,1544225203.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-12-07T23:26:43.592Z,1544225203.592 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-12-07T23:26:43.854Z,1544225203.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-12-07T23:26:43.855Z,1544225203.855 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-12-07T23:26:43.981Z,1544225203.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-12-07T23:26:43.983Z,1544225203.983 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-12-07T23:26:44.168Z,1544225204.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-12-07T23:26:44.170Z,1544225204.170 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-12-07T23:26:44.172Z,1544225204.172 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-12-07T23:26:44.442Z,1544225204.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-12-07T23:26:44.443Z,1544225204.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-12-07T23:26:44.563Z,1544225204.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-12-07T23:26:44.752Z,1544225204.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-12-07T23:26:44.863Z,1544225204.863 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-12-07T23:26:44.979Z,1544225204.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-12-07T23:26:45.165Z,1544225205.165 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-12-07T23:26:45.429Z,1544225205.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-12-07T23:26:45.561Z,1544225205.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-12-07T23:26:45.677Z,1544225205.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-12-07T23:26:45.803Z,1544225205.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-12-07T23:26:45.900Z,1544225205.900 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-12-07T23:26:45.908Z,1544225205.908 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-12-07T23:26:46.047Z,1544225206.047 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-12-07T23:26:46.049Z,1544225206.049 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-12-07T23:26:46.074Z,1544225206.074 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-12-07T23:26:46.075Z,1544225206.075 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-12-07T23:26:46.152Z,1544225206.152 [DepthRateCalculator] Loaded
2018-12-07T23:26:46.153Z,1544225206.153 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-12-07T23:26:46.159Z,1544225206.159 [PitchRateCalculator] Loaded
2018-12-07T23:26:46.160Z,1544225206.160 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-12-07T23:26:46.178Z,1544225206.178 [SpeedCalculator] Loaded
2018-12-07T23:26:46.179Z,1544225206.179 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-12-07T23:26:46.200Z,1544225206.200 [TempGradientCalculator] Loaded
2018-12-07T23:26:46.200Z,1544225206.200 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-12-07T23:26:46.207Z,1544225206.207 [YawRateCalculator] Loaded
2018-12-07T23:26:46.207Z,1544225206.207 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-12-07T23:26:46.263Z,1544225206.263 [ElevatorOffsetCalculator] Loaded
2018-12-07T23:26:46.263Z,1544225206.263 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-12-07T23:26:46.263Z,1544225206.263 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-12-07T23:26:46.264Z,1544225206.264 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-12-07T23:26:46.305Z,1544225206.305 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-12-07T23:26:46.306Z,1544225206.306 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-12-07T23:26:46.868Z,1544225206.868 [AcousticModem_Benthos_ATM900] Loaded
2018-12-07T23:26:46.869Z,1544225206.869 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-12-07T23:26:47.043Z,1544225207.043 [DataOverHttps] Loaded
2018-12-07T23:26:47.044Z,1544225207.044 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-12-07T23:26:47.060Z,1544225207.060 [Depth_Keller] Loaded
2018-12-07T23:26:47.060Z,1544225207.060 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-12-07T23:26:47.068Z,1544225207.068 [DropWeight] Loaded
2018-12-07T23:26:47.068Z,1544225207.068 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-12-07T23:26:47.179Z,1544225207.179 [NAL9602] Loaded
2018-12-07T23:26:47.179Z,1544225207.179 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-12-07T23:26:47.201Z,1544225207.201 [Onboard] Loaded
2018-12-07T23:26:47.201Z,1544225207.201 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-12-07T23:26:47.216Z,1544225207.216 [Radio_Surface] Loaded
2018-12-07T23:26:47.216Z,1544225207.216 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-12-07T23:26:47.218Z,1544225207.218 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0
2018-12-07T23:26:47.219Z,1544225207.219 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2018-12-07T23:26:47.278Z,1544225207.278 [PNI_TCM] Loaded
2018-12-07T23:26:47.278Z,1544225207.278 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-12-07T23:26:47.378Z,1544225207.378 [Rowe_600LCM] Loaded
2018-12-07T23:26:47.379Z,1544225207.379 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-12-07T23:26:47.380Z,1544225207.380 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0
2018-12-07T23:26:47.380Z,1544225207.380 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 883
2018-12-07T23:26:49.518Z,1544225209.518 [BPC1] Loaded
2018-12-07T23:26:49.518Z,1544225209.518 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-12-07T23:26:49.519Z,1544225209.519 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-12-07T23:26:49.519Z,1544225209.519 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-12-07T23:26:49.701Z,1544225209.701 [SBIT](DEBUG): Construct Startup Built In Test.
2018-12-07T23:26:49.739Z,1544225209.739 [SBIT] Loaded
2018-12-07T23:26:49.740Z,1544225209.740 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-12-07T23:26:49.740Z,1544225209.740 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-12-07T23:26:49.752Z,1544225209.752 [IBIT] Loaded
2018-12-07T23:26:49.752Z,1544225209.752 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-12-07T23:26:49.755Z,1544225209.755 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-12-07T23:26:49.936Z,1544225209.936 [CBIT] Loaded
2018-12-07T23:26:49.937Z,1544225209.937 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-12-07T23:26:49.937Z,1544225209.937 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-12-07T23:26:49.938Z,1544225209.938 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-12-07T23:26:50.194Z,1544225210.194 [Aanderaa_O2] Loaded
2018-12-07T23:26:50.195Z,1544225210.195 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-12-07T23:26:50.263Z,1544225210.263 [CTD_Seabird] Loaded
2018-12-07T23:26:50.263Z,1544225210.263 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-12-07T23:26:50.265Z,1544225210.265 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408C74E0
2018-12-07T23:26:50.266Z,1544225210.266 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 884
2018-12-07T23:26:50.299Z,1544225210.299 [ESPComponent] Loaded
2018-12-07T23:26:50.299Z,1544225210.299 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-12-07T23:26:50.321Z,1544225210.321 [PAR_Licor] Loaded
2018-12-07T23:26:50.322Z,1544225210.322 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-12-07T23:26:50.377Z,1544225210.377 [WetLabsBB2FL] Loaded
2018-12-07T23:26:50.377Z,1544225210.377 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-12-07T23:26:50.379Z,1544225210.379 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408F74E0
2018-12-07T23:26:50.379Z,1544225210.379 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 885
2018-12-07T23:26:50.381Z,1544225210.381 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-12-07T23:26:50.383Z,1544225210.383 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-12-07T23:26:50.707Z,1544225210.707 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-12-07T23:26:50.708Z,1544225210.708 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-12-07T23:26:50.806Z,1544225210.806 [VerticalControl](DEBUG): Construct VerticalControl.
2018-12-07T23:26:50.922Z,1544225210.922 [VerticalControl] Loaded
2018-12-07T23:26:50.923Z,1544225210.923 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-12-07T23:26:50.923Z,1544225210.923 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-12-07T23:26:50.989Z,1544225210.989 [HorizontalControl] Loaded
2018-12-07T23:26:50.989Z,1544225210.989 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-12-07T23:26:50.990Z,1544225210.990 [SpeedControl](DEBUG): Construct SpeedControl.
2018-12-07T23:26:50.995Z,1544225210.995 [SpeedControl] Loaded
2018-12-07T23:26:50.996Z,1544225210.996 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-12-07T23:26:50.996Z,1544225210.996 [LoopControl](DEBUG): Construct LoopControl.
2018-12-07T23:26:50.997Z,1544225210.997 [LoopControl] Loaded
2018-12-07T23:26:50.998Z,1544225210.998 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-12-07T23:26:50.999Z,1544225210.999 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-12-07T23:26:51.002Z,1544225211.002 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-12-07T23:26:51.113Z,1544225211.113 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-12-07T23:26:51.114Z,1544225211.114 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-12-07T23:26:51.250Z,1544225211.250 [BuoyancyServo] Loaded
2018-12-07T23:26:51.250Z,1544225211.250 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-12-07T23:26:51.262Z,1544225211.262 [ElevatorServo] Loaded
2018-12-07T23:26:51.262Z,1544225211.262 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-12-07T23:26:51.273Z,1544225211.273 [MassServo] Loaded
2018-12-07T23:26:51.274Z,1544225211.274 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-12-07T23:26:51.287Z,1544225211.287 [RudderServo] Loaded
2018-12-07T23:26:51.288Z,1544225211.288 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-12-07T23:26:51.300Z,1544225211.300 [ThrusterServo] Loaded
2018-12-07T23:26:51.300Z,1544225211.300 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-12-07T23:26:51.300Z,1544225211.300 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-12-07T23:26:51.301Z,1544225211.301 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-12-07T23:26:51.427Z,1544225211.427 [DeadReckonUsingSpeedCalculator] Loaded
2018-12-07T23:26:51.427Z,1544225211.427 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-12-07T23:26:51.447Z,1544225211.447 [NavChart] Loaded
2018-12-07T23:26:51.448Z,1544225211.448 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-12-07T23:26:51.453Z,1544225211.453 [UniversalFixResidualReporter] Loaded
2018-12-07T23:26:51.454Z,1544225211.454 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-12-07T23:26:51.454Z,1544225211.454 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-12-07T23:26:51.458Z,1544225211.458 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-12-07T23:26:51.459Z,1544225211.459 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-12-07T23:26:51.466Z,1544225211.466 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-12-07T23:26:51.467Z,1544225211.467 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A504E0
2018-12-07T23:26:51.467Z,1544225211.467 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 886
2018-12-07T23:26:51.473Z,1544225211.473 [Supervisor](INFO): Main Thread ID is 796
2018-12-07T23:26:51.473Z,1544225211.473 [Supervisor](DEBUG): Running supervisor.
2018-12-07T23:26:51.474Z,1544225211.474 [CommandLine ThreadHandler](INFO): Handler Thread ID is 887
2018-12-07T23:26:51.477Z,1544225211.477 [controlThread ThreadHandler](INFO): Handler Thread ID is 888
2018-12-07T23:26:51.478Z,1544225211.478 [controlThread](DEBUG): Initializing ControlThread
2018-12-07T23:26:51.479Z,1544225211.479 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-12-07T23:26:51.479Z,1544225211.479 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-12-07T23:26:51.479Z,1544225211.479 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-12-07T23:26:51.480Z,1544225211.480 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-12-07T23:26:51.480Z,1544225211.480 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-12-07T23:26:51.481Z,1544225211.481 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-12-07T23:26:51.487Z,1544225211.487 [SBIT](INFO): Initialize SBIT Component.
2018-12-07T23:26:51.488Z,1544225211.488 [SBIT](IMPORTANT): git: 2018-11-05-3-g424fd37
2018-12-07T23:26:51.488Z,1544225211.488 [SBIT](INFO): git hash: 424fd377e53a63ada65ca308b07209cf4fd40d4d
2018-12-07T23:26:51.488Z,1544225211.488 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-12-07T23:26:51.488Z,1544225211.488 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-12-07T23:26:51.490Z,1544225211.490 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-12-07T23:26:51.490Z,1544225211.490 [IBIT](INFO): Initialize IBIT Component.
2018-12-07T23:26:51.491Z,1544225211.491 [CBIT](DEBUG): Initialize CBIT Component.
2018-12-07T23:26:51.491Z,1544225211.491 [CBIT](DEBUG): Initialized mux pins.
2018-12-07T23:26:51.491Z,1544225211.491 [CBIT](DEBUG): Initializing the watchdog timer.
2018-12-07T23:26:51.494Z,1544225211.494 [logger ThreadHandler](INFO): Handler Thread ID is 889
2018-12-07T23:26:51.517Z,1544225211.517 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-12-07T23:26:51.517Z,1544225211.517 [CBIT](DEBUG): Initializing heartbeat.
2018-12-07T23:26:51.521Z,1544225211.522 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 890
2018-12-07T23:26:51.528Z,1544225211.528 [Radio_Surface](INFO): Powering up
2018-12-07T23:26:51.533Z,1544225211.533 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 891
2018-12-07T23:26:51.534Z,1544225211.534 [Rowe_600LCM](INFO): Initializing
2018-12-07T23:26:51.535Z,1544225211.535 [Rowe_600LCM](INFO): Checking LCM
2018-12-07T23:26:51.553Z,1544225211.553 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 892
2018-12-07T23:26:51.555Z,1544225211.555 [CTD_Seabird](INFO): Initializing
2018-12-07T23:26:51.556Z,1544225211.556 [CTD_Seabird](INFO): Checking LCM
2018-12-07T23:26:51.589Z,1544225211.589 [CBIT](DEBUG): Deactivating GF circuits.
2018-12-07T23:26:51.589Z,1544225211.589 [CBIT](DEBUG): Deactivating emergency mode.
2018-12-07T23:26:51.649Z,1544225211.649 [CBIT](DEBUG): Backplane powered.
2018-12-07T23:26:51.669Z,1544225211.669 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 893
2018-12-07T23:26:51.671Z,1544225211.671 [WetLabsBB2FL](INFO): Powering down
2018-12-07T23:26:51.693Z,1544225211.693 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 894
2018-12-07T23:26:51.700Z,1544225211.700 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-12-07T23:26:51.701Z,1544225211.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-12-07T23:26:51.701Z,1544225211.701 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-12-07T23:26:51.701Z,1544225211.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-12-07T23:26:51.702Z,1544225211.702 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-12-07T23:26:51.702Z,1544225211.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-12-07T23:26:51.702Z,1544225211.702 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-12-07T23:26:51.702Z,1544225211.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-12-07T23:26:51.702Z,1544225211.702 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-12-07T23:26:51.702Z,1544225211.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-12-07T23:26:51.703Z,1544225211.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-12-07T23:26:51.703Z,1544225211.703 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-12-07T23:26:51.703Z,1544225211.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-12-07T23:26:51.703Z,1544225211.703 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-12-07T23:26:51.703Z,1544225211.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-12-07T23:26:51.704Z,1544225211.704 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-12-07T23:26:51.725Z,1544225211.725 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-12-07T23:26:51.727Z,1544225211.727 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-12-07T23:26:51.740Z,1544225211.740 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-12-07T23:26:51.751Z,1544225211.751 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-12-07T23:26:51.759Z,1544225211.759 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-07T23:26:51.759Z,1544225211.759 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-07T23:26:51.760Z,1544225211.760 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-07T23:26:51.760Z,1544225211.760 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-12-07T23:26:51.761Z,1544225211.761 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-12-07T23:26:51.776Z,1544225211.776 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-12-07T23:26:51.964Z,1544225211.964 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-07T23:26:52.144Z,1544225212.144 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-12-07T23:26:52.145Z,1544225212.145 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-12-07T23:26:52.288Z,1544225212.288 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-12-07T23:26:52.290Z,1544225212.290 [Default:A.Wait](DEBUG): Construct Wait.
2018-12-07T23:26:52.291Z,1544225212.291 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-07T23:26:52.309Z,1544225212.309 [CTD_Seabird](INFO): LCM OK
2018-12-07T23:26:52.309Z,1544225212.309 [CTD_Seabird](INFO): Powering up
2018-12-07T23:26:52.309Z,1544225212.309 [Rowe_600LCM](INFO): LCM OK
2018-12-07T23:26:52.310Z,1544225212.310 [Rowe_600LCM](INFO): Powering up
2018-12-07T23:26:52.329Z,1544225212.329 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-12-07T23:26:52.331Z,1544225212.331 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-12-07T23:26:52.368Z,1544225212.368 [Default:E.Execute](DEBUG): Construct Execute.
2018-12-07T23:26:52.372Z,1544225212.372 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <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">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-12-07T23:26:52.390Z,1544225212.390 [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-07T23:26:52.413Z,1544225212.413 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-12-07T23:26:52.413Z,1544225212.413 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-12-07T23:26:52.439Z,1544225212.439 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-12-07T23:26:52.811Z,1544225212.811 [ESPComponent](INFO): powering down ESP
2018-12-07T23:26:53.445Z,1544225213.445 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-07T23:26:53.453Z,1544225213.453 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-07T23:26:53.467Z,1544225213.467 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-12-07T23:26:53.469Z,1544225213.469 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-12-07T23:26:53.491Z,1544225213.491 [MassServo](DEBUG): Initializing EZServoServo.
2018-12-07T23:26:53.498Z,1544225213.498 [MassServo](DEBUG): Initializing MassServo.
2018-12-07T23:26:53.517Z,1544225213.517 [RudderServo](DEBUG): Initializing EZServoServo.
2018-12-07T23:26:53.526Z,1544225213.526 [RudderServo](DEBUG): Initializing RudderServo.
2018-12-07T23:26:53.558Z,1544225213.558 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-12-07T23:26:53.561Z,1544225213.561 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-12-07T23:26:53.791Z,1544225213.791 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-12-07T23:26:53.791Z,1544225213.791 [DropWeight] Hardware Fault, FailCount= 1
2018-12-07T23:26:53.791Z,1544225213.791 [DropWeight](ERROR): Hardware Fault
2018-12-07T23:26:53.951Z,1544225213.951 [CommandLine](FAULT): Scheduling is paused
2018-12-07T23:26:53.951Z,1544225213.951 [CBIT](INFO): Critical error at 20181207T232653
2018-12-07T23:26:53.951Z,1544225213.951 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-12-07T23:26:53.954Z,1544225213.954 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-12-07T23:26:53.954Z,1544225213.954 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-12-07T23:26:55.811Z,1544225215.811 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-07T23:26:55.972Z,1544225215.972 [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-07T23:26:56.119Z,1544225216.119 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-07T23:26:56.120Z,1544225216.120 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-07T23:26:56.120Z,1544225216.120 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-07T23:26:57.031Z,1544225217.031 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-12-07T23:26:57.184Z,1544225217.184 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-12-07T23:26:57.185Z,1544225217.185 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-12-07T23:26:57.227Z,1544225217.227 [Radio_Surface](INFO): Powering down
2018-12-07T23:26:57.585Z,1544225217.585 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-12-07T23:26:57.585Z,1544225217.585 [CTD_Seabird](INFO): Powering down
2018-12-07T23:27:03.521Z,1544225223.521 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-07T23:27:03.521Z,1544225223.521 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-12-07T23:27:03.521Z,1544225223.521 [Aanderaa_O2](ERROR): Communications Fault
2018-12-07T23:27:03.630Z,1544225223.630 [CommandLine](IMPORTANT): got command quit
2018-12-07T23:27:03.754Z,1544225223.754 [Radio_Surface](INFO): Powering up
2018-12-07T23:27:03.899Z,1544225223.899 [CBIT](INFO): Critical error at 20181207T232653
2018-12-07T23:27:03.902Z,1544225223.902 [CBIT](CRITICAL): Environmental Failure. Press:14.818675 PSI. Humidity:39%. Temp:26 C. ABORTING MISSION
2018-12-07T23:27:03.902Z,1544225223.902 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-07T23:27:03.909Z,1544225223.909 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-12-07T23:27:03.911Z,1544225223.911 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-12-07T23:27:03.912Z,1544225223.912 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-12-07T23:27:03.912Z,1544225223.912 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-12-07T23:27:03.913Z,1544225223.913 [AcousticModem_Benthos_ATM900](DEBUG): Nov 11 2018 08:18:22 
2018-12-07T23:27:03.914Z,1544225223.914 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-12-07T23:27:03.961Z,1544225223.961 [Aanderaa_O2](INFO): Powering down
2018-12-07T23:27:04.066Z,1544225224.066 [CBIT](INFO): Critical error at 20181207T232703
2018-12-07T23:27:04.636Z,1544225224.636 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-07T23:27:04.636Z,1544225224.636 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:04.710Z,1544225224.710 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-12-07T23:27:04.715Z,1544225224.715 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003908
2018-12-07T23:27:04.805Z,1544225224.805 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-12-07T23:27:04.805Z,1544225224.805 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:04.806Z,1544225224.806 [CommandLine](INFO): Join timeout helper Thread ID is 914
2018-12-07T23:27:04.806Z,1544225224.806 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-12-07T23:27:04.807Z,1544225224.807 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:04.807Z,1544225224.807 [NavChartDb](INFO): Join timeout helper Thread ID is 915
2018-12-07T23:27:04.819Z,1544225224.819 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-07T23:27:04.819Z,1544225224.819 [Aanderaa_O2] No Fault, FailCount= 1
2018-12-07T23:27:05.110Z,1544225225.110 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-12-07T23:27:05.110Z,1544225225.110 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-12-07T23:27:05.111Z,1544225225.111 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-12-07T23:27:05.510Z,1544225225.510 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-12-07T23:27:05.910Z,1544225225.910 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-12-07T23:27:05.911Z,1544225225.911 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-12-07T23:27:06.310Z,1544225226.310 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-12-07T23:27:09.412Z,1544225229.412 [Radio_Surface](INFO): Powering down
2018-12-07T23:27:09.529Z,1544225229.529 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-07T23:27:09.529Z,1544225229.529 [PNI_TCM] Data Fault, FailCount= 1
2018-12-07T23:27:09.529Z,1544225229.529 [PNI_TCM](ERROR): Data Fault
2018-12-07T23:27:09.646Z,1544225229.646 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-07T23:27:09.929Z,1544225229.929 [PNI_TCM](INFO): Powering down
2018-12-07T23:27:11.197Z,1544225231.197 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-07T23:27:11.197Z,1544225231.197 [PNI_TCM] No Fault, FailCount= 1
2018-12-07T23:27:11.750Z,1544225231.750 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-07T23:27:11.750Z,1544225231.750 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:11.761Z,1544225231.761 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-12-07T23:27:11.761Z,1544225231.761 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:11.762Z,1544225231.762 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 916
2018-12-07T23:27:11.865Z,1544225231.865 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-07T23:27:11.865Z,1544225231.865 [WetLabsBB2FL](INFO): Powering down
2018-12-07T23:27:11.866Z,1544225231.866 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:11.873Z,1544225231.873 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-12-07T23:27:11.873Z,1544225231.873 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:11.874Z,1544225231.874 [CTD_Seabird](INFO): Join timeout helper Thread ID is 917
2018-12-07T23:27:12.149Z,1544225232.149 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-07T23:27:12.329Z,1544225232.329 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-12-07T23:27:12.329Z,1544225232.329 [CTD_Seabird](INFO): Powering down
2018-12-07T23:27:12.330Z,1544225232.330 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:12.341Z,1544225232.341 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-12-07T23:27:12.341Z,1544225232.341 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:12.342Z,1544225232.342 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 919
2018-12-07T23:27:12.461Z,1544225232.461 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-07T23:27:12.462Z,1544225232.462 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-07T23:27:12.611Z,1544225232.611 [Rowe_600LCM](INFO): Powering down
2018-12-07T23:27:12.612Z,1544225232.612 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:12.630Z,1544225232.630 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-12-07T23:27:12.630Z,1544225232.630 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:12.631Z,1544225232.631 [Radio_Surface](INFO): Join timeout helper Thread ID is 921
2018-12-07T23:27:12.641Z,1544225232.641 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-07T23:27:12.642Z,1544225232.642 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:12.643Z,1544225232.643 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-12-07T23:27:12.644Z,1544225232.644 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:12.644Z,1544225232.644 [logger](INFO): Join timeout helper Thread ID is 922
2018-12-07T23:27:12.668Z,1544225232.668 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-07T23:27:12.669Z,1544225232.669 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:12.678Z,1544225232.678 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-12-07T23:27:12.678Z,1544225232.678 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:12.678Z,1544225232.678 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-12-07T23:27:12.678Z,1544225232.678 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:12.679Z,1544225232.679 [controlThread](INFO): Join timeout helper Thread ID is 923
2018-12-07T23:27:12.705Z,1544225232.705 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-07T23:27:12.706Z,1544225232.706 [controlThread](DEBUG): Uninitializing ControlThread
2018-12-07T23:27:12.707Z,1544225232.707 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-12-07T23:27:12.826Z,1544225232.826 [NAL9602](INFO): Powering down
2018-12-07T23:27:12.848Z,1544225232.848 [PNI_TCM](INFO): Powering down
2018-12-07T23:27:13.057Z,1544225233.057 [Aanderaa_O2](INFO): Powering down
2018-12-07T23:27:13.060Z,1544225233.060 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-12-07T23:27:13.061Z,1544225233.061 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-12-07T23:27:13.062Z,1544225233.062 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-12-07T23:27:13.062Z,1544225233.062 [MissionManager](INFO): Uninitializing Mission Default
2018-12-07T23:27:13.066Z,1544225233.066 [MissionManager](INFO): Uninitializing Mission Startup
2018-12-07T23:27:13.067Z,1544225233.067 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-12-07T23:27:13.067Z,1544225233.067 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-12-07T23:27:13.068Z,1544225233.068 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-12-07T23:27:13.068Z,1544225233.068 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-12-07T23:27:13.068Z,1544225233.068 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-12-07T23:27:13.069Z,1544225233.069 [BuoyancyServo](INFO): Powering down
2018-12-07T23:27:13.081Z,1544225233.081 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-12-07T23:27:13.082Z,1544225233.082 [ElevatorServo](INFO): Powering down
2018-12-07T23:27:13.104Z,1544225233.104 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-12-07T23:27:13.104Z,1544225233.104 [MassServo](INFO): Powering down
2018-12-07T23:27:13.105Z,1544225233.105 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-12-07T23:27:13.105Z,1544225233.105 [RudderServo](INFO): Powering down
2018-12-07T23:27:13.106Z,1544225233.106 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-12-07T23:27:13.107Z,1544225233.107 [ThrusterServo](INFO): Powering down
2018-12-07T23:27:13.107Z,1544225233.107 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-12-07T23:27:13.108Z,1544225233.108 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-12-07T23:27:13.108Z,1544225233.108 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-12-07T23:27:13.108Z,1544225233.108 [CBIT](DEBUG): Powering off loads.
2018-12-07T23:27:13.121Z,1544225233.121 [CBIT](DEBUG): Disabling WDT.
2018-12-07T23:27:13.133Z,1544225233.133 [CBIT](DEBUG): Opening all GF detection circuits.
2018-12-07T23:27:13.134Z,1544225233.134 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:13.232Z,1544225233.232 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:13.247Z,1544225233.247 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:13.291Z,1544225233.291 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:13.295Z,1544225233.295 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:13.469Z,1544225233.469 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-07T23:27:13.534Z,1544225233.534 [logger ThreadHandler](INFO): Thread cancelled.