2018-11-06T06:11:14.854Z,1541484674.854 [Supervisor](DEBUG): Initializing supervisor.
2018-11-06T06:11:14.857Z,1541484674.857 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-06T06:11:14.857Z,1541484674.857 [SyncHandler](INFO): Protected caller Thread ID is 3512
2018-11-06T06:11:14.858Z,1541484674.858 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-06T06:11:14.859Z,1541484674.859 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-06T06:11:14.859Z,1541484674.859 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3513
2018-11-06T06:11:14.862Z,1541484674.862 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-06T06:11:14.873Z,1541484674.873 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-06T06:11:14.874Z,1541484674.874 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-06T06:11:14.875Z,1541484674.875 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3514
2018-11-06T06:11:14.876Z,1541484674.876 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-06T06:11:14.877Z,1541484674.877 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-06T06:11:14.877Z,1541484674.877 [logger ThreadHandler](INFO): Protected caller Thread ID is 3515
2018-11-06T06:11:14.879Z,1541484674.879 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-06T06:11:14.879Z,1541484674.879 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-06T06:11:14.881Z,1541484674.881 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-06T06:11:14.978Z,1541484674.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-06T06:11:14.978Z,1541484674.978 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-06T06:11:15.080Z,1541484675.080 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-06T06:11:15.081Z,1541484675.081 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-06T06:11:15.277Z,1541484675.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-06T06:11:15.278Z,1541484675.278 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-06T06:11:15.607Z,1541484675.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-06T06:11:15.608Z,1541484675.608 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-06T06:11:15.939Z,1541484675.939 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-06T06:11:15.940Z,1541484675.940 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-06T06:11:16.396Z,1541484676.396 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-06T06:11:16.396Z,1541484676.396 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-06T06:11:16.855Z,1541484676.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-06T06:11:16.855Z,1541484676.855 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-06T06:11:17.302Z,1541484677.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-06T06:11:17.303Z,1541484677.303 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-06T06:11:17.382Z,1541484677.382 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-06T06:11:17.698Z,1541484677.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-06T06:11:17.699Z,1541484677.699 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-06T06:11:17.847Z,1541484677.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-06T06:11:17.847Z,1541484677.847 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-06T06:11:18.061Z,1541484678.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-06T06:11:18.061Z,1541484678.061 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-06T06:11:18.266Z,1541484678.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-06T06:11:18.267Z,1541484678.267 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-06T06:11:18.364Z,1541484678.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-06T06:11:18.365Z,1541484678.365 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-06T06:11:18.510Z,1541484678.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-06T06:11:18.511Z,1541484678.511 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-11-06T06:11:18.512Z,1541484678.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-11-06T06:11:18.739Z,1541484678.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-06T06:11:18.740Z,1541484678.740 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-11-06T06:11:18.844Z,1541484678.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-11-06T06:11:19.002Z,1541484679.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-11-06T06:11:19.259Z,1541484679.259 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-11-06T06:11:19.385Z,1541484679.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-11-06T06:11:19.731Z,1541484679.731 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-11-06T06:11:20.015Z,1541484680.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-11-06T06:11:20.113Z,1541484680.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-11-06T06:11:20.208Z,1541484680.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-11-06T06:11:20.305Z,1541484680.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-11-06T06:11:20.388Z,1541484680.388 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-11-06T06:11:20.388Z,1541484680.388 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-06T06:11:20.391Z,1541484680.391 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-06T06:11:20.491Z,1541484680.491 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-06T06:11:20.492Z,1541484680.492 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-06T06:11:20.505Z,1541484680.505 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-06T06:11:20.505Z,1541484680.505 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-06T06:11:20.547Z,1541484680.547 [DepthRateCalculator] Loaded
2018-11-06T06:11:20.547Z,1541484680.547 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-06T06:11:20.553Z,1541484680.553 [PitchRateCalculator] Loaded
2018-11-06T06:11:20.553Z,1541484680.553 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-06T06:11:20.569Z,1541484680.569 [SpeedCalculator] Loaded
2018-11-06T06:11:20.570Z,1541484680.570 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-06T06:11:20.591Z,1541484680.591 [TempGradientCalculator] Loaded
2018-11-06T06:11:20.591Z,1541484680.591 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-06T06:11:20.596Z,1541484680.596 [YawRateCalculator] Loaded
2018-11-06T06:11:20.597Z,1541484680.597 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-06T06:11:20.636Z,1541484680.636 [ElevatorOffsetCalculator] Loaded
2018-11-06T06:11:20.636Z,1541484680.636 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-06T06:11:20.637Z,1541484680.637 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-06T06:11:20.637Z,1541484680.637 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-06T06:11:20.662Z,1541484680.662 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-06T06:11:20.663Z,1541484680.663 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-06T06:11:20.940Z,1541484680.940 [AcousticModem_Benthos_ATM900] Loaded
2018-11-06T06:11:20.940Z,1541484680.940 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-11-06T06:11:21.010Z,1541484681.010 [DataOverHttps] Loaded
2018-11-06T06:11:21.010Z,1541484681.010 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-06T06:11:21.023Z,1541484681.023 [Depth_Keller] Loaded
2018-11-06T06:11:21.024Z,1541484681.024 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-06T06:11:21.028Z,1541484681.028 [DropWeight] Loaded
2018-11-06T06:11:21.029Z,1541484681.029 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-06T06:11:21.126Z,1541484681.126 [NAL9602] Loaded
2018-11-06T06:11:21.126Z,1541484681.126 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-06T06:11:21.142Z,1541484681.142 [Onboard] Loaded
2018-11-06T06:11:21.142Z,1541484681.142 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-06T06:11:21.152Z,1541484681.152 [Radio_Surface] Loaded
2018-11-06T06:11:21.152Z,1541484681.152 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-06T06:11:21.153Z,1541484681.153 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0
2018-11-06T06:11:21.154Z,1541484681.154 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3594
2018-11-06T06:11:21.200Z,1541484681.200 [PNI_TCM] Loaded
2018-11-06T06:11:21.201Z,1541484681.201 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-11-06T06:11:21.262Z,1541484681.262 [Rowe_600LCM] Loaded
2018-11-06T06:11:21.262Z,1541484681.262 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-11-06T06:11:21.263Z,1541484681.263 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0
2018-11-06T06:11:21.263Z,1541484681.263 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 3595
2018-11-06T06:11:23.026Z,1541484683.026 [BPC1] Loaded
2018-11-06T06:11:23.026Z,1541484683.026 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-06T06:11:23.026Z,1541484683.026 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-06T06:11:23.027Z,1541484683.027 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-06T06:11:23.158Z,1541484683.158 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-06T06:11:23.181Z,1541484683.181 [SBIT] Loaded
2018-11-06T06:11:23.181Z,1541484683.181 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-06T06:11:23.182Z,1541484683.182 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-06T06:11:23.193Z,1541484683.193 [IBIT] Loaded
2018-11-06T06:11:23.194Z,1541484683.194 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-06T06:11:23.197Z,1541484683.197 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-06T06:11:23.344Z,1541484683.344 [CBIT] Loaded
2018-11-06T06:11:23.345Z,1541484683.345 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-06T06:11:23.345Z,1541484683.345 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-06T06:11:23.346Z,1541484683.346 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-06T06:11:23.505Z,1541484683.505 [Aanderaa_O2] Loaded
2018-11-06T06:11:23.505Z,1541484683.505 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-11-06T06:11:23.562Z,1541484683.562 [CTD_Seabird] Loaded
2018-11-06T06:11:23.562Z,1541484683.562 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-11-06T06:11:23.563Z,1541484683.563 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0
2018-11-06T06:11:23.563Z,1541484683.563 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3596
2018-11-06T06:11:23.581Z,1541484683.581 [ESPComponent] Loaded
2018-11-06T06:11:23.581Z,1541484683.581 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-11-06T06:11:23.595Z,1541484683.595 [PAR_Licor] Loaded
2018-11-06T06:11:23.596Z,1541484683.596 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-11-06T06:11:23.646Z,1541484683.646 [WetLabsBB2FL] Loaded
2018-11-06T06:11:23.646Z,1541484683.646 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-06T06:11:23.647Z,1541484683.647 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2018-11-06T06:11:23.647Z,1541484683.647 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3597
2018-11-06T06:11:23.648Z,1541484683.648 [Module Loader](CRITICAL): Could not find element WetLabsUBAT.loadAtStartup
2018-11-06T06:11:23.648Z,1541484683.648 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-06T06:11:23.648Z,1541484683.648 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-06T06:11:23.892Z,1541484683.892 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-06T06:11:23.893Z,1541484683.893 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-06T06:11:23.963Z,1541484683.963 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-06T06:11:24.058Z,1541484684.058 [VerticalControl] Loaded
2018-11-06T06:11:24.059Z,1541484684.059 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-06T06:11:24.059Z,1541484684.059 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-06T06:11:24.120Z,1541484684.120 [HorizontalControl] Loaded
2018-11-06T06:11:24.120Z,1541484684.120 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-06T06:11:24.121Z,1541484684.121 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-06T06:11:24.126Z,1541484684.126 [SpeedControl] Loaded
2018-11-06T06:11:24.126Z,1541484684.126 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-06T06:11:24.127Z,1541484684.127 [LoopControl](DEBUG): Construct LoopControl.
2018-11-06T06:11:24.127Z,1541484684.127 [LoopControl] Loaded
2018-11-06T06:11:24.128Z,1541484684.128 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-06T06:11:24.128Z,1541484684.128 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-06T06:11:24.129Z,1541484684.129 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-06T06:11:24.364Z,1541484684.364 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-06T06:11:24.364Z,1541484684.364 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-06T06:11:24.508Z,1541484684.508 [BuoyancyServo] Loaded
2018-11-06T06:11:24.508Z,1541484684.508 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-06T06:11:24.520Z,1541484684.520 [ElevatorServo] Loaded
2018-11-06T06:11:24.520Z,1541484684.520 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-06T06:11:24.531Z,1541484684.531 [MassServo] Loaded
2018-11-06T06:11:24.531Z,1541484684.531 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-06T06:11:24.542Z,1541484684.542 [RudderServo] Loaded
2018-11-06T06:11:24.543Z,1541484684.543 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-06T06:11:24.554Z,1541484684.554 [ThrusterServo] Loaded
2018-11-06T06:11:24.554Z,1541484684.554 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-06T06:11:24.554Z,1541484684.554 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-06T06:11:24.555Z,1541484684.555 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-06T06:11:24.646Z,1541484684.646 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-06T06:11:24.646Z,1541484684.646 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-06T06:11:24.662Z,1541484684.662 [NavChart] Loaded
2018-11-06T06:11:24.662Z,1541484684.662 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-06T06:11:24.666Z,1541484684.666 [UniversalFixResidualReporter] Loaded
2018-11-06T06:11:24.667Z,1541484684.667 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-06T06:11:24.667Z,1541484684.667 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-06T06:11:24.671Z,1541484684.671 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-06T06:11:24.672Z,1541484684.672 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-06T06:11:24.678Z,1541484684.678 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-06T06:11:24.679Z,1541484684.679 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2018-11-06T06:11:24.680Z,1541484684.680 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3598
2018-11-06T06:11:24.684Z,1541484684.684 [Supervisor](INFO): Main Thread ID is 2717
2018-11-06T06:11:24.684Z,1541484684.684 [Supervisor](DEBUG): Running supervisor.
2018-11-06T06:11:24.685Z,1541484684.685 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3599
2018-11-06T06:11:24.687Z,1541484684.687 [controlThread ThreadHandler](INFO): Handler Thread ID is 3600
2018-11-06T06:11:24.688Z,1541484684.688 [controlThread](DEBUG): Initializing ControlThread
2018-11-06T06:11:24.689Z,1541484684.689 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T06:11:24.689Z,1541484684.689 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-06T06:11:24.689Z,1541484684.689 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-06T06:11:24.690Z,1541484684.690 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-06T06:11:24.690Z,1541484684.690 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-06T06:11:24.691Z,1541484684.691 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-06T06:11:24.695Z,1541484684.695 [SBIT](INFO): Initialize SBIT Component.
2018-11-06T06:11:24.696Z,1541484684.696 [SBIT](IMPORTANT): git: 2018-10-09-16-g0b73f69
2018-11-06T06:11:24.696Z,1541484684.696 [SBIT](INFO): git hash: 0b73f69de94197fb1a4de5b5ddcbfb64d4e9bd0d
2018-11-06T06:11:24.696Z,1541484684.696 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-06T06:11:24.697Z,1541484684.697 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-06T06:11:24.698Z,1541484684.698 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-11-06T06:11:24.698Z,1541484684.698 [IBIT](INFO): Initialize IBIT Component.
2018-11-06T06:11:24.699Z,1541484684.699 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-06T06:11:24.700Z,1541484684.700 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-06T06:11:24.700Z,1541484684.700 [logger ThreadHandler](INFO): Handler Thread ID is 3601
2018-11-06T06:11:24.718Z,1541484684.718 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3602
2018-11-06T06:11:24.724Z,1541484684.724 [Radio_Surface](INFO): Powering up
2018-11-06T06:11:24.730Z,1541484684.730 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 3603
2018-11-06T06:11:24.730Z,1541484684.730 [Rowe_600LCM](INFO): Initializing
2018-11-06T06:11:24.731Z,1541484684.731 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T06:11:24.740Z,1541484684.740 [Rowe_600LCM](INFO): LCM OK
2018-11-06T06:11:24.740Z,1541484684.740 [Rowe_600LCM](INFO): Powering up
2018-11-06T06:11:24.746Z,1541484684.746 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3604
2018-11-06T06:11:24.746Z,1541484684.746 [CTD_Seabird](INFO): Initializing
2018-11-06T06:11:24.747Z,1541484684.747 [CTD_Seabird](INFO): Checking LCM
2018-11-06T06:11:24.747Z,1541484684.747 [CTD_Seabird](INFO): LCM OK
2018-11-06T06:11:24.747Z,1541484684.747 [CTD_Seabird](INFO): Powering up
2018-11-06T06:11:24.754Z,1541484684.754 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3605
2018-11-06T06:11:24.755Z,1541484684.755 [WetLabsBB2FL](INFO): Powering down
2018-11-06T06:11:24.782Z,1541484684.782 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3606
2018-11-06T06:11:24.785Z,1541484684.785 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-06T06:11:24.785Z,1541484684.785 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-06T06:11:24.785Z,1541484684.785 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-06T06:11:24.786Z,1541484684.786 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-06T06:11:24.786Z,1541484684.786 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-06T06:11:24.786Z,1541484684.786 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-06T06:11:24.786Z,1541484684.786 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-06T06:11:24.786Z,1541484684.786 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-06T06:11:24.787Z,1541484684.787 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-06T06:11:24.787Z,1541484684.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-06T06:11:24.787Z,1541484684.787 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-06T06:11:24.787Z,1541484684.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-06T06:11:24.787Z,1541484684.787 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-06T06:11:24.787Z,1541484684.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-06T06:11:24.788Z,1541484684.788 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-06T06:11:24.788Z,1541484684.788 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-06T06:11:24.809Z,1541484684.809 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-06T06:11:24.811Z,1541484684.811 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-06T06:11:24.812Z,1541484684.812 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-06T06:11:24.812Z,1541484684.812 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-06T06:11:24.814Z,1541484684.814 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T06:11:24.815Z,1541484684.815 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T06:11:24.815Z,1541484684.815 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T06:11:24.816Z,1541484684.816 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-06T06:11:24.816Z,1541484684.816 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-06T06:11:24.817Z,1541484684.817 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-06T06:11:24.849Z,1541484684.849 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T06:11:24.868Z,1541484684.868 [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-11-06T06:11:24.885Z,1541484684.885 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-06T06:11:24.959Z,1541484684.959 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-06T06:11:24.960Z,1541484684.960 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-06T06:11:24.961Z,1541484684.961 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T06:11:25.004Z,1541484685.004 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-06T06:11:25.007Z,1541484685.007 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-06T06:11:25.020Z,1541484685.020 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-06T06:11:25.024Z,1541484685.024 [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-11-06T06:11:25.038Z,1541484685.038 [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-11-06T06:11:25.064Z,1541484685.064 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-11-06T06:11:25.065Z,1541484685.065 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-11-06T06:11:25.103Z,1541484685.103 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-06T06:11:25.391Z,1541484685.391 [ESPComponent](INFO): powering down ESP
2018-11-06T06:11:25.829Z,1541484685.829 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:25.945Z,1541484685.945 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:11:25.954Z,1541484685.954 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-06T06:11:25.969Z,1541484685.969 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:11:25.977Z,1541484685.977 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-06T06:11:25.995Z,1541484685.995 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:11:26.001Z,1541484686.001 [MassServo](DEBUG): Initializing MassServo.
2018-11-06T06:11:26.015Z,1541484686.015 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:11:26.021Z,1541484686.021 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-06T06:11:26.027Z,1541484686.027 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:11:26.033Z,1541484686.033 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-06T06:11:26.071Z,1541484686.071 [CommandLine](FAULT): Scheduling is paused
2018-11-06T06:11:26.072Z,1541484686.072 [CBIT](INFO): Critical error at 20181106T061123
2018-11-06T06:11:26.072Z,1541484686.072 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-06T06:11:26.206Z,1541484686.206 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-06T06:11:26.206Z,1541484686.206 [DropWeight] Hardware Fault, FailCount= 1
2018-11-06T06:11:26.207Z,1541484686.207 [DropWeight](ERROR): Hardware Fault
2018-11-06T06:11:26.263Z,1541484686.263 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:26.294Z,1541484686.294 [CBIT](INFO): Critical error at 20181106T061126
2018-11-06T06:11:26.296Z,1541484686.296 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-06T06:11:26.297Z,1541484686.297 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-06T06:11:26.596Z,1541484686.596 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:26.991Z,1541484686.991 [CBIT](INFO): Critical error at 20181106T061126
2018-11-06T06:11:27.084Z,1541484687.084 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:27.448Z,1541484687.448 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:27.843Z,1541484687.843 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:28.252Z,1541484688.252 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:28.672Z,1541484688.672 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:29.039Z,1541484689.039 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T06:11:29.074Z,1541484689.074 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:29.168Z,1541484689.168 [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-11-06T06:11:29.243Z,1541484689.243 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T06:11:29.244Z,1541484689.244 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T06:11:29.261Z,1541484689.261 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T06:11:29.459Z,1541484689.459 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:29.526Z,1541484689.526 [CommandLine](IMPORTANT): got command restart application
2018-11-06T06:11:29.853Z,1541484689.853 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:30.238Z,1541484690.238 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T06:11:30.288Z,1541484690.288 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:30.360Z,1541484690.360 [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-11-06T06:11:30.361Z,1541484690.361 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-11-06T06:11:30.532Z,1541484690.532 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:11:30.533Z,1541484690.533 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:30.549Z,1541484690.549 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-06T06:11:30.549Z,1541484690.549 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:30.550Z,1541484690.550 [CommandLine](INFO): Join timeout helper Thread ID is 3625
2018-11-06T06:11:30.577Z,1541484690.577 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-06T06:11:30.577Z,1541484690.577 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:30.578Z,1541484690.578 [NavChartDb](INFO): Join timeout helper Thread ID is 3626
2018-11-06T06:11:30.642Z,1541484690.642 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:30.769Z,1541484690.769 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T06:11:30.769Z,1541484690.769 [CTD_Seabird](INFO): Powering down
2018-11-06T06:11:31.061Z,1541484691.061 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:31.454Z,1541484691.454 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:31.854Z,1541484691.854 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:32.153Z,1541484692.153 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:11:32.153Z,1541484692.153 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:32.173Z,1541484692.173 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-06T06:11:32.173Z,1541484692.173 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:32.174Z,1541484692.174 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3627
2018-11-06T06:11:32.245Z,1541484692.245 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:32.389Z,1541484692.389 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:11:32.390Z,1541484692.390 [WetLabsBB2FL](INFO): Powering down
2018-11-06T06:11:32.391Z,1541484692.391 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:32.405Z,1541484692.405 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-11-06T06:11:32.405Z,1541484692.405 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:32.406Z,1541484692.406 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3628
2018-11-06T06:11:32.602Z,1541484692.602 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-11-06T06:11:32.623Z,1541484692.623 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T06:11:32.623Z,1541484692.623 [PNI_TCM] Data Fault, FailCount= 1
2018-11-06T06:11:32.623Z,1541484692.623 [PNI_TCM](ERROR): Data Fault
2018-11-06T06:11:32.634Z,1541484692.634 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:32.694Z,1541484692.694 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T06:11:32.805Z,1541484692.805 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:11:32.964Z,1541484692.964 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T06:11:32.964Z,1541484692.964 [CTD_Seabird](INFO): Powering down
2018-11-06T06:11:32.966Z,1541484692.966 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:32.977Z,1541484692.977 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-11-06T06:11:32.977Z,1541484692.977 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:32.978Z,1541484692.978 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 3630
2018-11-06T06:11:33.031Z,1541484693.031 [PNI_TCM](INFO): Powering down
2018-11-06T06:11:33.085Z,1541484693.085 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:33.461Z,1541484693.461 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:33.662Z,1541484693.662 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:11:33.662Z,1541484693.662 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T06:11:33.804Z,1541484693.804 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-11-06T06:11:33.805Z,1541484693.805 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-11-06T06:11:33.806Z,1541484693.806 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-11-06T06:11:33.806Z,1541484693.806 [AcousticModem_Benthos_ATM900](DEBUG): Oct 25 2018 06:54:12 
2018-11-06T06:11:33.858Z,1541484693.858 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T06:11:33.906Z,1541484693.906 [Rowe_600LCM](INFO): Powering down
2018-11-06T06:11:33.907Z,1541484693.907 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:33.910Z,1541484693.910 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-06T06:11:33.910Z,1541484693.910 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:33.911Z,1541484693.911 [Radio_Surface](INFO): Join timeout helper Thread ID is 3632
2018-11-06T06:11:34.105Z,1541484694.105 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:11:34.106Z,1541484694.106 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.126Z,1541484694.126 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-06T06:11:34.126Z,1541484694.126 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.126Z,1541484694.126 [logger](INFO): Join timeout helper Thread ID is 3633
2018-11-06T06:11:34.167Z,1541484694.167 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:11:34.167Z,1541484694.167 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.178Z,1541484694.178 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-06T06:11:34.178Z,1541484694.178 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.178Z,1541484694.178 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-06T06:11:34.178Z,1541484694.178 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.179Z,1541484694.179 [controlThread](INFO): Join timeout helper Thread ID is 3634
2018-11-06T06:11:34.197Z,1541484694.197 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:11:34.198Z,1541484694.198 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-06T06:11:34.198Z,1541484694.198 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-11-06T06:11:34.346Z,1541484694.346 [NAL9602](INFO): Powering down
2018-11-06T06:11:34.367Z,1541484694.367 [PNI_TCM](INFO): Powering down
2018-11-06T06:11:34.485Z,1541484694.485 [Aanderaa_O2](INFO): Powering down
2018-11-06T06:11:34.510Z,1541484694.510 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T06:11:34.511Z,1541484694.511 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-06T06:11:34.511Z,1541484694.511 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-06T06:11:34.512Z,1541484694.512 [MissionManager](INFO): Uninitializing Mission Default
2018-11-06T06:11:34.515Z,1541484694.515 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-06T06:11:34.516Z,1541484694.516 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-06T06:11:34.516Z,1541484694.516 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-06T06:11:34.516Z,1541484694.516 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-06T06:11:34.517Z,1541484694.517 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-06T06:11:34.517Z,1541484694.517 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-06T06:11:34.518Z,1541484694.518 [BuoyancyServo](INFO): Powering down
2018-11-06T06:11:34.529Z,1541484694.529 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-06T06:11:34.529Z,1541484694.529 [ElevatorServo](INFO): Powering down
2018-11-06T06:11:34.549Z,1541484694.549 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-06T06:11:34.549Z,1541484694.549 [MassServo](INFO): Powering down
2018-11-06T06:11:34.550Z,1541484694.550 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-06T06:11:34.550Z,1541484694.550 [RudderServo](INFO): Powering down
2018-11-06T06:11:34.551Z,1541484694.551 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-06T06:11:34.551Z,1541484694.551 [ThrusterServo](INFO): Powering down
2018-11-06T06:11:34.552Z,1541484694.552 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-06T06:11:34.552Z,1541484694.552 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-06T06:11:34.552Z,1541484694.552 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-06T06:11:34.554Z,1541484694.554 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.653Z,1541484694.653 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.663Z,1541484694.663 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.711Z,1541484694.711 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.715Z,1541484694.715 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.880Z,1541484694.880 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:11:34.936Z,1541484694.936 [logger ThreadHandler](INFO): Thread cancelled.