2019-03-08T21:56:25.818Z,1552082185.818 [Supervisor](DEBUG): Initializing supervisor.
2019-03-08T21:56:25.821Z,1552082185.821 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-08T21:56:25.822Z,1552082185.822 [SyncHandler](INFO): Protected caller Thread ID is 6697
2019-03-08T21:56:25.822Z,1552082185.822 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-08T21:56:25.823Z,1552082185.823 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-08T21:56:25.824Z,1552082185.824 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6698
2019-03-08T21:56:25.827Z,1552082185.827 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-08T21:56:25.840Z,1552082185.840 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-08T21:56:25.841Z,1552082185.841 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-08T21:56:25.841Z,1552082185.841 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6699
2019-03-08T21:56:25.842Z,1552082185.842 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-08T21:56:25.843Z,1552082185.843 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-08T21:56:25.843Z,1552082185.843 [logger ThreadHandler](INFO): Protected caller Thread ID is 6700
2019-03-08T21:56:25.845Z,1552082185.845 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-08T21:56:25.846Z,1552082185.846 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-08T21:56:25.847Z,1552082185.847 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-08T21:56:26.300Z,1552082186.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-08T21:56:26.300Z,1552082186.300 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-08T21:56:26.400Z,1552082186.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-08T21:56:26.401Z,1552082186.401 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-08T21:56:26.754Z,1552082186.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-08T21:56:26.755Z,1552082186.755 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-08T21:56:26.904Z,1552082186.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-08T21:56:26.904Z,1552082186.904 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-08T21:56:27.108Z,1552082187.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-08T21:56:27.109Z,1552082187.109 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-08T21:56:27.606Z,1552082187.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-08T21:56:27.607Z,1552082187.607 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-08T21:56:27.916Z,1552082187.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-08T21:56:27.917Z,1552082187.917 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-08T21:56:28.070Z,1552082188.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-08T21:56:28.070Z,1552082188.070 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-08T21:56:28.286Z,1552082188.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-08T21:56:28.286Z,1552082188.286 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-08T21:56:28.384Z,1552082188.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-08T21:56:28.384Z,1552082188.384 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-08T21:56:28.717Z,1552082188.717 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-08T21:56:28.717Z,1552082188.717 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-08T21:56:28.798Z,1552082188.798 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-08T21:56:28.904Z,1552082188.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-08T21:56:28.905Z,1552082188.905 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-08T21:56:29.433Z,1552082189.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-08T21:56:29.434Z,1552082189.434 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-08T21:56:29.851Z,1552082189.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-08T21:56:29.853Z,1552082189.853 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-08T21:56:29.854Z,1552082189.854 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-08T21:56:30.077Z,1552082190.077 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-08T21:56:30.181Z,1552082190.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-08T21:56:30.282Z,1552082190.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-08T21:56:30.515Z,1552082190.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-08T21:56:30.515Z,1552082190.515 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-08T21:56:30.602Z,1552082190.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-08T21:56:30.698Z,1552082190.698 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-08T21:56:30.799Z,1552082190.799 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-08T21:56:30.882Z,1552082190.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-08T21:56:30.995Z,1552082190.995 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-08T21:56:31.181Z,1552082191.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-08T21:56:31.321Z,1552082191.321 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-08T21:56:31.321Z,1552082191.321 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-08T21:56:31.325Z,1552082191.325 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-08T21:56:31.632Z,1552082191.632 [DataOverHttps] Loaded
2019-03-08T21:56:31.632Z,1552082191.632 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-08T21:56:31.633Z,1552082191.633 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0
2019-03-08T21:56:31.634Z,1552082191.634 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6779
2019-03-08T21:56:31.673Z,1552082191.673 [Depth_Keller] Loaded
2019-03-08T21:56:31.673Z,1552082191.673 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-08T21:56:31.679Z,1552082191.679 [DropWeight] Loaded
2019-03-08T21:56:31.679Z,1552082191.679 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-08T21:56:31.729Z,1552082191.729 [DUSBL_Hydroid] Loaded
2019-03-08T21:56:31.729Z,1552082191.729 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-08T21:56:31.771Z,1552082191.771 [Micromodem] Loaded
2019-03-08T21:56:31.772Z,1552082191.772 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-08T21:56:31.870Z,1552082191.870 [NAL9602] Loaded
2019-03-08T21:56:31.870Z,1552082191.870 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-08T21:56:31.886Z,1552082191.886 [Onboard] Loaded
2019-03-08T21:56:31.887Z,1552082191.887 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-08T21:56:31.894Z,1552082191.894 [Radio_Surface] Loaded
2019-03-08T21:56:31.894Z,1552082191.894 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-08T21:56:31.895Z,1552082191.895 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0
2019-03-08T21:56:31.895Z,1552082191.895 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6780
2019-03-08T21:56:31.941Z,1552082191.941 [RDI_Pathfinder] Loaded
2019-03-08T21:56:31.941Z,1552082191.941 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-08T21:56:31.964Z,1552082191.964 [RDI_PathfinderUp] Loaded
2019-03-08T21:56:31.964Z,1552082191.964 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-08T21:56:34.308Z,1552082194.308 [BPC1] Loaded
2019-03-08T21:56:34.308Z,1552082194.308 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-08T21:56:34.309Z,1552082194.309 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-08T21:56:34.310Z,1552082194.310 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-08T21:56:34.323Z,1552082194.323 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-08T21:56:34.323Z,1552082194.323 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-08T21:56:34.432Z,1552082194.432 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-08T21:56:34.432Z,1552082194.432 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-08T21:56:34.493Z,1552082194.493 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-08T21:56:34.493Z,1552082194.493 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-08T21:56:34.555Z,1552082194.555 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-08T21:56:34.555Z,1552082194.555 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-08T21:56:34.576Z,1552082194.576 [NavChart] Loaded
2019-03-08T21:56:34.576Z,1552082194.576 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-08T21:56:34.581Z,1552082194.581 [UniversalFixResidualReporter] Loaded
2019-03-08T21:56:34.581Z,1552082194.581 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-08T21:56:34.582Z,1552082194.582 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-08T21:56:34.582Z,1552082194.582 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-08T21:56:34.683Z,1552082194.683 [BuoyancyServo] Loaded
2019-03-08T21:56:34.684Z,1552082194.684 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-08T21:56:34.700Z,1552082194.700 [ElevatorServo] Loaded
2019-03-08T21:56:34.700Z,1552082194.700 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-08T21:56:34.716Z,1552082194.716 [MassServo] Loaded
2019-03-08T21:56:34.716Z,1552082194.716 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-08T21:56:34.732Z,1552082194.732 [RudderServo] Loaded
2019-03-08T21:56:34.732Z,1552082194.732 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-08T21:56:34.748Z,1552082194.748 [ThrusterServo] Loaded
2019-03-08T21:56:34.748Z,1552082194.748 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-08T21:56:34.748Z,1552082194.748 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-08T21:56:34.749Z,1552082194.749 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-08T21:56:34.927Z,1552082194.927 [Aanderaa_O2] Loaded
2019-03-08T21:56:34.928Z,1552082194.928 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-03-08T21:56:35.014Z,1552082195.014 [CTD_NeilBrown] Loaded
2019-03-08T21:56:35.014Z,1552082195.014 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-08T21:56:35.015Z,1552082195.015 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2019-03-08T21:56:35.016Z,1552082195.016 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6781
2019-03-08T21:56:35.062Z,1552082195.062 [WetLabsSeaOWL_UV_A] Loaded
2019-03-08T21:56:35.063Z,1552082195.063 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-08T21:56:35.064Z,1552082195.064 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2019-03-08T21:56:35.064Z,1552082195.064 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 6782
2019-03-08T21:56:35.065Z,1552082195.065 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-08T21:56:35.065Z,1552082195.065 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-08T21:56:35.299Z,1552082195.299 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-08T21:56:35.300Z,1552082195.300 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-08T21:56:35.340Z,1552082195.340 [DepthRateCalculator] Loaded
2019-03-08T21:56:35.340Z,1552082195.340 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-08T21:56:35.346Z,1552082195.346 [PitchRateCalculator] Loaded
2019-03-08T21:56:35.347Z,1552082195.347 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-08T21:56:35.360Z,1552082195.360 [SpeedCalculator] Loaded
2019-03-08T21:56:35.360Z,1552082195.360 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-08T21:56:35.382Z,1552082195.382 [TempGradientCalculator] Loaded
2019-03-08T21:56:35.382Z,1552082195.382 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-08T21:56:35.389Z,1552082195.389 [YawRateCalculator] Loaded
2019-03-08T21:56:35.389Z,1552082195.389 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-08T21:56:35.432Z,1552082195.432 [ElevatorOffsetCalculator] Loaded
2019-03-08T21:56:35.432Z,1552082195.432 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-08T21:56:35.433Z,1552082195.433 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-08T21:56:35.434Z,1552082195.434 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-08T21:56:35.564Z,1552082195.564 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-08T21:56:35.588Z,1552082195.588 [SBIT] Loaded
2019-03-08T21:56:35.589Z,1552082195.589 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-08T21:56:35.589Z,1552082195.589 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-08T21:56:35.602Z,1552082195.602 [IBIT] Loaded
2019-03-08T21:56:35.602Z,1552082195.602 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-08T21:56:35.605Z,1552082195.605 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-08T21:56:35.751Z,1552082195.751 [CBIT] Loaded
2019-03-08T21:56:35.752Z,1552082195.752 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-08T21:56:35.752Z,1552082195.752 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-08T21:56:35.753Z,1552082195.753 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-08T21:56:35.815Z,1552082195.815 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-08T21:56:35.816Z,1552082195.816 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-08T21:56:35.911Z,1552082195.911 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-08T21:56:35.912Z,1552082195.912 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-08T21:56:35.977Z,1552082195.977 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-08T21:56:36.066Z,1552082196.066 [VerticalControl] Loaded
2019-03-08T21:56:36.066Z,1552082196.066 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-08T21:56:36.067Z,1552082196.067 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-08T21:56:36.127Z,1552082196.127 [HorizontalControl] Loaded
2019-03-08T21:56:36.128Z,1552082196.128 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-08T21:56:36.128Z,1552082196.128 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-08T21:56:36.130Z,1552082196.130 [SpeedControl] Loaded
2019-03-08T21:56:36.131Z,1552082196.131 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-08T21:56:36.131Z,1552082196.131 [LoopControl](DEBUG): Construct LoopControl.
2019-03-08T21:56:36.132Z,1552082196.132 [LoopControl] Loaded
2019-03-08T21:56:36.132Z,1552082196.132 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-08T21:56:36.133Z,1552082196.133 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-08T21:56:36.133Z,1552082196.133 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-08T21:56:36.160Z,1552082196.160 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-08T21:56:36.164Z,1552082196.164 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-08T21:56:36.165Z,1552082196.165 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-08T21:56:36.172Z,1552082196.172 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-08T21:56:36.173Z,1552082196.173 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2019-03-08T21:56:36.174Z,1552082196.174 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6783
2019-03-08T21:56:36.178Z,1552082196.178 [Supervisor](INFO): Main Thread ID is 5883
2019-03-08T21:56:36.179Z,1552082196.179 [Supervisor](DEBUG): Running supervisor.
2019-03-08T21:56:36.179Z,1552082196.179 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6784
2019-03-08T21:56:36.182Z,1552082196.182 [controlThread ThreadHandler](INFO): Handler Thread ID is 6785
2019-03-08T21:56:36.182Z,1552082196.182 [controlThread](DEBUG): Initializing ControlThread
2019-03-08T21:56:36.188Z,1552082196.188 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-08T21:56:36.189Z,1552082196.189 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-08T21:56:36.189Z,1552082196.189 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-08T21:56:36.191Z,1552082196.191 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-08T21:56:36.191Z,1552082196.191 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-08T21:56:36.191Z,1552082196.191 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-08T21:56:36.191Z,1552082196.191 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-08T21:56:36.192Z,1552082196.192 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-08T21:56:36.192Z,1552082196.192 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-08T21:56:36.193Z,1552082196.193 [SBIT](INFO): Initialize SBIT Component.
2019-03-08T21:56:36.194Z,1552082196.194 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3
2019-03-08T21:56:36.194Z,1552082196.194 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa
2019-03-08T21:56:36.194Z,1552082196.194 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-08T21:56:36.194Z,1552082196.194 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-08T21:56:36.195Z,1552082196.195 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-08T21:56:36.196Z,1552082196.196 [IBIT](INFO): Initialize IBIT Component.
2019-03-08T21:56:36.197Z,1552082196.197 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-08T21:56:36.198Z,1552082196.198 [logger ThreadHandler](INFO): Handler Thread ID is 6786
2019-03-08T21:56:36.209Z,1552082196.209 [CBIT](DEBUG): Initialized mux pins.
2019-03-08T21:56:36.209Z,1552082196.209 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-08T21:56:36.217Z,1552082196.217 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6787
2019-03-08T21:56:36.218Z,1552082196.218 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-08T21:56:36.229Z,1552082196.229 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6788
2019-03-08T21:56:36.233Z,1552082196.233 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-08T21:56:36.233Z,1552082196.233 [CBIT](DEBUG): Initializing heartbeat.
2019-03-08T21:56:36.241Z,1552082196.241 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6789
2019-03-08T21:56:36.242Z,1552082196.242 [CTD_NeilBrown](INFO): Powering down
2019-03-08T21:56:36.269Z,1552082196.269 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 6790
2019-03-08T21:56:36.270Z,1552082196.270 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-08T21:56:36.305Z,1552082196.305 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-08T21:56:36.305Z,1552082196.305 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-08T21:56:36.306Z,1552082196.306 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6791
2019-03-08T21:56:36.309Z,1552082196.309 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-08T21:56:36.309Z,1552082196.309 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-08T21:56:36.309Z,1552082196.309 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-08T21:56:36.309Z,1552082196.309 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-08T21:56:36.310Z,1552082196.310 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-08T21:56:36.310Z,1552082196.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-08T21:56:36.310Z,1552082196.310 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-08T21:56:36.310Z,1552082196.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-08T21:56:36.310Z,1552082196.310 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-08T21:56:36.310Z,1552082196.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-08T21:56:36.311Z,1552082196.311 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-08T21:56:36.311Z,1552082196.311 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-08T21:56:36.311Z,1552082196.311 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-08T21:56:36.311Z,1552082196.311 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-08T21:56:36.311Z,1552082196.311 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-08T21:56:36.311Z,1552082196.311 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-08T21:56:36.341Z,1552082196.341 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-08T21:56:36.343Z,1552082196.343 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-08T21:56:36.343Z,1552082196.343 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-08T21:56:36.344Z,1552082196.344 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-08T21:56:36.345Z,1552082196.345 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-08T21:56:36.354Z,1552082196.354 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-08T21:56:36.389Z,1552082196.389 [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-08T21:56:36.390Z,1552082196.390 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-08T21:56:36.461Z,1552082196.461 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-08T21:56:36.462Z,1552082196.462 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-08T21:56:36.464Z,1552082196.464 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-08T21:56:36.486Z,1552082196.486 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-08T21:56:36.519Z,1552082196.519 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-08T21:56:36.546Z,1552082196.546 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-08T21:56:36.549Z,1552082196.549 [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-08T21:56:36.561Z,1552082196.561 [controlThread](DEBUG): Component order: CycleStarter,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-08T21:56:36.598Z,1552082196.598 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-08T21:56:36.599Z,1552082196.599 [DUSBL_Hydroid](INFO): Powering up
2019-03-08T21:56:36.599Z,1552082196.599 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-08T21:56:36.617Z,1552082196.617 [Micromodem](INFO): Start
2019-03-08T21:56:36.634Z,1552082196.634 [Radio_Surface](INFO): Powering up
2019-03-08T21:56:36.690Z,1552082196.690 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-08T21:56:36.734Z,1552082196.734 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-08T21:56:36.737Z,1552082196.737 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-08T21:56:36.738Z,1552082196.738 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-08T21:56:36.745Z,1552082196.745 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-08T21:56:36.746Z,1552082196.746 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-08T21:56:36.753Z,1552082196.753 [MassServo](DEBUG): Initializing MassServo.
2019-03-08T21:56:36.754Z,1552082196.754 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-08T21:56:36.761Z,1552082196.761 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-08T21:56:36.762Z,1552082196.762 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-08T21:56:36.769Z,1552082196.769 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-08T21:56:36.986Z,1552082196.986 [Micromodem](INFO): Starting
2019-03-08T21:56:36.987Z,1552082196.987 [Micromodem](INFO): Powering up
2019-03-08T21:56:36.987Z,1552082196.987 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-08T21:56:37.089Z,1552082197.089 [RDI_PathfinderUp](INFO): Powering down
2019-03-08T21:56:37.141Z,1552082197.141 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-08T21:56:37.378Z,1552082197.378 [Micromodem](INFO): Starting
2019-03-08T21:56:37.397Z,1552082197.397 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-08T21:56:37.721Z,1552082197.721 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-08T21:56:37.721Z,1552082197.721 [RudderServo](FAULT): Rudder failed to initialize
2019-03-08T21:56:37.721Z,1552082197.721 [RudderServo] Communications Fault, FailCount= 1
2019-03-08T21:56:37.721Z,1552082197.721 [RudderServo](ERROR): Communications Fault
2019-03-08T21:56:37.832Z,1552082197.832 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-08T21:56:37.843Z,1552082197.843 [Micromodem](INFO): Starting
2019-03-08T21:56:38.022Z,1552082198.022 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-08T21:56:38.022Z,1552082198.022 [RudderServo](INFO): Powering down
2019-03-08T21:56:38.246Z,1552082198.246 [Micromodem](INFO): Starting
2019-03-08T21:56:38.655Z,1552082198.655 [Micromodem](INFO): Starting
2019-03-08T21:56:38.682Z,1552082198.682 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-08T21:56:38.802Z,1552082198.802 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-08T21:56:38.806Z,1552082198.806 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-08T21:56:38.806Z,1552082198.806 [RudderServo] No Fault, FailCount= 1
2019-03-08T21:56:39.055Z,1552082199.055 [Micromodem](INFO): Starting
2019-03-08T21:56:39.459Z,1552082199.459 [Micromodem](INFO): Starting
2019-03-08T21:56:39.864Z,1552082199.864 [Micromodem](INFO): Starting
2019-03-08T21:56:40.001Z,1552082200.001 [Aanderaa_O2](INFO): Powering down
2019-03-08T21:56:40.267Z,1552082200.267 [Micromodem](INFO): Starting
2019-03-08T21:56:40.679Z,1552082200.679 [Micromodem](INFO): Starting
2019-03-08T21:56:41.075Z,1552082201.075 [Micromodem](INFO): Starting
2019-03-08T21:56:41.479Z,1552082201.479 [Micromodem](INFO): Starting
2019-03-08T21:56:41.883Z,1552082201.883 [Micromodem](INFO): Starting
2019-03-08T21:56:41.883Z,1552082201.883 [Micromodem](INFO): Starting
2019-03-08T21:56:41.884Z,1552082201.884 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37

2019-03-08T21:56:41.884Z,1552082201.884 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,CST,1*37

2019-03-08T21:56:41.884Z,1552082201.884 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-03-08T21:56:41.884Z,1552082201.884 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-08T21:56:41.884Z,1552082201.884 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-08T21:56:41.888Z,1552082201.888 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32

2019-03-08T21:56:41.888Z,1552082201.888 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,REV,1*32

2019-03-08T21:56:41.889Z,1552082201.889 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29

2019-03-08T21:56:41.890Z,1552082201.890 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,RXP,1*29

2019-03-08T21:56:42.287Z,1552082202.287 [Micromodem](INFO): Starting
2019-03-08T21:56:42.691Z,1552082202.691 [Micromodem](INFO): Starting
2019-03-08T21:56:43.095Z,1552082203.095 [Micromodem](INFO): Starting
2019-03-08T21:56:43.503Z,1552082203.503 [Micromodem](INFO): Starting
2019-03-08T21:56:43.914Z,1552082203.914 [Micromodem](INFO): Starting
2019-03-08T21:56:44.307Z,1552082204.307 [Micromodem](INFO): Starting
2019-03-08T21:56:44.719Z,1552082204.719 [Micromodem](INFO): Starting
2019-03-08T21:56:45.119Z,1552082205.119 [Micromodem](INFO): Starting
2019-03-08T21:56:45.519Z,1552082205.519 [Micromodem](INFO): Starting
2019-03-08T21:56:45.929Z,1552082205.929 [Micromodem](INFO): Starting
2019-03-08T21:56:46.327Z,1552082206.327 [Micromodem](INFO): Starting
2019-03-08T21:56:46.735Z,1552082206.735 [Micromodem](INFO): Starting
2019-03-08T21:56:47.135Z,1552082207.135 [Micromodem](INFO): Starting
2019-03-08T21:56:47.539Z,1552082207.539 [Micromodem](INFO): Starting
2019-03-08T21:56:47.943Z,1552082207.943 [Micromodem](INFO): Starting
2019-03-08T21:56:48.347Z,1552082208.347 [Micromodem](INFO): Starting
2019-03-08T21:56:48.755Z,1552082208.755 [Micromodem](INFO): Starting
2019-03-08T21:56:49.155Z,1552082209.155 [Micromodem](INFO): Starting
2019-03-08T21:56:49.559Z,1552082209.559 [Micromodem](INFO): Starting
2019-03-08T21:56:49.963Z,1552082209.963 [Micromodem](INFO): Starting
2019-03-08T21:56:49.989Z,1552082209.989 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-08T21:56:50.367Z,1552082210.367 [Micromodem](INFO): Starting
2019-03-08T21:56:50.786Z,1552082210.786 [Micromodem](INFO): Starting
2019-03-08T21:56:51.175Z,1552082211.175 [Micromodem](INFO): Starting
2019-03-08T21:56:51.579Z,1552082211.579 [Micromodem](INFO): Starting
2019-03-08T21:56:51.983Z,1552082211.983 [Micromodem](INFO): Starting
2019-03-08T21:56:52.387Z,1552082212.387 [Micromodem](INFO): Starting
2019-03-08T21:56:52.798Z,1552082212.798 [Micromodem](INFO): Starting
2019-03-08T21:56:53.195Z,1552082213.195 [Micromodem](INFO): Starting
2019-03-08T21:56:53.195Z,1552082213.195 [Micromodem](ERROR): Nmea resend: $CCCFG,CST,1*37

2019-03-08T21:56:53.599Z,1552082213.599 [Micromodem](INFO): Starting
2019-03-08T21:56:54.004Z,1552082214.004 [Micromodem](INFO): Starting
2019-03-08T21:56:54.408Z,1552082214.408 [Micromodem](INFO): Starting
2019-03-08T21:56:54.811Z,1552082214.811 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-08T21:56:54.825Z,1552082214.825 [Micromodem](INFO): Starting
2019-03-08T21:56:55.215Z,1552082215.215 [Micromodem](INFO): Starting
2019-03-08T21:56:55.619Z,1552082215.619 [Micromodem](INFO): Starting
2019-03-08T21:56:56.023Z,1552082216.023 [Micromodem](INFO): Starting
2019-03-08T21:56:56.427Z,1552082216.427 [Micromodem](INFO): Starting
2019-03-08T21:56:56.831Z,1552082216.831 [Micromodem](INFO): Starting
2019-03-08T21:56:57.235Z,1552082217.235 [Micromodem](INFO): Starting
2019-03-08T21:56:57.639Z,1552082217.639 [Micromodem](INFO): Starting
2019-03-08T21:56:58.046Z,1552082218.046 [Micromodem](INFO): Starting
2019-03-08T21:56:58.447Z,1552082218.447 [Micromodem](INFO): Starting
2019-03-08T21:56:58.851Z,1552082218.851 [Micromodem](INFO): Starting
2019-03-08T21:56:59.255Z,1552082219.255 [Micromodem](INFO): Starting
2019-03-08T21:56:59.659Z,1552082219.659 [Micromodem](INFO): Starting
2019-03-08T21:57:00.063Z,1552082220.063 [Micromodem](INFO): Starting
2019-03-08T21:57:00.467Z,1552082220.467 [Micromodem](INFO): Starting
2019-03-08T21:57:00.871Z,1552082220.871 [Micromodem](INFO): Starting
2019-03-08T21:57:01.275Z,1552082221.275 [Micromodem](INFO): Starting
2019-03-08T21:57:01.675Z,1552082221.675 [Micromodem](INFO): Starting
2019-03-08T21:57:02.083Z,1552082222.083 [Micromodem](INFO): Starting
2019-03-08T21:57:02.083Z,1552082222.083 [Micromodem](IMPORTANT): CACFG stsring$CACFG,CST,1*35

2019-03-08T21:57:02.084Z,1552082222.084 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-08T21:57:02.085Z,1552082222.085 [NAL9602](INFO): Powering up NAL9602
2019-03-08T21:57:02.488Z,1552082222.488 [Micromodem](INFO): Starting
2019-03-08T21:57:02.891Z,1552082222.891 [Micromodem](INFO): Starting
2019-03-08T21:57:03.295Z,1552082223.295 [Micromodem](INFO): Starting
2019-03-08T21:57:03.699Z,1552082223.699 [Micromodem](INFO): Starting
2019-03-08T21:57:04.103Z,1552082224.103 [Micromodem](INFO): Starting
2019-03-08T21:57:04.507Z,1552082224.507 [Micromodem](INFO): Starting
2019-03-08T21:57:04.911Z,1552082224.911 [Micromodem](INFO): Starting
2019-03-08T21:57:04.960Z,1552082224.960 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-08T21:57:04.964Z,1552082224.964 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-08T21:57:05.315Z,1552082225.315 [Micromodem](INFO): Starting
2019-03-08T21:57:05.726Z,1552082225.726 [Micromodem](INFO): Starting
2019-03-08T21:57:06.123Z,1552082226.123 [Micromodem](INFO): Starting
2019-03-08T21:57:06.527Z,1552082226.527 [Micromodem](INFO): Starting
2019-03-08T21:57:06.938Z,1552082226.938 [Micromodem](INFO): Starting
2019-03-08T21:57:07.335Z,1552082227.335 [Micromodem](INFO): Starting
2019-03-08T21:57:07.944Z,1552082227.944 [Micromodem](INFO): Starting
2019-03-08T21:57:08.346Z,1552082228.346 [Micromodem](INFO): Starting
2019-03-08T21:57:08.773Z,1552082228.773 [Micromodem](INFO): Starting
2019-03-08T21:57:09.155Z,1552082229.155 [Micromodem](INFO): Starting
2019-03-08T21:57:09.566Z,1552082229.566 [Micromodem](INFO): Starting
2019-03-08T21:57:09.959Z,1552082229.959 [Micromodem](INFO): Starting
2019-03-08T21:57:10.370Z,1552082230.370 [Micromodem](INFO): Starting
2019-03-08T21:57:10.780Z,1552082230.780 [Micromodem](INFO): Starting
2019-03-08T21:57:11.174Z,1552082231.174 [Micromodem](INFO): Starting
2019-03-08T21:57:11.578Z,1552082231.578 [Micromodem](INFO): Starting
2019-03-08T21:57:11.982Z,1552082231.982 [Micromodem](INFO): Starting
2019-03-08T21:57:11.983Z,1552082231.983 [Micromodem](IMPORTANT): CACFG stsring$CACFG,SRC,1*33

2019-03-08T21:57:11.983Z,1552082231.983 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32

2019-03-08T21:57:12.386Z,1552082232.386 [Micromodem](INFO): Starting
2019-03-08T21:57:12.798Z,1552082232.798 [Micromodem](INFO): Starting
2019-03-08T21:57:13.194Z,1552082233.194 [Micromodem](INFO): Starting
2019-03-08T21:57:13.195Z,1552082233.195 [NAL9602](INFO): NAL9602 initialized
2019-03-08T21:57:13.598Z,1552082233.598 [Micromodem](INFO): Starting
2019-03-08T21:57:14.002Z,1552082234.002 [Micromodem](INFO): Starting
2019-03-08T21:57:14.017Z,1552082234.017 [NAL9602](DEBUG): Fix Requested
2019-03-08T21:57:14.406Z,1552082234.406 [Micromodem](INFO): Starting
2019-03-08T21:57:14.810Z,1552082234.810 [Micromodem](INFO): Starting
2019-03-08T21:57:15.214Z,1552082235.214 [Micromodem](INFO): Starting
2019-03-08T21:57:15.618Z,1552082235.618 [Micromodem](INFO): Starting
2019-03-08T21:57:15.693Z,1552082235.693 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.016830
CHAN A1 (24V): -0.026301
CHAN A2 (12V): -0.007008
CHAN A3 (5V): -0.001351
CHAN B0 (3.3V): -0.000522
CHAN B1 (3.15aV): -0.000263
CHAN B2 (3.15bV): -0.000181
CHAN B3 (GND): 0.000841
OPEN: 0.009294
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-08T21:57:16.024Z,1552082236.024 [Micromodem](INFO): Starting
2019-03-08T21:57:16.426Z,1552082236.426 [Micromodem](INFO): Starting
2019-03-08T21:57:16.830Z,1552082236.830 [Micromodem](INFO): Starting
2019-03-08T21:57:17.234Z,1552082237.234 [Micromodem](INFO): Starting
2019-03-08T21:57:17.638Z,1552082237.638 [Micromodem](INFO): Starting
2019-03-08T21:57:18.042Z,1552082238.042 [Micromodem](INFO): Starting
2019-03-08T21:57:18.446Z,1552082238.446 [Micromodem](INFO): Starting
2019-03-08T21:57:18.850Z,1552082238.850 [Micromodem](INFO): Starting
2019-03-08T21:57:19.254Z,1552082239.254 [Micromodem](INFO): Starting
2019-03-08T21:57:19.658Z,1552082239.658 [Micromodem](INFO): Starting
2019-03-08T21:57:20.062Z,1552082240.062 [Micromodem](INFO): Starting
2019-03-08T21:57:20.642Z,1552082240.642 [Micromodem](INFO): Starting
2019-03-08T21:57:21.046Z,1552082241.046 [Micromodem](INFO): Starting
2019-03-08T21:57:21.450Z,1552082241.450 [Micromodem](INFO): Starting
2019-03-08T21:57:21.856Z,1552082241.856 [Micromodem](INFO): Starting
2019-03-08T21:57:22.258Z,1552082242.258 [Micromodem](INFO): Starting
2019-03-08T21:57:22.259Z,1552082242.259 [Micromodem](IMPORTANT): CACFG stsring$CACFG,REV,1*30

2019-03-08T21:57:22.259Z,1552082242.259 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29

2019-03-08T21:57:22.662Z,1552082242.662 [Micromodem](INFO): Starting
2019-03-08T21:57:23.066Z,1552082243.066 [Micromodem](INFO): Starting
2019-03-08T21:57:23.478Z,1552082243.478 [Micromodem](INFO): Starting
2019-03-08T21:57:23.874Z,1552082243.874 [Micromodem](INFO): Starting
2019-03-08T21:57:24.278Z,1552082244.278 [Micromodem](INFO): Starting
2019-03-08T21:57:24.682Z,1552082244.682 [Micromodem](INFO): Starting
2019-03-08T21:57:25.086Z,1552082245.086 [Micromodem](INFO): Starting
2019-03-08T21:57:25.490Z,1552082245.490 [Micromodem](INFO): Starting
2019-03-08T21:57:25.894Z,1552082245.894 [Micromodem](INFO): Starting
2019-03-08T21:57:26.298Z,1552082246.298 [Micromodem](INFO): Starting
2019-03-08T21:57:26.702Z,1552082246.702 [Micromodem](INFO): Starting
2019-03-08T21:57:27.106Z,1552082247.106 [Micromodem](INFO): Starting
2019-03-08T21:57:27.510Z,1552082247.510 [Micromodem](INFO): Starting
2019-03-08T21:57:27.914Z,1552082247.914 [Micromodem](INFO): Starting
2019-03-08T21:57:28.318Z,1552082248.318 [Micromodem](INFO): Starting
2019-03-08T21:57:28.724Z,1552082248.724 [Micromodem](INFO): Starting
2019-03-08T21:57:29.126Z,1552082249.126 [Micromodem](INFO): Starting
2019-03-08T21:57:29.530Z,1552082249.530 [Micromodem](INFO): Starting
2019-03-08T21:57:29.934Z,1552082249.934 [Micromodem](INFO): Starting
2019-03-08T21:57:30.338Z,1552082250.338 [Micromodem](INFO): Starting
2019-03-08T21:57:30.743Z,1552082250.743 [Micromodem](INFO): Starting
2019-03-08T21:57:31.146Z,1552082251.146 [Micromodem](INFO): Starting
2019-03-08T21:57:31.550Z,1552082251.550 [Micromodem](INFO): Starting
2019-03-08T21:57:31.955Z,1552082251.955 [Micromodem](INFO): Starting
2019-03-08T21:57:31.956Z,1552082251.956 [Micromodem](IMPORTANT): CACFG stsring$CACFG,RXP,1*2B

2019-03-08T21:57:31.956Z,1552082251.956 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-08T21:57:32Z,0*75

2019-03-08T21:57:31.957Z,1552082251.957 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-08T21:57:32Z,0*75

2019-03-08T21:57:31.957Z,1552082251.957 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-08T21:57:32Z,0*75

2019-03-08T21:57:32.358Z,1552082252.358 [Micromodem](INFO): Starting
2019-03-08T21:57:32.773Z,1552082252.773 [Micromodem](INFO): Starting
2019-03-08T21:57:33.166Z,1552082253.166 [Micromodem](INFO): Starting
2019-03-08T21:57:33.570Z,1552082253.570 [Micromodem](INFO): Starting
2019-03-08T21:57:58.355Z,1552082278.355 [SBIT](IMPORTANT): SBIT PASSED
2019-03-08T21:57:58.430Z,1552082278.430 [CommandLine](IMPORTANT): got command configSet list
2019-03-08T21:57:58.430Z,1552082278.430 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-08T21:57:58.431Z,1552082278.431 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-08T21:57:58.431Z,1552082278.431 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=20 count;

2019-03-08T21:57:58.756Z,1552082278.756 [MissionManager](IMPORTANT): Started mission Startup
2019-03-08T21:57:58.757Z,1552082278.757 [Startup] Running Loop=1
2019-03-08T21:57:58.758Z,1552082278.758 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-08T21:57:58.758Z,1552082278.758 [Startup:A.GoToSurface] Running Loop=1
2019-03-08T21:57:58.758Z,1552082278.758 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-08T21:57:58.760Z,1552082278.760 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-08T21:57:58.761Z,1552082278.761 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-08T21:57:58.762Z,1552082278.762 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-08T21:57:58.763Z,1552082278.763 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-08T21:57:58.764Z,1552082278.764 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-08T21:57:58.767Z,1552082278.767 [Startup:StartupSatComms] Running Loop=1
2019-03-08T21:57:58.768Z,1552082278.768 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-08T21:57:58.768Z,1552082278.768 [Startup:StartupSatComms:A] Running Loop=1
2019-03-08T21:57:59.161Z,1552082279.161 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-08T21:58:36.312Z,1552082316.312 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-08T21:58:36.312Z,1552082316.312 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-08T21:58:36.312Z,1552082316.312 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-08T21:58:36.314Z,1552082316.314 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-08T21:58:36.314Z,1552082316.314 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-08T21:58:36.314Z,1552082316.314 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-08T21:58:36.315Z,1552082316.315 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-08T21:58:36.315Z,1552082316.315 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-08T21:58:36.316Z,1552082316.316 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-08T21:58:36.327Z,1552082316.327 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-08T21:58:36.327Z,1552082316.327 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-08T21:58:36.327Z,1552082316.327 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-08T21:58:36.722Z,1552082316.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-08T21:58:36.722Z,1552082316.722 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-08T21:58:36.722Z,1552082316.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-08T21:58:36.722Z,1552082316.722 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-08T21:58:36.723Z,1552082316.723 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-08T21:58:36.723Z,1552082316.723 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-08T21:58:37.120Z,1552082317.120 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-08T21:58:58.958Z,1552082338.958 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-08T21:57:58.8Z
2019-03-08T21:58:58.958Z,1552082338.958 [Startup:StartupSatComms:A] Stopped
2019-03-08T21:58:58.958Z,1552082338.958 [Startup:StartupSatComms:B] Running Loop=1
2019-03-08T21:58:59.343Z,1552082339.343 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-08T21:59:02.395Z,1552082342.395 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004957
2019-03-08T21:59:07.369Z,1552082347.369 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20190308T214901/Courier0004.lzma
2019-03-08T21:59:08.174Z,1552082348.174 [DataOverHttps](INFO): Moved sent file to Logs/20190308T214901/Courier0004.lzma.bak
2019-03-08T21:59:08.175Z,1552082348.175 [DataOverHttps](INFO): SBD MOMSN=10117018
2019-03-08T21:59:19.274Z,1552082359.274 [DataOverHttps](INFO): Sending 1164 bytes from file Logs/20190308T214901/Express0005.lzma
2019-03-08T21:59:20.078Z,1552082360.078 [DataOverHttps](INFO): Moved sent file to Logs/20190308T214901/Express0005.lzma.bak
2019-03-08T21:59:20.079Z,1552082360.079 [DataOverHttps](INFO): SBD MOMSN=10117021
2019-03-08T21:59:36.606Z,1552082376.606 [DataOverHttps](INFO): Sending 1005 bytes from file Logs/20190308T215625/Express0001.lzma
2019-03-08T21:59:37.410Z,1552082377.410 [DataOverHttps](INFO): Moved sent file to Logs/20190308T215625/Express0001.lzma.bak
2019-03-08T21:59:37.411Z,1552082377.411 [DataOverHttps](INFO): SBD MOMSN=10117096
2019-03-08T21:59:38.198Z,1552082378.198 [Startup:StartupSatComms:B] Stopped
2019-03-08T21:59:38.199Z,1552082378.199 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-08T21:59:38.199Z,1552082378.199 [Startup:StartupSatComms] Stopped
2019-03-08T21:59:38.199Z,1552082378.199 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-08T21:59:38.200Z,1552082378.200 [Startup](INFO): Completed Startup
2019-03-08T21:59:38.200Z,1552082378.200 [MissionManager](INFO): Startup is completed.
2019-03-08T21:59:38.200Z,1552082378.200 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-08T21:59:38.200Z,1552082378.200 [Startup] Stopped
2019-03-08T21:59:38.200Z,1552082378.200 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-08T21:59:38.200Z,1552082378.200 [Startup:A.GoToSurface] Stopped
2019-03-08T21:59:38.200Z,1552082378.200 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-08T21:59:38.532Z,1552082378.532 [MissionManager](IMPORTANT): Started mission Default
2019-03-08T21:59:38.532Z,1552082378.532 [Default] Running Loop=1
2019-03-08T21:59:38.532Z,1552082378.532 [Default](DEBUG): Aggregate::initialize Default
2019-03-08T21:59:38.532Z,1552082378.532 [Default:B.GoToSurface] Running Loop=1
2019-03-08T21:59:38.532Z,1552082378.532 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-08T21:59:38.533Z,1552082378.533 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-08T21:59:38.533Z,1552082378.533 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-08T21:59:38.533Z,1552082378.533 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-08T21:59:38.534Z,1552082378.534 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-08T21:59:38.534Z,1552082378.534 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-08T21:59:38.534Z,1552082378.534 [Default:A.Wait] Running Loop=1
2019-03-08T21:59:38.534Z,1552082378.534 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-08T21:59:51.925Z,1552082391.925 [Default:A.Wait](INFO): Done Waiting.
2019-03-08T21:59:51.925Z,1552082391.925 [Default:A.Wait] Stopped
2019-03-08T21:59:51.925Z,1552082391.925 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-08T21:59:52.265Z,1552082392.265 [Default:CheckIn] Running Loop=1
2019-03-08T21:59:52.266Z,1552082392.266 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-08T21:59:52.266Z,1552082392.266 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-08T21:59:52.687Z,1552082392.687 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-08T22:00:37.125Z,1552082437.125 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-08T22:00:37.125Z,1552082437.125 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-08T22:00:37.126Z,1552082437.126 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-08T22:00:37.127Z,1552082437.127 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-08T22:00:37.127Z,1552082437.127 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-08T22:00:37.127Z,1552082437.127 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-08T22:00:37.128Z,1552082437.128 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-08T22:00:37.129Z,1552082437.129 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-08T22:00:37.129Z,1552082437.129 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-08T22:00:37.140Z,1552082437.140 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-08T22:00:37.140Z,1552082437.140 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-08T22:00:37.140Z,1552082437.140 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-08T22:00:37.543Z,1552082437.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-08T22:00:37.543Z,1552082437.543 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-08T22:00:37.544Z,1552082437.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-08T22:00:37.544Z,1552082437.544 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-08T22:00:37.544Z,1552082437.544 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-08T22:00:37.544Z,1552082437.544 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-08T22:00:37.925Z,1552082437.925 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-08T22:01:30.028Z,1552082490.028 [CommandLine](IMPORTANT): got command report
2019-03-08T22:01:32.307Z,1552082492.307 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-03-08T22:01:32.344Z,1552082492.344 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-08T22:01:32.344Z,1552082492.344 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-08T22:01:32.345Z,1552082492.345 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-08T22:01:32.345Z,1552082492.345 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-08T22:01:32.346Z,1552082492.346 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-08T22:01:32.346Z,1552082492.346 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-08T22:01:32.346Z,1552082492.346 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-08T22:01:32.347Z,1552082492.347 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-08T22:01:32.347Z,1552082492.347 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-08T22:01:32.347Z,1552082492.347 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-08T22:01:32.348Z,1552082492.348 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-08T22:01:32.348Z,1552082492.348 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-08T22:01:32.400Z,1552082492.400 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-08T22:01:32.400Z,1552082492.400 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-08T22:01:32.400Z,1552082492.400 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-08T22:01:32.405Z,1552082492.405 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-08T22:01:32.406Z,1552082492.406 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-08T22:01:32.406Z,1552082492.406 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-08T22:01:32.406Z,1552082492.406 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-08T22:01:32.407Z,1552082492.407 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-08T22:01:32.407Z,1552082492.407 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-08T22:01:32.407Z,1552082492.407 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-08T22:01:32.408Z,1552082492.408 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-08T22:01:32.408Z,1552082492.408 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-08T22:01:32.431Z,1552082492.431 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-08T22:01:41.782Z,1552082501.782 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.direction
2019-03-08T22:01:48.443Z,1552082508.443 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.xCenter
2019-03-08T22:01:54.147Z,1552082514.147 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-03-08T22:01:54.147Z,1552082514.147 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-08T22:01:54.173Z,1552082514.173 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-08T22:01:54.176Z,1552082514.176 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-08T22:01:54.180Z,1552082514.180 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-08T22:01:54.183Z,1552082514.183 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-08T22:01:54.188Z,1552082514.188 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-08T22:01:54.189Z,1552082514.189 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-08T22:01:54.195Z,1552082514.195 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-08T22:01:54.203Z,1552082514.203 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-08T22:01:54.207Z,1552082514.207 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="NumberOfRequests"><Description>
        Number of pings to requst.
    </Description><Units:count/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>   

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>      
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Science:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <ReadData>
        <While>
            <Arg Name="EnabledDUSBL"/>
        </While>
        <Sensor:DUSBL_Hydroid.range/>
        <Sensor:DUSBL_Hydroid.direction/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

        <Sequence Repeat="NumberOfRequests"/>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.transponderCode/><Arg Name="TransponderCode"/></Assign>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.numberOfPingsRequested/><Arg Name="NumberOfPings"/></Assign>
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>10</Value></Setting>
          </Guidance:Wait>
      </Aggregate>    
</Mission>


2019-03-08T22:01:54.210Z,1552082514.210 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-03-08T22:01:54.270Z,1552082514.270 [Default] Stopped
2019-03-08T22:01:54.270Z,1552082514.270 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-08T22:01:54.270Z,1552082514.270 [Default:B.GoToSurface] Stopped
2019-03-08T22:01:54.270Z,1552082514.270 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-08T22:01:54.270Z,1552082514.270 [Default:CheckIn] Stopped
2019-03-08T22:01:54.270Z,1552082514.270 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-08T22:01:54.270Z,1552082514.270 [Default:CheckIn:Read_GPS] Stopped
2019-03-08T22:01:54.271Z,1552082514.271 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-08T22:01:54.271Z,1552082514.271 [DUSBL] Running Loop=1
2019-03-08T22:01:54.271Z,1552082514.271 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-08T22:01:54.271Z,1552082514.271 [DUSBL:A.Pitch] Running Loop=1
2019-03-08T22:01:54.271Z,1552082514.271 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-08T22:01:54.271Z,1552082514.271 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-08T22:01:54.271Z,1552082514.271 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-08T22:01:54.272Z,1552082514.272 [DUSBL:C] Running Loop=1
2019-03-08T22:01:54.272Z,1552082514.272 [DUSBL:RequestRepeater] Running Loop=1
2019-03-08T22:01:54.272Z,1552082514.272 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-08T22:01:54.272Z,1552082514.272 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-08T22:01:54.272Z,1552082514.272 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-08T22:01:54.272Z,1552082514.272 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-08T22:01:54.272Z,1552082514.272 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-08T22:01:54.273Z,1552082514.273 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-08T22:01:54.273Z,1552082514.273 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-08T22:01:54.273Z,1552082514.273 [DUSBL:C] Running Loop=1
2019-03-08T22:01:54.277Z,1552082514.277 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-08T22:01:54.278Z,1552082514.278 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-08T22:01:54.278Z,1552082514.278 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-08T22:01:54.278Z,1552082514.278 [DUSBL:A.Pitch] Running Loop=1
2019-03-08T22:01:55.860Z,1552082515.860 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:01:55.862Z,1552082515.862 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:01:55.864Z,1552082515.864 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:01:55.865Z,1552082515.865 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:01:55.865Z,1552082515.865 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:01:59.907Z,1552082519.907 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:01:59.911Z,1552082519.911 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:01:59.912Z,1552082519.912 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:01:59.912Z,1552082519.912 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:01:59.912Z,1552082519.912 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:03.943Z,1552082523.943 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:03.945Z,1552082523.945 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:03.947Z,1552082523.947 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:03.948Z,1552082523.948 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:03.948Z,1552082523.948 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:04.374Z,1552082524.374 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-08T22:02:04.374Z,1552082524.374 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-08T22:02:04.374Z,1552082524.374 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-08T22:02:04.375Z,1552082524.375 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-08T22:02:04.375Z,1552082524.375 [DUSBL:RequestRepeater] Stopped
2019-03-08T22:02:04.375Z,1552082524.375 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-08T22:02:04.375Z,1552082524.375 [DUSBL:RequestRepeater:A] Stopped
2019-03-08T22:02:04.375Z,1552082524.375 [DUSBL:RequestRepeater:B] Stopped
2019-03-08T22:02:04.376Z,1552082524.376 [DUSBL:RequestRepeater](INFO): Running loop #2
2019-03-08T22:02:04.376Z,1552082524.376 [DUSBL:RequestRepeater] Running Loop=2
2019-03-08T22:02:04.376Z,1552082524.376 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-08T22:02:04.376Z,1552082524.376 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-08T22:02:04.376Z,1552082524.376 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-08T22:02:04.376Z,1552082524.376 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-08T22:02:04.376Z,1552082524.376 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-08T22:02:07.979Z,1552082527.979 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:07.982Z,1552082527.982 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:07.983Z,1552082527.983 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:07.984Z,1552082527.984 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:07.984Z,1552082527.984 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:12.019Z,1552082532.019 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:12.024Z,1552082532.024 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:12.024Z,1552082532.024 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:12.024Z,1552082532.024 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:12.025Z,1552082532.025 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:14.892Z,1552082534.892 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-08T22:02:14.892Z,1552082534.892 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-08T22:02:14.892Z,1552082534.892 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-08T22:02:14.892Z,1552082534.892 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-08T22:02:14.893Z,1552082534.893 [DUSBL:RequestRepeater] Stopped
2019-03-08T22:02:14.893Z,1552082534.893 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-08T22:02:14.893Z,1552082534.893 [DUSBL:RequestRepeater:A] Stopped
2019-03-08T22:02:14.893Z,1552082534.893 [DUSBL:RequestRepeater:B] Stopped
2019-03-08T22:02:14.893Z,1552082534.893 [DUSBL:RequestRepeater](INFO): Running loop #3
2019-03-08T22:02:14.893Z,1552082534.893 [DUSBL:RequestRepeater] Running Loop=3
2019-03-08T22:02:14.893Z,1552082534.893 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-08T22:02:14.894Z,1552082534.894 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-08T22:02:14.894Z,1552082534.894 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-08T22:02:14.894Z,1552082534.894 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-08T22:02:14.894Z,1552082534.894 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-08T22:02:16.069Z,1552082536.069 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:16.071Z,1552082536.071 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:16.073Z,1552082536.073 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:16.073Z,1552082536.073 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:16.074Z,1552082536.074 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:16.474Z,1552082536.474 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-08T22:02:17.285Z,1552082537.285 [NAL9602](DEBUG): Fix Requested
2019-03-08T22:02:20.099Z,1552082540.099 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:20.103Z,1552082540.103 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:20.104Z,1552082540.104 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:20.104Z,1552082540.104 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:20.104Z,1552082540.104 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:24.139Z,1552082544.139 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:24.144Z,1552082544.144 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:24.144Z,1552082544.144 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:24.144Z,1552082544.144 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:24.145Z,1552082544.145 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:24.983Z,1552082544.983 [RDI_Pathfinder](ERROR): Failed to parse:
    +0.48,        +0.05,        +0.17,   9.58,  0.00
2019-03-08T22:02:25.386Z,1552082545.386 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-08T22:02:25.386Z,1552082545.386 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-08T22:02:25.386Z,1552082545.386 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-08T22:02:25.387Z,1552082545.387 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-08T22:02:25.387Z,1552082545.387 [DUSBL:RequestRepeater] Stopped
2019-03-08T22:02:25.387Z,1552082545.387 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-08T22:02:25.387Z,1552082545.387 [DUSBL:RequestRepeater:A] Stopped
2019-03-08T22:02:25.387Z,1552082545.387 [DUSBL:RequestRepeater:B] Stopped
2019-03-08T22:02:25.387Z,1552082545.387 [DUSBL:RequestRepeater](INFO): Running loop #4
2019-03-08T22:02:25.388Z,1552082545.388 [DUSBL:RequestRepeater] Running Loop=4
2019-03-08T22:02:25.388Z,1552082545.388 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-08T22:02:25.388Z,1552082545.388 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-08T22:02:25.388Z,1552082545.388 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-08T22:02:25.388Z,1552082545.388 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-08T22:02:25.388Z,1552082545.388 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-08T22:02:25.777Z,1552082545.777 [NAL9602](DEBUG): Fix Requested
2019-03-08T22:02:26.164Z,1552082546.164 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215800.00,A,3648.16273,N,12147.28334,W,0.428,0.00,080319,,,A*78
2019-03-08T22:02:26.167Z,1552082546.167 [NAL9602](INFO): GPS fix at 20190308T215800: (36.802712, -121.788056)
2019-03-08T22:02:28.179Z,1552082548.179 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:28.179Z,1552082548.179 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:28.180Z,1552082548.180 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:28.180Z,1552082548.180 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:28.180Z,1552082548.180 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:32.227Z,1552082552.227 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:32.229Z,1552082552.229 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:32.231Z,1552082552.231 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:32.231Z,1552082552.231 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:32.232Z,1552082552.232 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:35.902Z,1552082555.902 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-08T22:02:35.902Z,1552082555.902 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-08T22:02:35.902Z,1552082555.902 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-08T22:02:35.903Z,1552082555.903 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-08T22:02:35.903Z,1552082555.903 [DUSBL:RequestRepeater] Stopped
2019-03-08T22:02:35.903Z,1552082555.903 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-08T22:02:35.903Z,1552082555.903 [DUSBL:RequestRepeater:A] Stopped
2019-03-08T22:02:35.903Z,1552082555.903 [DUSBL:RequestRepeater:B] Stopped
2019-03-08T22:02:35.904Z,1552082555.904 [DUSBL:RequestRepeater](INFO): Running loop #5
2019-03-08T22:02:35.904Z,1552082555.904 [DUSBL:RequestRepeater] Running Loop=5
2019-03-08T22:02:35.904Z,1552082555.904 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-08T22:02:35.904Z,1552082555.904 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-08T22:02:35.904Z,1552082555.904 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-08T22:02:35.904Z,1552082555.904 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-08T22:02:35.904Z,1552082555.904 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-08T22:02:36.266Z,1552082556.266 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:36.270Z,1552082556.270 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:36.272Z,1552082556.272 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:36.272Z,1552082556.272 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:36.272Z,1552082556.272 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:38.320Z,1552082558.320 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-08T22:02:38.320Z,1552082558.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-08T22:02:38.320Z,1552082558.320 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-08T22:02:38.327Z,1552082558.327 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-08T22:02:38.327Z,1552082558.327 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-08T22:02:38.327Z,1552082558.327 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-08T22:02:38.328Z,1552082558.328 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-08T22:02:38.333Z,1552082558.333 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-08T22:02:38.333Z,1552082558.333 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-08T22:02:38.357Z,1552082558.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-08T22:02:38.357Z,1552082558.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-08T22:02:38.357Z,1552082558.357 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-08T22:02:38.754Z,1552082558.754 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-08T22:02:38.754Z,1552082558.754 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-08T22:02:38.756Z,1552082558.756 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-08T22:02:38.756Z,1552082558.756 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-08T22:02:38.761Z,1552082558.761 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-08T22:02:38.762Z,1552082558.762 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-08T22:02:39.148Z,1552082559.148 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-08T22:02:40.299Z,1552082560.299 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-08T22:02:40.299Z,1552082560.299 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-08T22:02:40.300Z,1552082560.300 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:40.300Z,1552082560.300 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:40.300Z,1552082560.300 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-08T22:02:41.091Z,1552082561.091 [CommandLine](IMPORTANT): got command stop
2019-03-08T22:02:41.091Z,1552082561.091 [CommandLine](IMPORTANT): Scheduling is paused
2019-03-08T22:02:41.091Z,1552082561.091 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-03-08T22:02:41.146Z,1552082561.146 [MissionManager](INFO): MissionManager is completed.
2019-03-08T22:02:41.147Z,1552082561.147 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL] Stopped
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:A.Pitch] Stopped
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:B.SetSpeed] Stopped
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:C] Stopped
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:RequestRepeater] Stopped
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:RequestRepeater:A] Stopped
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:RequestRepeater:B] Stopped
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-08T22:02:41.147Z,1552082561.147 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-08T22:02:41.536Z,1552082561.536 [MissionManager](IMPORTANT): Started mission Default
2019-03-08T22:02:41.536Z,1552082561.536 [Default] Running Loop=1
2019-03-08T22:02:41.536Z,1552082561.536 [Default](DEBUG): Aggregate::initialize Default
2019-03-08T22:02:41.536Z,1552082561.536 [Default:B.GoToSurface] Running Loop=1
2019-03-08T22:02:41.536Z,1552082561.536 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-08T22:02:41.537Z,1552082561.537 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-08T22:02:41.537Z,1552082561.537 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-08T22:02:41.538Z,1552082561.538 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-08T22:02:41.538Z,1552082561.538 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-08T22:02:41.538Z,1552082561.538 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-08T22:02:41.538Z,1552082561.538 [Default:A.Wait] Running Loop=1
2019-03-08T22:02:41.539Z,1552082561.539 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-08T22:02:48.299Z,1552082568.299 [CommandLine](IMPORTANT): got command show variable detectionThresh
2019-03-08T22:02:48.357Z,1552082568.357 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-08T22:02:54.893Z,1552082574.893 [Default:A.Wait](INFO): Done Waiting.
2019-03-08T22:02:54.893Z,1552082574.893 [Default:A.Wait] Stopped
2019-03-08T22:02:54.893Z,1552082574.893 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-08T22:02:55.276Z,1552082575.276 [Default:CheckIn] Running Loop=1
2019-03-08T22:02:55.277Z,1552082575.277 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-08T22:02:55.277Z,1552082575.277 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-08T22:02:56.885Z,1552082576.885 [NAL9602](DEBUG): Fix Requested
2019-03-08T22:02:57.269Z,1552082577.269 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215831.00,A,3648.16033,N,12147.27969,W,0.097,0.00,080319,,,A*71
2019-03-08T22:02:57.271Z,1552082577.271 [NAL9602](INFO): GPS fix at 20190308T215831: (36.802672, -121.787995)
2019-03-08T22:02:57.349Z,1552082577.349 [Default:CheckIn:Read_GPS] Stopped
2019-03-08T22:02:57.350Z,1552082577.350 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-08T22:02:57.741Z,1552082577.741 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-08T22:03:01.531Z,1552082581.531 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.detectionThreshold 9.000000 count persist
2019-03-08T22:03:02.669Z,1552082582.669 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190308T215625/Courier0004.lzma
2019-03-08T22:03:03.474Z,1552082583.474 [DataOverHttps](INFO): Moved sent file to Logs/20190308T215625/Courier0004.lzma.bak
2019-03-08T22:03:03.474Z,1552082583.474 [DataOverHttps](INFO): SBD MOMSN=10117128
2019-03-08T22:03:05.666Z,1552082585.666 [CommandLine](IMPORTANT): got command restart application
2019-03-08T22:03:06.669Z,1552082586.669 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-08T22:03:06.669Z,1552082586.669 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:06.837Z,1552082586.837 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-08T22:03:06.837Z,1552082586.837 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:06.838Z,1552082586.838 [CommandLine](INFO): Join timeout helper Thread ID is 6816
2019-03-08T22:03:06.857Z,1552082586.857 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-08T22:03:06.857Z,1552082586.857 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:06.857Z,1552082586.857 [NavChartDb](INFO): Join timeout helper Thread ID is 6817
2019-03-08T22:03:07.181Z,1552082587.181 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-08T22:03:07.181Z,1552082587.181 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:07.189Z,1552082587.189 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-08T22:03:07.189Z,1552082587.189 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:07.189Z,1552082587.189 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 6818
2019-03-08T22:03:07.365Z,1552082587.365 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-08T22:03:07.365Z,1552082587.365 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-08T22:03:07.366Z,1552082587.366 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:07.377Z,1552082587.377 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-08T22:03:07.377Z,1552082587.377 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:07.377Z,1552082587.377 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6819
2019-03-08T22:03:07.457Z,1552082587.457 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-08T22:03:07.457Z,1552082587.457 [CTD_NeilBrown](INFO): Powering down
2019-03-08T22:03:07.469Z,1552082587.469 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:07.485Z,1552082587.485 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-08T22:03:07.485Z,1552082587.485 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:07.485Z,1552082587.485 [Radio_Surface](INFO): Join timeout helper Thread ID is 6820
2019-03-08T22:03:07.713Z,1552082587.713 [Radio_Surface](INFO): Powering down
2019-03-08T22:03:07.714Z,1552082587.714 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-08T22:03:07.714Z,1552082587.714 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:07.722Z,1552082587.722 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-08T22:03:07.722Z,1552082587.722 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:07.722Z,1552082587.722 [DataOverHttps](INFO): Join timeout helper Thread ID is 6821
2019-03-08T22:03:08.152Z,1552082588.152 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-08T22:03:08.156Z,1552082588.156 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.170Z,1552082588.170 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-08T22:03:08.170Z,1552082588.170 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.170Z,1552082588.170 [logger](INFO): Join timeout helper Thread ID is 6822
2019-03-08T22:03:08.193Z,1552082588.193 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-08T22:03:08.193Z,1552082588.193 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.214Z,1552082588.214 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-08T22:03:08.214Z,1552082588.214 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.214Z,1552082588.214 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-08T22:03:08.214Z,1552082588.214 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.214Z,1552082588.214 [controlThread](INFO): Join timeout helper Thread ID is 6823
2019-03-08T22:03:08.250Z,1552082588.250 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-08T22:03:08.251Z,1552082588.251 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-08T22:03:08.251Z,1552082588.251 [DUSBL_Hydroid](INFO): Powering down
2019-03-08T22:03:08.321Z,1552082588.321 [Micromodem](INFO): uninitialize
2019-03-08T22:03:08.321Z,1552082588.321 [Micromodem](INFO): Powering down
2019-03-08T22:03:08.417Z,1552082588.417 [NAL9602](INFO): Powering down
2019-03-08T22:03:08.489Z,1552082588.489 [RDI_Pathfinder](INFO): Powering down
2019-03-08T22:03:08.490Z,1552082588.490 [RDI_PathfinderUp](INFO): Powering down
2019-03-08T22:03:08.491Z,1552082588.491 [Aanderaa_O2](INFO): Powering down
2019-03-08T22:03:08.492Z,1552082588.492 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-08T22:03:08.493Z,1552082588.493 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-08T22:03:08.494Z,1552082588.494 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-08T22:03:08.495Z,1552082588.495 [MissionManager](INFO): Uninitializing Mission Default
2019-03-08T22:03:08.495Z,1552082588.495 [Default] Stopped
2019-03-08T22:03:08.495Z,1552082588.495 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-08T22:03:08.495Z,1552082588.495 [Default:B.GoToSurface] Stopped
2019-03-08T22:03:08.495Z,1552082588.495 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-08T22:03:08.495Z,1552082588.495 [Default:CheckIn] Stopped
2019-03-08T22:03:08.495Z,1552082588.495 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-08T22:03:08.495Z,1552082588.495 [Default:CheckIn:Read_Iridium] Stopped
2019-03-08T22:03:08.498Z,1552082588.498 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-08T22:03:08.498Z,1552082588.498 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-08T22:03:08.498Z,1552082588.498 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-08T22:03:08.499Z,1552082588.499 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-08T22:03:08.499Z,1552082588.499 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-08T22:03:08.499Z,1552082588.499 [BuoyancyServo](INFO): Powering down
2019-03-08T22:03:08.513Z,1552082588.513 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-08T22:03:08.513Z,1552082588.513 [ElevatorServo](INFO): Powering down
2019-03-08T22:03:08.514Z,1552082588.514 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-08T22:03:08.514Z,1552082588.514 [MassServo](INFO): Powering down
2019-03-08T22:03:08.515Z,1552082588.515 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-08T22:03:08.515Z,1552082588.515 [RudderServo](INFO): Powering down
2019-03-08T22:03:08.516Z,1552082588.516 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-08T22:03:08.516Z,1552082588.516 [ThrusterServo](INFO): Powering down
2019-03-08T22:03:08.517Z,1552082588.517 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-08T22:03:08.517Z,1552082588.517 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-08T22:03:08.517Z,1552082588.517 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-08T22:03:08.517Z,1552082588.517 [CBIT](DEBUG): Powering off loads.
2019-03-08T22:03:08.529Z,1552082588.529 [CBIT](DEBUG): Disabling WDT.
2019-03-08T22:03:08.541Z,1552082588.541 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-08T22:03:08.542Z,1552082588.542 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.612Z,1552082588.612 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.622Z,1552082588.622 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.664Z,1552082588.664 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.666Z,1552082588.666 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.727Z,1552082588.727 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-08T22:03:08.801Z,1552082588.801 [logger ThreadHandler](INFO): Thread cancelled.