2019-03-06T22:51:30.043Z,1551912690.043 [Supervisor](DEBUG): Initializing supervisor.
2019-03-06T22:51:30.046Z,1551912690.046 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-06T22:51:30.046Z,1551912690.046 [SyncHandler](INFO): Protected caller Thread ID is 7659
2019-03-06T22:51:30.047Z,1551912690.047 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-06T22:51:30.048Z,1551912690.048 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-06T22:51:30.048Z,1551912690.048 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7660
2019-03-06T22:51:30.051Z,1551912690.051 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-06T22:51:30.062Z,1551912690.062 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-06T22:51:30.063Z,1551912690.063 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-06T22:51:30.063Z,1551912690.063 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7661
2019-03-06T22:51:30.064Z,1551912690.064 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-06T22:51:30.065Z,1551912690.065 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-06T22:51:30.066Z,1551912690.066 [logger ThreadHandler](INFO): Protected caller Thread ID is 7662
2019-03-06T22:51:30.068Z,1551912690.068 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-06T22:51:30.068Z,1551912690.068 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-06T22:51:30.070Z,1551912690.070 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-06T22:51:30.502Z,1551912690.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-06T22:51:30.503Z,1551912690.503 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-06T22:51:30.600Z,1551912690.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-06T22:51:30.601Z,1551912690.601 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-06T22:51:30.938Z,1551912690.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-06T22:51:30.939Z,1551912690.939 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-06T22:51:31.084Z,1551912691.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-06T22:51:31.084Z,1551912691.084 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-06T22:51:31.281Z,1551912691.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-06T22:51:31.282Z,1551912691.282 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-06T22:51:31.754Z,1551912691.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-06T22:51:31.755Z,1551912691.755 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-06T22:51:31.970Z,1551912691.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-06T22:51:31.971Z,1551912691.971 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-06T22:51:32.118Z,1551912692.118 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-06T22:51:32.119Z,1551912692.119 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-06T22:51:32.321Z,1551912692.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-06T22:51:32.321Z,1551912692.321 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-06T22:51:32.417Z,1551912692.417 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-06T22:51:32.417Z,1551912692.417 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-06T22:51:32.737Z,1551912692.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-06T22:51:32.737Z,1551912692.737 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-06T22:51:32.816Z,1551912692.816 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-06T22:51:32.920Z,1551912692.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-06T22:51:32.921Z,1551912692.921 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-06T22:51:33.429Z,1551912693.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-06T22:51:33.430Z,1551912693.430 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-06T22:51:33.832Z,1551912693.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-06T22:51:33.834Z,1551912693.834 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-06T22:51:33.835Z,1551912693.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-06T22:51:34.048Z,1551912694.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-06T22:51:34.149Z,1551912694.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-06T22:51:34.248Z,1551912694.248 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-06T22:51:34.549Z,1551912694.549 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-06T22:51:34.550Z,1551912694.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-06T22:51:34.635Z,1551912694.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-06T22:51:34.728Z,1551912694.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-06T22:51:34.875Z,1551912694.875 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-06T22:51:35.130Z,1551912695.130 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-06T22:51:35.458Z,1551912695.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-06T22:51:35.712Z,1551912695.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-06T22:51:35.846Z,1551912695.846 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-03-06T22:51:35.847Z,1551912695.847 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-06T22:51:36.201Z,1551912696.201 [AHRS_M2] Loaded
2019-03-06T22:51:36.201Z,1551912696.201 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-03-06T22:51:36.275Z,1551912696.275 [DataOverHttps] Loaded
2019-03-06T22:51:36.275Z,1551912696.275 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-06T22:51:36.276Z,1551912696.276 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0
2019-03-06T22:51:36.277Z,1551912696.277 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7742
2019-03-06T22:51:36.290Z,1551912696.290 [Depth_Keller] Loaded
2019-03-06T22:51:36.290Z,1551912696.290 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-06T22:51:36.295Z,1551912696.295 [DropWeight] Loaded
2019-03-06T22:51:36.295Z,1551912696.295 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-06T22:51:36.343Z,1551912696.343 [DUSBL_Hydroid] Loaded
2019-03-06T22:51:36.343Z,1551912696.343 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-06T22:51:36.382Z,1551912696.382 [Micromodem] Loaded
2019-03-06T22:51:36.382Z,1551912696.382 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-06T22:51:36.479Z,1551912696.479 [NAL9602] Loaded
2019-03-06T22:51:36.480Z,1551912696.480 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-06T22:51:36.496Z,1551912696.496 [Onboard] Loaded
2019-03-06T22:51:36.496Z,1551912696.496 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-06T22:51:36.502Z,1551912696.502 [Radio_Surface] Loaded
2019-03-06T22:51:36.503Z,1551912696.503 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-06T22:51:36.504Z,1551912696.504 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0
2019-03-06T22:51:36.504Z,1551912696.504 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7743
2019-03-06T22:51:36.547Z,1551912696.547 [RDI_Pathfinder] Loaded
2019-03-06T22:51:36.548Z,1551912696.548 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-06T22:51:36.569Z,1551912696.569 [RDI_PathfinderUp] Loaded
2019-03-06T22:51:36.569Z,1551912696.569 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-06T22:51:38.697Z,1551912698.697 [BPC1] Loaded
2019-03-06T22:51:38.698Z,1551912698.698 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-06T22:51:38.698Z,1551912698.698 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-06T22:51:38.699Z,1551912698.699 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-06T22:51:38.712Z,1551912698.712 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-06T22:51:38.712Z,1551912698.712 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-06T22:51:38.816Z,1551912698.816 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-06T22:51:38.817Z,1551912698.817 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-06T22:51:38.876Z,1551912698.876 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-06T22:51:38.876Z,1551912698.876 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-06T22:51:38.935Z,1551912698.935 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-06T22:51:38.935Z,1551912698.935 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-06T22:51:38.955Z,1551912698.955 [NavChart] Loaded
2019-03-06T22:51:38.956Z,1551912698.956 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-06T22:51:38.960Z,1551912698.960 [UniversalFixResidualReporter] Loaded
2019-03-06T22:51:38.960Z,1551912698.960 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-06T22:51:38.960Z,1551912698.960 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-06T22:51:38.961Z,1551912698.961 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-06T22:51:39.061Z,1551912699.061 [BuoyancyServo] Loaded
2019-03-06T22:51:39.062Z,1551912699.062 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-06T22:51:39.077Z,1551912699.077 [ElevatorServo] Loaded
2019-03-06T22:51:39.077Z,1551912699.077 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-06T22:51:39.091Z,1551912699.091 [MassServo] Loaded
2019-03-06T22:51:39.092Z,1551912699.092 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-06T22:51:39.107Z,1551912699.107 [RudderServo] Loaded
2019-03-06T22:51:39.108Z,1551912699.108 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-06T22:51:39.122Z,1551912699.122 [ThrusterServo] Loaded
2019-03-06T22:51:39.122Z,1551912699.122 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-06T22:51:39.123Z,1551912699.123 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-06T22:51:39.123Z,1551912699.123 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-06T22:51:39.302Z,1551912699.302 [Aanderaa_O2] Loaded
2019-03-06T22:51:39.303Z,1551912699.303 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-03-06T22:51:39.386Z,1551912699.386 [CTD_NeilBrown] Loaded
2019-03-06T22:51:39.387Z,1551912699.387 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-06T22:51:39.388Z,1551912699.388 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2019-03-06T22:51:39.388Z,1551912699.388 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 7744
2019-03-06T22:51:39.433Z,1551912699.433 [WetLabsSeaOWL_UV_A] Loaded
2019-03-06T22:51:39.433Z,1551912699.433 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-06T22:51:39.434Z,1551912699.434 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2019-03-06T22:51:39.434Z,1551912699.434 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 7745
2019-03-06T22:51:39.435Z,1551912699.435 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-06T22:51:39.435Z,1551912699.435 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-06T22:51:39.670Z,1551912699.670 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-06T22:51:39.671Z,1551912699.671 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-06T22:51:39.711Z,1551912699.711 [DepthRateCalculator] Loaded
2019-03-06T22:51:39.711Z,1551912699.711 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-06T22:51:39.717Z,1551912699.717 [PitchRateCalculator] Loaded
2019-03-06T22:51:39.717Z,1551912699.717 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-06T22:51:39.729Z,1551912699.729 [SpeedCalculator] Loaded
2019-03-06T22:51:39.730Z,1551912699.730 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-06T22:51:39.750Z,1551912699.750 [TempGradientCalculator] Loaded
2019-03-06T22:51:39.751Z,1551912699.751 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-06T22:51:39.756Z,1551912699.756 [YawRateCalculator] Loaded
2019-03-06T22:51:39.756Z,1551912699.756 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-06T22:51:39.796Z,1551912699.796 [ElevatorOffsetCalculator] Loaded
2019-03-06T22:51:39.796Z,1551912699.796 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-06T22:51:39.797Z,1551912699.797 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-06T22:51:39.797Z,1551912699.797 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-06T22:51:39.929Z,1551912699.929 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-06T22:51:39.950Z,1551912699.950 [SBIT] Loaded
2019-03-06T22:51:39.951Z,1551912699.951 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-06T22:51:39.951Z,1551912699.951 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-06T22:51:39.963Z,1551912699.963 [IBIT] Loaded
2019-03-06T22:51:39.963Z,1551912699.963 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-06T22:51:39.966Z,1551912699.966 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-06T22:51:40.106Z,1551912700.106 [CBIT] Loaded
2019-03-06T22:51:40.106Z,1551912700.106 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-06T22:51:40.107Z,1551912700.107 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-06T22:51:40.107Z,1551912700.107 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-06T22:51:40.207Z,1551912700.207 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-06T22:51:40.207Z,1551912700.207 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-06T22:51:40.331Z,1551912700.331 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-06T22:51:40.332Z,1551912700.332 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-06T22:51:40.397Z,1551912700.397 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-06T22:51:40.481Z,1551912700.481 [VerticalControl] Loaded
2019-03-06T22:51:40.482Z,1551912700.482 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-06T22:51:40.483Z,1551912700.483 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-06T22:51:40.540Z,1551912700.540 [HorizontalControl] Loaded
2019-03-06T22:51:40.540Z,1551912700.540 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-06T22:51:40.541Z,1551912700.541 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-06T22:51:40.543Z,1551912700.543 [SpeedControl] Loaded
2019-03-06T22:51:40.543Z,1551912700.543 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-06T22:51:40.544Z,1551912700.544 [LoopControl](DEBUG): Construct LoopControl.
2019-03-06T22:51:40.544Z,1551912700.544 [LoopControl] Loaded
2019-03-06T22:51:40.544Z,1551912700.544 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-06T22:51:40.545Z,1551912700.545 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-06T22:51:40.545Z,1551912700.545 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-06T22:51:40.572Z,1551912700.572 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-06T22:51:40.575Z,1551912700.575 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-06T22:51:40.576Z,1551912700.576 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-06T22:51:40.583Z,1551912700.583 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-06T22:51:40.584Z,1551912700.584 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2019-03-06T22:51:40.585Z,1551912700.585 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7746
2019-03-06T22:51:40.589Z,1551912700.589 [Supervisor](INFO): Main Thread ID is 7658
2019-03-06T22:51:40.589Z,1551912700.589 [Supervisor](DEBUG): Running supervisor.
2019-03-06T22:51:40.590Z,1551912700.590 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7747
2019-03-06T22:51:40.592Z,1551912700.592 [controlThread ThreadHandler](INFO): Handler Thread ID is 7748
2019-03-06T22:51:40.593Z,1551912700.593 [controlThread](DEBUG): Initializing ControlThread
2019-03-06T22:51:40.601Z,1551912700.601 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T22:51:40.601Z,1551912700.601 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-06T22:51:40.602Z,1551912700.602 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-06T22:51:40.603Z,1551912700.603 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-06T22:51:40.603Z,1551912700.603 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-06T22:51:40.604Z,1551912700.604 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-06T22:51:40.604Z,1551912700.604 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-06T22:51:40.604Z,1551912700.604 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-06T22:51:40.605Z,1551912700.605 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-06T22:51:40.606Z,1551912700.606 [SBIT](INFO): Initialize SBIT Component.
2019-03-06T22:51:40.606Z,1551912700.606 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3
2019-03-06T22:51:40.607Z,1551912700.607 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa
2019-03-06T22:51:40.607Z,1551912700.607 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-06T22:51:40.607Z,1551912700.607 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-06T22:51:40.608Z,1551912700.608 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-06T22:51:40.609Z,1551912700.609 [IBIT](INFO): Initialize IBIT Component.
2019-03-06T22:51:40.610Z,1551912700.610 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-06T22:51:40.611Z,1551912700.611 [logger ThreadHandler](INFO): Handler Thread ID is 7749
2019-03-06T22:51:40.621Z,1551912700.621 [CBIT](DEBUG): Initialized mux pins.
2019-03-06T22:51:40.621Z,1551912700.621 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-03-06T22:51:40.621Z,1551912700.621 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-06T22:51:40.629Z,1551912700.629 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7750
2019-03-06T22:51:40.630Z,1551912700.630 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-06T22:51:40.641Z,1551912700.641 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7751
2019-03-06T22:51:40.645Z,1551912700.645 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-06T22:51:40.645Z,1551912700.645 [CBIT](DEBUG): Initializing heartbeat.
2019-03-06T22:51:40.653Z,1551912700.653 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 7752
2019-03-06T22:51:40.654Z,1551912700.654 [CTD_NeilBrown](INFO): Powering down
2019-03-06T22:51:40.689Z,1551912700.689 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 7753
2019-03-06T22:51:40.690Z,1551912700.690 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-06T22:51:40.717Z,1551912700.717 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-06T22:51:40.717Z,1551912700.717 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-06T22:51:40.722Z,1551912700.722 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7754
2019-03-06T22:51:40.724Z,1551912700.724 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-06T22:51:40.725Z,1551912700.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-06T22:51:40.725Z,1551912700.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-06T22:51:40.725Z,1551912700.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-06T22:51:40.725Z,1551912700.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-06T22:51:40.725Z,1551912700.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-06T22:51:40.726Z,1551912700.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-06T22:51:40.726Z,1551912700.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-06T22:51:40.726Z,1551912700.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-06T22:51:40.726Z,1551912700.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-06T22:51:40.726Z,1551912700.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-06T22:51:40.726Z,1551912700.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-06T22:51:40.727Z,1551912700.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-06T22:51:40.727Z,1551912700.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-06T22:51:40.727Z,1551912700.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-06T22:51:40.727Z,1551912700.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-06T22:51:40.753Z,1551912700.753 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-06T22:51:40.755Z,1551912700.755 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-06T22:51:40.755Z,1551912700.755 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-06T22:51:40.756Z,1551912700.756 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-06T22:51:40.757Z,1551912700.757 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-06T22:51:40.766Z,1551912700.766 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-06T22:51:40.789Z,1551912700.789 [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>


2019-03-06T22:51:40.790Z,1551912700.790 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-06T22:51:40.844Z,1551912700.844 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-06T22:51:40.866Z,1551912700.866 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-06T22:51:40.867Z,1551912700.867 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-06T22:51:40.903Z,1551912700.903 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-06T22:51:40.921Z,1551912700.921 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-06T22:51:40.926Z,1551912700.926 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-06T22:51:40.946Z,1551912700.946 [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>


2019-03-06T22:51:40.951Z,1551912700.951 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-06T22:51:40.956Z,1551912700.956 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T22:51:41.019Z,1551912701.019 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-06T22:51:41.020Z,1551912701.020 [DUSBL_Hydroid](INFO): Powering up
2019-03-06T22:51:41.020Z,1551912701.020 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-06T22:51:41.045Z,1551912701.045 [Radio_Surface](INFO): Powering up
2019-03-06T22:51:41.121Z,1551912701.121 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T22:51:41.161Z,1551912701.161 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:51:41.169Z,1551912701.169 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-06T22:51:41.170Z,1551912701.170 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:51:41.177Z,1551912701.177 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-06T22:51:41.178Z,1551912701.178 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:51:41.185Z,1551912701.185 [MassServo](DEBUG): Initializing MassServo.
2019-03-06T22:51:41.186Z,1551912701.186 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:51:41.193Z,1551912701.193 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-06T22:51:41.194Z,1551912701.194 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:51:41.201Z,1551912701.201 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-06T22:51:41.379Z,1551912701.379 [Micromodem](INFO): Powering up
2019-03-06T22:51:41.379Z,1551912701.379 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-06T22:51:41.481Z,1551912701.481 [RDI_PathfinderUp](INFO): Powering down
2019-03-06T22:51:41.541Z,1551912701.541 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T22:51:41.785Z,1551912701.785 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T22:51:42.105Z,1551912702.105 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-06T22:51:42.105Z,1551912702.105 [RudderServo](FAULT): Rudder failed to initialize
2019-03-06T22:51:42.105Z,1551912702.105 [RudderServo] Communications Fault, FailCount= 1
2019-03-06T22:51:42.105Z,1551912702.105 [RudderServo](ERROR): Communications Fault
2019-03-06T22:51:42.216Z,1551912702.216 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-06T22:51:42.396Z,1551912702.396 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-06T22:51:42.396Z,1551912702.396 [RudderServo](INFO): Powering down
2019-03-06T22:51:43.062Z,1551912703.062 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:51:43.182Z,1551912703.182 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-06T22:51:43.186Z,1551912703.186 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-06T22:51:43.186Z,1551912703.186 [RudderServo] No Fault, FailCount= 1
2019-03-06T22:51:44.433Z,1551912704.433 [Aanderaa_O2](INFO): Powering down
2019-03-06T22:51:44.741Z,1551912704.741 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:45.145Z,1551912705.145 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:45.549Z,1551912705.549 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:45.953Z,1551912705.953 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:46.357Z,1551912706.357 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:46.364Z,1551912706.364 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37

2019-03-06T22:51:46.365Z,1551912706.365 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-03-06T22:51:46.365Z,1551912706.365 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-06T22:51:46.366Z,1551912706.366 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32

2019-03-06T22:51:46.366Z,1551912706.366 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29

2019-03-06T22:51:46.761Z,1551912706.761 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:47.165Z,1551912707.165 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:47.569Z,1551912707.569 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:47.973Z,1551912707.973 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:48.377Z,1551912708.377 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:48.781Z,1551912708.781 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-06T22:51:54.385Z,1551912714.385 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-06T22:51:55.581Z,1551912715.581 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-06T22:51:59.214Z,1551912719.214 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-06T22:52:03.645Z,1551912723.645 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-03-06T22:52:03.645Z,1551912723.645 [AHRS_M2] Data Fault, FailCount= 1
2019-03-06T22:52:03.645Z,1551912723.645 [AHRS_M2](ERROR): Data Fault
2019-03-06T22:52:03.690Z,1551912723.690 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-03-06T22:52:04.037Z,1551912724.037 [AHRS_M2](INFO): Powering down
2019-03-06T22:52:04.896Z,1551912724.896 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T22:52:04.896Z,1551912724.896 [AHRS_M2] No Fault, FailCount= 1
2019-03-06T22:52:05.250Z,1551912725.250 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T22:52:05.276Z,1551912725.276 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32

2019-03-06T22:52:05.561Z,1551912725.561 [CommandLine](IMPORTANT): got command restart application
2019-03-06T22:52:06.063Z,1551912726.063 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,225154,COPROC,0.10.0.6*45

2019-03-06T22:52:06.565Z,1551912726.565 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-03-06T22:52:06.565Z,1551912726.565 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:52:06.565Z,1551912726.565 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:06.701Z,1551912726.701 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-06T22:52:06.701Z,1551912726.701 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:06.702Z,1551912726.702 [CommandLine](INFO): Join timeout helper Thread ID is 7768
2019-03-06T22:52:06.713Z,1551912726.713 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-06T22:52:06.713Z,1551912726.713 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:06.713Z,1551912726.713 [NavChartDb](INFO): Join timeout helper Thread ID is 7769
2019-03-06T22:52:06.871Z,1551912726.871 [NAL9602](INFO): Powering up NAL9602
2019-03-06T22:52:08.010Z,1551912728.010 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:52:08.010Z,1551912728.010 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.013Z,1551912728.013 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-06T22:52:08.013Z,1551912728.013 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.013Z,1551912728.013 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7770
2019-03-06T22:52:08.181Z,1551912728.181 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:52:08.181Z,1551912728.181 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-06T22:52:08.182Z,1551912728.182 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.201Z,1551912728.201 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-06T22:52:08.201Z,1551912728.201 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.202Z,1551912728.202 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7771
2019-03-06T22:52:08.221Z,1551912728.221 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:52:08.222Z,1551912728.222 [CTD_NeilBrown](INFO): Powering down
2019-03-06T22:52:08.237Z,1551912728.237 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.241Z,1551912728.241 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-06T22:52:08.242Z,1551912728.242 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.243Z,1551912728.243 [Radio_Surface](INFO): Join timeout helper Thread ID is 7772
2019-03-06T22:52:08.517Z,1551912728.517 [Radio_Surface](INFO): Powering down
2019-03-06T22:52:08.518Z,1551912728.518 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:52:08.518Z,1551912728.518 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.530Z,1551912728.530 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-06T22:52:08.530Z,1551912728.530 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.530Z,1551912728.530 [DataOverHttps](INFO): Join timeout helper Thread ID is 7773
2019-03-06T22:52:08.913Z,1551912728.913 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:52:08.913Z,1551912728.913 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.918Z,1551912728.918 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-06T22:52:08.918Z,1551912728.918 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.918Z,1551912728.918 [logger](INFO): Join timeout helper Thread ID is 7774
2019-03-06T22:52:08.937Z,1551912728.937 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:52:08.937Z,1551912728.937 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.958Z,1551912728.958 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-06T22:52:08.958Z,1551912728.958 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.958Z,1551912728.958 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-06T22:52:08.958Z,1551912728.958 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:08.958Z,1551912728.958 [controlThread](INFO): Join timeout helper Thread ID is 7775
2019-03-06T22:52:09.001Z,1551912729.001 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:52:09.001Z,1551912729.001 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-06T22:52:09.001Z,1551912729.001 [AHRS_M2](INFO): Powering down
2019-03-06T22:52:09.073Z,1551912729.073 [DUSBL_Hydroid](INFO): Powering down
2019-03-06T22:52:09.165Z,1551912729.165 [Micromodem](INFO): Powering down
2019-03-06T22:52:09.281Z,1551912729.281 [NAL9602](INFO): Powering down
2019-03-06T22:52:09.373Z,1551912729.373 [RDI_Pathfinder](INFO): Powering down
2019-03-06T22:52:09.374Z,1551912729.374 [RDI_PathfinderUp](INFO): Powering down
2019-03-06T22:52:09.375Z,1551912729.375 [Aanderaa_O2](INFO): Powering down
2019-03-06T22:52:09.376Z,1551912729.376 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-06T22:52:09.377Z,1551912729.377 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-06T22:52:09.378Z,1551912729.378 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-06T22:52:09.379Z,1551912729.379 [MissionManager](INFO): Uninitializing Mission Default
2019-03-06T22:52:09.381Z,1551912729.381 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-06T22:52:09.382Z,1551912729.382 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-06T22:52:09.382Z,1551912729.382 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-06T22:52:09.383Z,1551912729.383 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-06T22:52:09.383Z,1551912729.383 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-06T22:52:09.383Z,1551912729.383 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-06T22:52:09.383Z,1551912729.383 [BuoyancyServo](INFO): Powering down
2019-03-06T22:52:09.397Z,1551912729.397 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-06T22:52:09.397Z,1551912729.397 [ElevatorServo](INFO): Powering down
2019-03-06T22:52:09.417Z,1551912729.417 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-06T22:52:09.417Z,1551912729.417 [MassServo](INFO): Powering down
2019-03-06T22:52:09.418Z,1551912729.418 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-06T22:52:09.418Z,1551912729.418 [RudderServo](INFO): Powering down
2019-03-06T22:52:09.419Z,1551912729.419 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-06T22:52:09.419Z,1551912729.419 [ThrusterServo](INFO): Powering down
2019-03-06T22:52:09.419Z,1551912729.419 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-06T22:52:09.420Z,1551912729.420 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-06T22:52:09.420Z,1551912729.420 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-06T22:52:09.420Z,1551912729.420 [CBIT](DEBUG): Powering off loads.
2019-03-06T22:52:09.433Z,1551912729.433 [CBIT](DEBUG): Disabling WDT.
2019-03-06T22:52:09.445Z,1551912729.445 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-06T22:52:09.446Z,1551912729.446 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:09.510Z,1551912729.510 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:09.519Z,1551912729.519 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:09.678Z,1551912729.678 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:09.680Z,1551912729.680 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:09.734Z,1551912729.734 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:52:09.788Z,1551912729.788 [logger ThreadHandler](INFO): Thread cancelled.