2019-03-07T20:22:31.207Z,1551990151.207 [Supervisor](DEBUG): Initializing supervisor.
2019-03-07T20:22:31.210Z,1551990151.210 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-07T20:22:31.210Z,1551990151.210 [SyncHandler](INFO): Protected caller Thread ID is 9845
2019-03-07T20:22:31.211Z,1551990151.211 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-07T20:22:31.212Z,1551990151.212 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-07T20:22:31.212Z,1551990151.212 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9846
2019-03-07T20:22:31.215Z,1551990151.215 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-07T20:22:31.227Z,1551990151.227 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-07T20:22:31.228Z,1551990151.228 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-07T20:22:31.228Z,1551990151.228 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9847
2019-03-07T20:22:31.229Z,1551990151.229 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-07T20:22:31.230Z,1551990151.230 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-07T20:22:31.231Z,1551990151.231 [logger ThreadHandler](INFO): Protected caller Thread ID is 9848
2019-03-07T20:22:31.232Z,1551990151.232 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-07T20:22:31.233Z,1551990151.233 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-07T20:22:31.234Z,1551990151.234 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-07T20:22:31.721Z,1551990151.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-07T20:22:31.721Z,1551990151.721 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-07T20:22:31.820Z,1551990151.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-07T20:22:31.820Z,1551990151.820 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-07T20:22:32.212Z,1551990152.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-07T20:22:32.212Z,1551990152.212 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-07T20:22:32.895Z,1551990152.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-07T20:22:32.896Z,1551990152.896 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-07T20:22:33.094Z,1551990153.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-07T20:22:33.094Z,1551990153.094 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-07T20:22:33.561Z,1551990153.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-07T20:22:33.562Z,1551990153.562 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-07T20:22:33.777Z,1551990153.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-07T20:22:33.778Z,1551990153.778 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-07T20:22:33.927Z,1551990153.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-07T20:22:33.927Z,1551990153.927 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-07T20:22:34.128Z,1551990154.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-07T20:22:34.129Z,1551990154.129 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-07T20:22:34.225Z,1551990154.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-07T20:22:34.226Z,1551990154.226 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-07T20:22:34.543Z,1551990154.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-07T20:22:34.544Z,1551990154.544 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-07T20:22:34.625Z,1551990154.625 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-07T20:22:34.729Z,1551990154.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-07T20:22:34.730Z,1551990154.730 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-07T20:22:35.237Z,1551990155.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-07T20:22:35.238Z,1551990155.238 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-07T20:22:35.638Z,1551990155.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-07T20:22:35.640Z,1551990155.640 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-07T20:22:35.641Z,1551990155.641 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-07T20:22:35.855Z,1551990155.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-07T20:22:35.957Z,1551990155.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-07T20:22:36.057Z,1551990156.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-07T20:22:36.286Z,1551990156.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-07T20:22:36.287Z,1551990156.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-07T20:22:36.373Z,1551990156.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-07T20:22:36.467Z,1551990156.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-07T20:22:36.566Z,1551990156.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-07T20:22:36.650Z,1551990156.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-07T20:22:36.759Z,1551990156.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-07T20:22:36.936Z,1551990156.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-07T20:22:37.072Z,1551990157.072 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-07T20:22:37.073Z,1551990157.073 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-07T20:22:37.077Z,1551990157.077 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-07T20:22:37.391Z,1551990157.391 [DataOverHttps] Loaded
2019-03-07T20:22:37.392Z,1551990157.392 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-07T20:22:37.393Z,1551990157.393 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0
2019-03-07T20:22:37.393Z,1551990157.393 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9927
2019-03-07T20:22:37.432Z,1551990157.432 [Depth_Keller] Loaded
2019-03-07T20:22:37.433Z,1551990157.433 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-07T20:22:37.437Z,1551990157.437 [DropWeight] Loaded
2019-03-07T20:22:37.437Z,1551990157.437 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-07T20:22:37.484Z,1551990157.484 [DUSBL_Hydroid] Loaded
2019-03-07T20:22:37.485Z,1551990157.485 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-07T20:22:37.524Z,1551990157.524 [Micromodem] Loaded
2019-03-07T20:22:37.525Z,1551990157.525 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-07T20:22:37.683Z,1551990157.683 [NAL9602] Loaded
2019-03-07T20:22:37.683Z,1551990157.683 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-07T20:22:37.710Z,1551990157.710 [Onboard] Loaded
2019-03-07T20:22:37.710Z,1551990157.710 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-07T20:22:37.717Z,1551990157.717 [Radio_Surface] Loaded
2019-03-07T20:22:37.717Z,1551990157.717 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-07T20:22:37.718Z,1551990157.718 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0
2019-03-07T20:22:37.718Z,1551990157.718 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9928
2019-03-07T20:22:37.952Z,1551990157.952 [RDI_Pathfinder] Loaded
2019-03-07T20:22:37.953Z,1551990157.953 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-07T20:22:38.039Z,1551990158.039 [RDI_PathfinderUp] Loaded
2019-03-07T20:22:38.040Z,1551990158.040 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-07T20:22:39.960Z,1551990159.960 [BPC1] Loaded
2019-03-07T20:22:39.960Z,1551990159.960 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-07T20:22:39.961Z,1551990159.961 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-07T20:22:39.961Z,1551990159.961 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-07T20:22:39.974Z,1551990159.974 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-07T20:22:39.975Z,1551990159.975 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-07T20:22:40.078Z,1551990160.078 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-07T20:22:40.079Z,1551990160.079 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-07T20:22:40.136Z,1551990160.136 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-07T20:22:40.136Z,1551990160.136 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-07T20:22:40.195Z,1551990160.195 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-07T20:22:40.195Z,1551990160.195 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-07T20:22:40.215Z,1551990160.215 [NavChart] Loaded
2019-03-07T20:22:40.215Z,1551990160.215 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-07T20:22:40.219Z,1551990160.219 [UniversalFixResidualReporter] Loaded
2019-03-07T20:22:40.219Z,1551990160.219 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-07T20:22:40.220Z,1551990160.220 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-07T20:22:40.220Z,1551990160.220 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-07T20:22:40.319Z,1551990160.319 [BuoyancyServo] Loaded
2019-03-07T20:22:40.319Z,1551990160.319 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-07T20:22:40.334Z,1551990160.334 [ElevatorServo] Loaded
2019-03-07T20:22:40.334Z,1551990160.334 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-07T20:22:40.348Z,1551990160.348 [MassServo] Loaded
2019-03-07T20:22:40.349Z,1551990160.349 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-07T20:22:40.364Z,1551990160.364 [RudderServo] Loaded
2019-03-07T20:22:40.364Z,1551990160.364 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-07T20:22:40.378Z,1551990160.378 [ThrusterServo] Loaded
2019-03-07T20:22:40.378Z,1551990160.378 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-07T20:22:40.379Z,1551990160.379 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-07T20:22:40.379Z,1551990160.379 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-07T20:22:40.556Z,1551990160.556 [Aanderaa_O2] Loaded
2019-03-07T20:22:40.556Z,1551990160.556 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-03-07T20:22:40.640Z,1551990160.640 [CTD_NeilBrown] Loaded
2019-03-07T20:22:40.640Z,1551990160.640 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-07T20:22:40.642Z,1551990160.642 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2019-03-07T20:22:40.642Z,1551990160.642 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9929
2019-03-07T20:22:40.685Z,1551990160.685 [WetLabsSeaOWL_UV_A] Loaded
2019-03-07T20:22:40.686Z,1551990160.686 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-07T20:22:40.687Z,1551990160.687 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2019-03-07T20:22:40.687Z,1551990160.687 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 9930
2019-03-07T20:22:40.688Z,1551990160.688 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-07T20:22:40.688Z,1551990160.688 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-07T20:22:40.919Z,1551990160.919 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-07T20:22:40.920Z,1551990160.920 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-07T20:22:40.959Z,1551990160.959 [DepthRateCalculator] Loaded
2019-03-07T20:22:40.960Z,1551990160.960 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-07T20:22:40.966Z,1551990160.966 [PitchRateCalculator] Loaded
2019-03-07T20:22:40.966Z,1551990160.966 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-07T20:22:40.978Z,1551990160.978 [SpeedCalculator] Loaded
2019-03-07T20:22:40.978Z,1551990160.978 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-07T20:22:40.999Z,1551990160.999 [TempGradientCalculator] Loaded
2019-03-07T20:22:40.999Z,1551990160.999 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-07T20:22:41.005Z,1551990161.005 [YawRateCalculator] Loaded
2019-03-07T20:22:41.005Z,1551990161.005 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-07T20:22:41.045Z,1551990161.045 [ElevatorOffsetCalculator] Loaded
2019-03-07T20:22:41.045Z,1551990161.045 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-07T20:22:41.046Z,1551990161.046 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-07T20:22:41.046Z,1551990161.046 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-07T20:22:41.175Z,1551990161.175 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-07T20:22:41.197Z,1551990161.197 [SBIT] Loaded
2019-03-07T20:22:41.198Z,1551990161.198 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-07T20:22:41.198Z,1551990161.198 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-07T20:22:41.210Z,1551990161.210 [IBIT] Loaded
2019-03-07T20:22:41.210Z,1551990161.210 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-07T20:22:41.213Z,1551990161.213 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-07T20:22:41.349Z,1551990161.349 [CBIT] Loaded
2019-03-07T20:22:41.350Z,1551990161.350 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-07T20:22:41.350Z,1551990161.350 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-07T20:22:41.351Z,1551990161.351 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-07T20:22:41.412Z,1551990161.412 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-07T20:22:41.413Z,1551990161.413 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-07T20:22:41.507Z,1551990161.507 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-07T20:22:41.508Z,1551990161.508 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-07T20:22:41.572Z,1551990161.572 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-07T20:22:41.655Z,1551990161.655 [VerticalControl] Loaded
2019-03-07T20:22:41.655Z,1551990161.655 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-07T20:22:41.656Z,1551990161.656 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-07T20:22:41.712Z,1551990161.712 [HorizontalControl] Loaded
2019-03-07T20:22:41.712Z,1551990161.712 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-07T20:22:41.713Z,1551990161.713 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-07T20:22:41.714Z,1551990161.714 [SpeedControl] Loaded
2019-03-07T20:22:41.715Z,1551990161.715 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-07T20:22:41.715Z,1551990161.715 [LoopControl](DEBUG): Construct LoopControl.
2019-03-07T20:22:41.716Z,1551990161.716 [LoopControl] Loaded
2019-03-07T20:22:41.716Z,1551990161.716 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-07T20:22:41.716Z,1551990161.716 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-07T20:22:41.717Z,1551990161.717 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-07T20:22:41.744Z,1551990161.744 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-07T20:22:41.747Z,1551990161.747 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-07T20:22:41.748Z,1551990161.748 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-07T20:22:41.755Z,1551990161.755 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-07T20:22:41.756Z,1551990161.756 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2019-03-07T20:22:41.756Z,1551990161.756 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9931
2019-03-07T20:22:41.761Z,1551990161.761 [Supervisor](INFO): Main Thread ID is 8039
2019-03-07T20:22:41.761Z,1551990161.761 [Supervisor](DEBUG): Running supervisor.
2019-03-07T20:22:41.762Z,1551990161.762 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9932
2019-03-07T20:22:41.764Z,1551990161.764 [controlThread ThreadHandler](INFO): Handler Thread ID is 9933
2019-03-07T20:22:41.764Z,1551990161.764 [controlThread](DEBUG): Initializing ControlThread
2019-03-07T20:22:41.773Z,1551990161.773 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-07T20:22:41.774Z,1551990161.774 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-07T20:22:41.774Z,1551990161.774 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-07T20:22:41.775Z,1551990161.775 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-07T20:22:41.776Z,1551990161.776 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-07T20:22:41.776Z,1551990161.776 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-07T20:22:41.776Z,1551990161.776 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-07T20:22:41.777Z,1551990161.777 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-07T20:22:41.777Z,1551990161.777 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-07T20:22:41.778Z,1551990161.778 [SBIT](INFO): Initialize SBIT Component.
2019-03-07T20:22:41.778Z,1551990161.778 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3
2019-03-07T20:22:41.778Z,1551990161.778 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa
2019-03-07T20:22:41.779Z,1551990161.779 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-07T20:22:41.779Z,1551990161.779 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-07T20:22:41.780Z,1551990161.780 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-07T20:22:41.780Z,1551990161.780 [IBIT](INFO): Initialize IBIT Component.
2019-03-07T20:22:41.781Z,1551990161.781 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-07T20:22:41.782Z,1551990161.782 [logger ThreadHandler](INFO): Handler Thread ID is 9934
2019-03-07T20:22:41.793Z,1551990161.793 [CBIT](DEBUG): Initialized mux pins.
2019-03-07T20:22:41.793Z,1551990161.793 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-07T20:22:41.801Z,1551990161.801 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9935
2019-03-07T20:22:41.802Z,1551990161.802 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-07T20:22:41.813Z,1551990161.813 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9936
2019-03-07T20:22:41.817Z,1551990161.817 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-07T20:22:41.817Z,1551990161.817 [CBIT](DEBUG): Initializing heartbeat.
2019-03-07T20:22:41.833Z,1551990161.833 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9937
2019-03-07T20:22:41.834Z,1551990161.834 [CTD_NeilBrown](INFO): Powering down
2019-03-07T20:22:41.873Z,1551990161.873 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 9938
2019-03-07T20:22:41.874Z,1551990161.874 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-07T20:22:41.889Z,1551990161.889 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-07T20:22:41.889Z,1551990161.889 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-07T20:22:41.893Z,1551990161.893 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9939
2019-03-07T20:22:41.900Z,1551990161.900 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-07T20:22:41.900Z,1551990161.900 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-07T20:22:41.901Z,1551990161.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-07T20:22:41.901Z,1551990161.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-07T20:22:41.901Z,1551990161.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-07T20:22:41.901Z,1551990161.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-07T20:22:41.902Z,1551990161.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-07T20:22:41.902Z,1551990161.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-07T20:22:41.902Z,1551990161.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-07T20:22:41.902Z,1551990161.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-07T20:22:41.902Z,1551990161.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-07T20:22:41.902Z,1551990161.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-07T20:22:41.903Z,1551990161.903 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-07T20:22:41.903Z,1551990161.903 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-07T20:22:41.903Z,1551990161.903 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-07T20:22:41.903Z,1551990161.903 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-07T20:22:41.925Z,1551990161.925 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-07T20:22:41.927Z,1551990161.927 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-07T20:22:41.928Z,1551990161.928 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-07T20:22:41.928Z,1551990161.928 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-07T20:22:41.929Z,1551990161.929 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-07T20:22:41.942Z,1551990161.942 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-07T20:22:41.969Z,1551990161.969 [MissionManager](DEBUG):
2019-03-07T20:22:41.970Z,1551990161.970 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-07T20:22:42.019Z,1551990162.019 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-07T20:22:42.020Z,1551990162.020 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-07T20:22:42.038Z,1551990162.038 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-07T20:22:42.065Z,1551990162.065 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-07T20:22:42.067Z,1551990162.067 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-07T20:22:42.089Z,1551990162.089 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-07T20:22:42.092Z,1551990162.092 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-03-07T20:22:42.105Z,1551990162.105 [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-07T20:22:42.217Z,1551990162.217 [Radio_Surface](INFO): Powering up
2019-03-07T20:22:42.220Z,1551990162.220 [DUSBL_Hydroid](INFO): Powering up
2019-03-07T20:22:42.233Z,1551990162.233 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-07T20:22:42.253Z,1551990162.253 [Micromodem](INFO): Start
2019-03-07T20:22:42.382Z,1551990162.382 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-07T20:22:42.389Z,1551990162.389 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-07T20:22:42.390Z,1551990162.390 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-07T20:22:42.397Z,1551990162.397 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-07T20:22:42.398Z,1551990162.398 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-07T20:22:42.405Z,1551990162.405 [MassServo](DEBUG): Initializing MassServo.
2019-03-07T20:22:42.406Z,1551990162.406 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-07T20:22:42.413Z,1551990162.413 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-07T20:22:42.414Z,1551990162.414 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-07T20:22:42.421Z,1551990162.421 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-07T20:22:42.531Z,1551990162.531 [Micromodem](INFO): Starting
2019-03-07T20:22:42.531Z,1551990162.531 [Micromodem](INFO): Powering up
2019-03-07T20:22:42.532Z,1551990162.532 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-07T20:22:42.629Z,1551990162.629 [RDI_PathfinderUp](INFO): Powering down
2019-03-07T20:22:42.922Z,1551990162.922 [Micromodem](INFO): Starting
2019-03-07T20:22:43.261Z,1551990163.261 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-07T20:22:43.261Z,1551990163.261 [RudderServo](FAULT): Rudder failed to initialize
2019-03-07T20:22:43.261Z,1551990163.261 [RudderServo] Communications Fault, FailCount= 1
2019-03-07T20:22:43.261Z,1551990163.261 [RudderServo](ERROR): Communications Fault
2019-03-07T20:22:43.384Z,1551990163.384 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-07T20:22:43.394Z,1551990163.394 [Micromodem](INFO): Starting
2019-03-07T20:22:43.564Z,1551990163.564 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-07T20:22:43.564Z,1551990163.564 [RudderServo](INFO): Powering down
2019-03-07T20:22:43.798Z,1551990163.798 [Micromodem](INFO): Starting
2019-03-07T20:22:44.214Z,1551990164.214 [Micromodem](INFO): Starting
2019-03-07T20:22:44.258Z,1551990164.258 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-07T20:22:44.378Z,1551990164.378 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-07T20:22:44.382Z,1551990164.382 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-07T20:22:44.382Z,1551990164.382 [RudderServo] No Fault, FailCount= 1
2019-03-07T20:22:44.606Z,1551990164.606 [Micromodem](INFO): Starting
2019-03-07T20:22:45.023Z,1551990165.023 [Micromodem](INFO): Starting
2019-03-07T20:22:45.414Z,1551990165.414 [Micromodem](INFO): Starting
2019-03-07T20:22:45.549Z,1551990165.549 [Aanderaa_O2](INFO): Powering down
2019-03-07T20:22:45.818Z,1551990165.818 [Micromodem](INFO): Starting
2019-03-07T20:22:46.222Z,1551990166.222 [Micromodem](INFO): Starting
2019-03-07T20:22:46.626Z,1551990166.626 [Micromodem](INFO): Starting
2019-03-07T20:22:47.030Z,1551990167.030 [Micromodem](INFO): Starting
2019-03-07T20:22:47.434Z,1551990167.434 [Micromodem](INFO): Starting
2019-03-07T20:22:47.434Z,1551990167.434 [Micromodem](INFO): Starting
2019-03-07T20:22:47.435Z,1551990167.435 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37
2019-03-07T20:22:47.435Z,1551990167.435 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37
2019-03-07T20:22:47.435Z,1551990167.435 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31
2019-03-07T20:22:47.436Z,1551990167.436 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32
2019-03-07T20:22:47.436Z,1551990167.436 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29
2019-03-07T20:22:47.838Z,1551990167.838 [Micromodem](INFO): Starting
2019-03-07T20:22:48.251Z,1551990168.251 [Micromodem](INFO): Starting
2019-03-07T20:22:48.646Z,1551990168.646 [Micromodem](INFO): Starting
2019-03-07T20:22:49.050Z,1551990169.050 [Micromodem](INFO): Starting
2019-03-07T20:22:49.455Z,1551990169.455 [Micromodem](INFO): Starting
2019-03-07T20:22:49.858Z,1551990169.858 [Micromodem](INFO): Starting
2019-03-07T20:22:50.264Z,1551990170.264 [Micromodem](INFO): Starting
2019-03-07T20:22:50.678Z,1551990170.678 [Micromodem](INFO): Starting
2019-03-07T20:22:51.070Z,1551990171.070 [Micromodem](INFO): Starting
2019-03-07T20:22:51.474Z,1551990171.474 [Micromodem](INFO): Starting
2019-03-07T20:22:51.878Z,1551990171.878 [Micromodem](INFO): Starting
2019-03-07T20:22:52.282Z,1551990172.282 [Micromodem](INFO): Starting
2019-03-07T20:22:52.686Z,1551990172.686 [Micromodem](INFO): Starting
2019-03-07T20:22:53.090Z,1551990173.090 [Micromodem](INFO): Starting
2019-03-07T20:22:53.494Z,1551990173.494 [Micromodem](INFO): Starting
2019-03-07T20:22:53.898Z,1551990173.898 [Micromodem](INFO): Starting
2019-03-07T20:22:54.302Z,1551990174.302 [Micromodem](INFO): Starting
2019-03-07T20:22:54.706Z,1551990174.706 [Micromodem](INFO): Starting
2019-03-07T20:22:55.110Z,1551990175.110 [Micromodem](INFO): Starting
2019-03-07T20:22:55.514Z,1551990175.514 [Micromodem](INFO): Starting
2019-03-07T20:22:55.540Z,1551990175.540 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-07T20:22:55.918Z,1551990175.918 [Micromodem](INFO): Starting
2019-03-07T20:22:56.322Z,1551990176.322 [Micromodem](INFO): Starting
2019-03-07T20:22:56.726Z,1551990176.726 [Micromodem](INFO): Starting
2019-03-07T20:22:57.130Z,1551990177.130 [Micromodem](INFO): Starting
2019-03-07T20:22:57.534Z,1551990177.534 [Micromodem](INFO): Starting
2019-03-07T20:22:57.938Z,1551990177.938 [Micromodem](INFO): Starting
2019-03-07T20:22:58.342Z,1551990178.342 [Micromodem](INFO): Starting
2019-03-07T20:22:58.746Z,1551990178.746 [Micromodem](INFO): Starting
2019-03-07T20:22:58.747Z,1551990178.747 [Micromodem](ERROR): Nmea resend: $CCCFG,CST,1*37
2019-03-07T20:22:59.150Z,1551990179.150 [Micromodem](INFO): Starting
2019-03-07T20:22:59.554Z,1551990179.554 [Micromodem](INFO): Starting
2019-03-07T20:22:59.959Z,1551990179.959 [Micromodem](INFO): Starting
2019-03-07T20:23:00.363Z,1551990180.363 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-07T20:23:00.377Z,1551990180.377 [Micromodem](INFO): Starting
2019-03-07T20:23:00.766Z,1551990180.766 [Micromodem](INFO): Starting
2019-03-07T20:23:01.170Z,1551990181.170 [Micromodem](INFO): Starting
2019-03-07T20:23:01.574Z,1551990181.574 [Micromodem](INFO): Starting
2019-03-07T20:23:01.978Z,1551990181.978 [Micromodem](INFO): Starting
2019-03-07T20:23:02.385Z,1551990182.385 [Micromodem](INFO): Starting
2019-03-07T20:23:02.786Z,1551990182.786 [Micromodem](INFO): Starting
2019-03-07T20:23:03.190Z,1551990183.190 [Micromodem](INFO): Starting
2019-03-07T20:23:03.594Z,1551990183.594 [Micromodem](INFO): Starting
2019-03-07T20:23:03.998Z,1551990183.998 [Micromodem](INFO): Starting
2019-03-07T20:23:04.413Z,1551990184.413 [Micromodem](INFO): Starting
2019-03-07T20:23:04.806Z,1551990184.806 [Micromodem](INFO): Starting
2019-03-07T20:23:05.210Z,1551990185.210 [Micromodem](INFO): Starting
2019-03-07T20:23:05.614Z,1551990185.614 [Micromodem](INFO): Starting
2019-03-07T20:23:06.018Z,1551990186.018 [Micromodem](INFO): Starting
2019-03-07T20:23:06.430Z,1551990186.430 [Micromodem](INFO): Starting
2019-03-07T20:23:06.826Z,1551990186.826 [Micromodem](INFO): Starting
2019-03-07T20:23:07.230Z,1551990187.230 [Micromodem](INFO): Starting
2019-03-07T20:23:07.634Z,1551990187.634 [Micromodem](INFO): Starting
2019-03-07T20:23:08.038Z,1551990188.038 [Micromodem](INFO): Starting
2019-03-07T20:23:08.039Z,1551990188.039 [NAL9602](INFO): Powering up NAL9602
2019-03-07T20:23:08.438Z,1551990188.438 [Micromodem](INFO): Starting
2019-03-07T20:23:08.843Z,1551990188.843 [Micromodem](INFO): Starting
2019-03-07T20:23:08.843Z,1551990188.843 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31
2019-03-07T20:23:09.250Z,1551990189.250 [Micromodem](INFO): Starting
2019-03-07T20:23:09.654Z,1551990189.654 [Micromodem](INFO): Starting
2019-03-07T20:23:10.058Z,1551990190.058 [Micromodem](INFO): Starting
2019-03-07T20:23:10.462Z,1551990190.462 [Micromodem](INFO): Starting
2019-03-07T20:23:10.529Z,1551990190.529 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-07T20:23:10.538Z,1551990190.538 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-07T20:23:10.866Z,1551990190.866 [Micromodem](INFO): Starting
2019-03-07T20:23:11.270Z,1551990191.270 [Micromodem](INFO): Starting
2019-03-07T20:23:11.674Z,1551990191.674 [Micromodem](INFO): Starting
2019-03-07T20:23:12.078Z,1551990192.078 [Micromodem](INFO): Starting
2019-03-07T20:23:12.482Z,1551990192.482 [Micromodem](INFO): Starting
2019-03-07T20:23:12.886Z,1551990192.886 [Micromodem](INFO): Starting
2019-03-07T20:23:13.484Z,1551990193.484 [Micromodem](INFO): Starting
2019-03-07T20:23:13.886Z,1551990193.886 [Micromodem](INFO): Starting
2019-03-07T20:23:14.290Z,1551990194.290 [Micromodem](INFO): Starting
2019-03-07T20:23:14.694Z,1551990194.694 [Micromodem](INFO): Starting
2019-03-07T20:23:15.098Z,1551990195.098 [Micromodem](INFO): Starting
2019-03-07T20:23:15.502Z,1551990195.502 [Micromodem](INFO): Starting
2019-03-07T20:23:15.906Z,1551990195.906 [Micromodem](INFO): Starting
2019-03-07T20:23:16.310Z,1551990196.310 [Micromodem](INFO): Starting
2019-03-07T20:23:16.714Z,1551990196.714 [Micromodem](INFO): Starting
2019-03-07T20:23:17.118Z,1551990197.118 [Micromodem](INFO): Starting
2019-03-07T20:23:17.522Z,1551990197.522 [Micromodem](INFO): Starting
2019-03-07T20:23:17.926Z,1551990197.926 [Micromodem](INFO): Starting
2019-03-07T20:23:18.330Z,1551990198.330 [Micromodem](INFO): Starting
2019-03-07T20:23:18.734Z,1551990198.734 [Micromodem](INFO): Starting
2019-03-07T20:23:18.735Z,1551990198.735 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32
2019-03-07T20:23:19.138Z,1551990199.138 [Micromodem](INFO): Starting
2019-03-07T20:23:19.139Z,1551990199.139 [NAL9602](INFO): NAL9602 initialized
2019-03-07T20:23:19.562Z,1551990199.562 [Micromodem](INFO): Starting
2019-03-07T20:23:19.946Z,1551990199.946 [Micromodem](INFO): Starting
2019-03-07T20:23:19.961Z,1551990199.961 [NAL9602](DEBUG): Fix Requested
2019-03-07T20:23:20.350Z,1551990200.350 [Micromodem](INFO): Starting
2019-03-07T20:23:20.754Z,1551990200.754 [Micromodem](INFO): Starting
2019-03-07T20:23:21.158Z,1551990201.158 [Micromodem](INFO): Starting
2019-03-07T20:23:21.253Z,1551990201.253 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.013162
CHAN A1 (24V): -0.026394
CHAN A2 (12V): -0.006797
CHAN A3 (5V): -0.001604
CHAN B0 (3.3V): -0.000616
CHAN B1 (3.15aV): -0.000366
CHAN B2 (3.15bV): -0.000258
CHAN B3 (GND): 0.000791
OPEN: 0.009394
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-07T20:23:21.562Z,1551990201.562 [Micromodem](INFO): Starting
2019-03-07T20:23:21.966Z,1551990201.966 [Micromodem](INFO): Starting
2019-03-07T20:23:22.374Z,1551990202.374 [Micromodem](INFO): Starting
2019-03-07T20:23:22.774Z,1551990202.774 [Micromodem](INFO): Starting
2019-03-07T20:23:23.178Z,1551990203.178 [Micromodem](INFO): Starting
2019-03-07T20:23:23.582Z,1551990203.582 [Micromodem](INFO): Starting
2019-03-07T20:23:23.986Z,1551990203.986 [Micromodem](INFO): Starting
2019-03-07T20:23:24.410Z,1551990204.410 [Micromodem](INFO): Starting
2019-03-07T20:23:24.794Z,1551990204.794 [Micromodem](INFO): Starting
2019-03-07T20:23:25.198Z,1551990205.198 [Micromodem](INFO): Starting
2019-03-07T20:23:25.602Z,1551990205.602 [Micromodem](INFO): Starting
2019-03-07T20:23:26.006Z,1551990206.006 [Micromodem](INFO): Starting
2019-03-07T20:23:26.494Z,1551990206.494 [Micromodem](INFO): Starting
2019-03-07T20:23:26.894Z,1551990206.894 [Micromodem](INFO): Starting
2019-03-07T20:23:27.298Z,1551990207.298 [Micromodem](INFO): Starting
2019-03-07T20:23:27.702Z,1551990207.702 [Micromodem](INFO): Starting
2019-03-07T20:23:28.106Z,1551990208.106 [Micromodem](INFO): Starting
2019-03-07T20:23:28.518Z,1551990208.518 [Micromodem](INFO): Starting
2019-03-07T20:23:28.914Z,1551990208.914 [Micromodem](INFO): Starting
2019-03-07T20:23:28.915Z,1551990208.915 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29
2019-03-07T20:23:29.318Z,1551990209.318 [Micromodem](INFO): Starting
2019-03-07T20:23:29.722Z,1551990209.722 [Micromodem](INFO): Starting
2019-03-07T20:23:30.126Z,1551990210.126 [Micromodem](INFO): Starting
2019-03-07T20:23:30.530Z,1551990210.530 [Micromodem](INFO): Starting
2019-03-07T20:23:30.934Z,1551990210.934 [Micromodem](INFO): Starting
2019-03-07T20:23:31.338Z,1551990211.338 [Micromodem](INFO): Starting
2019-03-07T20:23:31.742Z,1551990211.742 [Micromodem](INFO): Starting
2019-03-07T20:23:32.146Z,1551990212.146 [Micromodem](INFO): Starting
2019-03-07T20:23:32.550Z,1551990212.550 [Micromodem](INFO): Starting
2019-03-07T20:23:32.954Z,1551990212.954 [Micromodem](INFO): Starting
2019-03-07T20:23:33.355Z,1551990213.355 [Micromodem](INFO): Starting
2019-03-07T20:23:33.762Z,1551990213.762 [Micromodem](INFO): Starting
2019-03-07T20:23:34.166Z,1551990214.166 [Micromodem](INFO): Starting
2019-03-07T20:23:34.570Z,1551990214.570 [Micromodem](INFO): Starting
2019-03-07T20:23:34.974Z,1551990214.974 [Micromodem](INFO): Starting
2019-03-07T20:23:35.382Z,1551990215.382 [Micromodem](INFO): Starting
2019-03-07T20:23:35.782Z,1551990215.782 [Micromodem](INFO): Starting
2019-03-07T20:23:36.186Z,1551990216.186 [Micromodem](INFO): Starting
2019-03-07T20:23:36.590Z,1551990216.590 [Micromodem](INFO): Starting
2019-03-07T20:23:36.994Z,1551990216.994 [Micromodem](INFO): Starting
2019-03-07T20:23:37.408Z,1551990217.408 [Micromodem](INFO): Starting
2019-03-07T20:23:37.807Z,1551990217.807 [Micromodem](INFO): Starting
2019-03-07T20:23:38.206Z,1551990218.206 [Micromodem](INFO): Starting
2019-03-07T20:23:38.610Z,1551990218.610 [Micromodem](INFO): Starting
2019-03-07T20:23:38.611Z,1551990218.611 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-07T20:23:39Z,0*73
2019-03-07T20:23:38.612Z,1551990218.612 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-07T20:23:39Z,0*73
2019-03-07T20:23:39.016Z,1551990219.016 [Micromodem](INFO): Starting
2019-03-07T20:23:39.426Z,1551990219.426 [Micromodem](INFO): Starting
2019-03-07T20:23:39.826Z,1551990219.826 [Micromodem](INFO): Starting
2019-03-07T20:23:39.827Z,1551990219.827 [Micromodem](DEBUG): Nmea buf: $CCCFQ,TOP*33
2019-03-07T20:23:39.827Z,1551990219.827 [Micromodem](INFO): Nmea out: $CCCFQ,TOP*33
2019-03-07T20:23:40.227Z,1551990220.227 [Micromodem](INFO): Starting
2019-03-07T20:23:40.631Z,1551990220.631 [Micromodem](INFO): Starting
2019-03-07T20:23:40.635Z,1551990220.635 [Micromodem](INFO): Runnable
2019-03-07T20:23:41.039Z,1551990221.039 [Micromodem](INFO): Runnable
2019-03-07T20:23:41.439Z,1551990221.439 [Micromodem](INFO): Runnable
2019-03-07T20:23:41.843Z,1551990221.843 [Micromodem](INFO): Runnable
2019-03-07T20:23:42.249Z,1551990222.249 [Micromodem](INFO): Runnable
2019-03-07T20:23:42.655Z,1551990222.655 [Micromodem](INFO): Runnable
2019-03-07T20:23:43.055Z,1551990223.055 [Micromodem](INFO): Runnable
2019-03-07T20:23:43.458Z,1551990223.458 [Micromodem](INFO): Runnable
2019-03-07T20:23:43.863Z,1551990223.863 [Micromodem](INFO): Runnable
2019-03-07T20:23:44.267Z,1551990224.267 [Micromodem](INFO): Runnable
2019-03-07T20:23:44.675Z,1551990224.675 [Micromodem](INFO): Runnable
2019-03-07T20:23:45.074Z,1551990225.074 [Micromodem](INFO): Runnable
2019-03-07T20:23:45.482Z,1551990225.482 [Micromodem](INFO): Runnable
2019-03-07T20:23:45.882Z,1551990225.882 [Micromodem](INFO): Runnable
2019-03-07T20:23:46.290Z,1551990226.290 [Micromodem](INFO): Runnable
2019-03-07T20:23:46.690Z,1551990226.690 [Micromodem](INFO): Runnable
2019-03-07T20:23:47.094Z,1551990227.094 [Micromodem](INFO): Runnable
2019-03-07T20:23:47.502Z,1551990227.502 [Micromodem](INFO): Runnable
2019-03-07T20:23:47.902Z,1551990227.902 [Micromodem](INFO): Runnable
2019-03-07T20:23:48.306Z,1551990228.306 [Micromodem](INFO): Runnable
2019-03-07T20:23:48.718Z,1551990228.718 [Micromodem](INFO): Runnable
2019-03-07T20:23:49.120Z,1551990229.120 [Micromodem](INFO): Runnable
2019-03-07T20:23:49.518Z,1551990229.518 [Micromodem](INFO): Runnable
2019-03-07T20:23:49.926Z,1551990229.926 [Micromodem](INFO): Runnable
2019-03-07T20:23:50.326Z,1551990230.326 [Micromodem](INFO): Runnable
2019-03-07T20:23:50.734Z,1551990230.734 [Micromodem](INFO): Runnable
2019-03-07T20:23:51.134Z,1551990231.134 [Micromodem](INFO): Runnable
2019-03-07T20:23:51.538Z,1551990231.538 [Micromodem](INFO): Runnable
2019-03-07T20:23:51.946Z,1551990231.946 [Micromodem](INFO): Runnable
2019-03-07T20:23:52.348Z,1551990232.348 [Micromodem](INFO): Runnable
2019-03-07T20:23:52.872Z,1551990232.872 [Micromodem](INFO): Runnable
2019-03-07T20:23:53.274Z,1551990233.274 [Micromodem](INFO): Runnable
2019-03-07T20:23:53.678Z,1551990233.678 [Micromodem](INFO): Runnable
2019-03-07T20:23:54.078Z,1551990234.078 [Micromodem](INFO): Runnable
2019-03-07T20:23:54.482Z,1551990234.482 [Micromodem](INFO): Runnable
2019-03-07T20:23:54.886Z,1551990234.886 [Micromodem](INFO): Runnable
2019-03-07T20:23:55.294Z,1551990235.294 [Micromodem](INFO): Runnable
2019-03-07T20:23:55.698Z,1551990235.698 [Micromodem](INFO): Runnable
2019-03-07T20:23:56.102Z,1551990236.102 [Micromodem](INFO): Runnable
2019-03-07T20:23:56.508Z,1551990236.508 [Micromodem](INFO): Runnable
2019-03-07T20:23:56.910Z,1551990236.910 [Micromodem](INFO): Runnable
2019-03-07T20:23:57.314Z,1551990237.314 [Micromodem](INFO): Runnable
2019-03-07T20:23:57.714Z,1551990237.714 [Micromodem](INFO): Runnable
2019-03-07T20:23:58.122Z,1551990238.122 [Micromodem](INFO): Runnable
2019-03-07T20:23:58.526Z,1551990238.526 [Micromodem](INFO): Runnable
2019-03-07T20:23:58.926Z,1551990238.926 [Micromodem](INFO): Runnable
2019-03-07T20:23:59.334Z,1551990239.334 [Micromodem](INFO): Runnable
2019-03-07T20:23:59.747Z,1551990239.747 [Micromodem](INFO): Runnable
2019-03-07T20:24:00.138Z,1551990240.138 [Micromodem](INFO): Runnable
2019-03-07T20:24:00.546Z,1551990240.546 [Micromodem](INFO): Runnable
2019-03-07T20:24:00.950Z,1551990240.950 [Micromodem](INFO): Runnable
2019-03-07T20:24:01.354Z,1551990241.354 [Micromodem](INFO): Runnable
2019-03-07T20:24:01.754Z,1551990241.754 [Micromodem](INFO): Runnable
2019-03-07T20:24:02.162Z,1551990242.162 [Micromodem](INFO): Runnable
2019-03-07T20:24:02.562Z,1551990242.562 [Micromodem](INFO): Runnable
2019-03-07T20:24:02.974Z,1551990242.974 [Micromodem](INFO): Runnable
2019-03-07T20:24:03.376Z,1551990243.376 [Micromodem](INFO): Runnable
2019-03-07T20:24:03.774Z,1551990243.774 [Micromodem](INFO): Runnable
2019-03-07T20:24:03.873Z,1551990243.873 [SBIT](IMPORTANT): SBIT PASSED
2019-03-07T20:24:03.893Z,1551990243.893 [CommandLine](IMPORTANT): got command configSet list
2019-03-07T20:24:03.893Z,1551990243.893 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-07T20:24:03.894Z,1551990243.894 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;
2019-03-07T20:24:03.894Z,1551990243.894 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity=1 enum;
2019-03-07T20:24:04.178Z,1551990244.178 [Micromodem](INFO): Runnable
2019-03-07T20:24:04.262Z,1551990244.262 [MissionManager](IMPORTANT): Started mission Startup
2019-03-07T20:24:04.262Z,1551990244.262 [Startup] Running Loop=1
2019-03-07T20:24:04.263Z,1551990244.263 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-07T20:24:04.263Z,1551990244.263 [Startup:A.GoToSurface] Running Loop=1
2019-03-07T20:24:04.263Z,1551990244.263 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-07T20:24:04.263Z,1551990244.263 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-07T20:24:04.264Z,1551990244.264 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-07T20:24:04.264Z,1551990244.264 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-07T20:24:04.264Z,1551990244.264 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-07T20:24:04.265Z,1551990244.265 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-07T20:24:04.266Z,1551990244.266 [Startup:StartupSatComms] Running Loop=1
2019-03-07T20:24:04.267Z,1551990244.267 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-07T20:24:04.267Z,1551990244.267 [Startup:StartupSatComms:A] Running Loop=1
2019-03-07T20:24:04.582Z,1551990244.582 [Micromodem](INFO): Runnable
2019-03-07T20:24:04.651Z,1551990244.651 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-07T20:24:04.990Z,1551990244.990 [Micromodem](INFO): Runnable
2019-03-07T20:24:05.395Z,1551990245.395 [Micromodem](INFO): Runnable
2019-03-07T20:24:05.798Z,1551990245.798 [Micromodem](INFO): Runnable
2019-03-07T20:24:06.202Z,1551990246.202 [Micromodem](INFO): Runnable
2019-03-07T20:24:06.604Z,1551990246.604 [Micromodem](INFO): Runnable
2019-03-07T20:24:07.006Z,1551990247.006 [Micromodem](INFO): Runnable
2019-03-07T20:24:07.416Z,1551990247.416 [Micromodem](INFO): Runnable
2019-03-07T20:24:07.818Z,1551990247.818 [Micromodem](INFO): Runnable
2019-03-07T20:24:08.218Z,1551990248.218 [Micromodem](INFO): Runnable
2019-03-07T20:24:08.626Z,1551990248.626 [Micromodem](INFO): Runnable
2019-03-07T20:24:09.030Z,1551990249.030 [Micromodem](INFO): Runnable
2019-03-07T20:24:09.438Z,1551990249.438 [Micromodem](INFO): Runnable
2019-03-07T20:24:09.831Z,1551990249.831 [Micromodem](INFO): Runnable
2019-03-07T20:24:10.242Z,1551990250.242 [Micromodem](INFO): Runnable
2019-03-07T20:24:10.646Z,1551990250.646 [Micromodem](INFO): Runnable
2019-03-07T20:24:11.050Z,1551990251.050 [Micromodem](INFO): Runnable
2019-03-07T20:24:11.454Z,1551990251.454 [Micromodem](INFO): Runnable
2019-03-07T20:24:11.858Z,1551990251.858 [Micromodem](INFO): Runnable
2019-03-07T20:24:12.262Z,1551990252.262 [Micromodem](INFO): Runnable
2019-03-07T20:24:12.662Z,1551990252.662 [Micromodem](INFO): Runnable
2019-03-07T20:24:13.072Z,1551990253.072 [Micromodem](INFO): Runnable
2019-03-07T20:24:13.474Z,1551990253.474 [Micromodem](INFO): Runnable
2019-03-07T20:24:13.874Z,1551990253.874 [Micromodem](INFO): Runnable
2019-03-07T20:24:14.282Z,1551990254.282 [Micromodem](INFO): Runnable
2019-03-07T20:24:14.682Z,1551990254.682 [Micromodem](INFO): Runnable
2019-03-07T20:24:15.086Z,1551990255.086 [Micromodem](INFO): Runnable
2019-03-07T20:24:15.494Z,1551990255.494 [Micromodem](INFO): Runnable
2019-03-07T20:24:15.894Z,1551990255.894 [Micromodem](INFO): Runnable
2019-03-07T20:24:16.302Z,1551990256.302 [Micromodem](INFO): Runnable
2019-03-07T20:24:16.706Z,1551990256.706 [Micromodem](INFO): Runnable
2019-03-07T20:24:17.106Z,1551990257.106 [Micromodem](INFO): Runnable
2019-03-07T20:24:17.514Z,1551990257.514 [Micromodem](INFO): Runnable
2019-03-07T20:24:17.914Z,1551990257.914 [Micromodem](INFO): Runnable
2019-03-07T20:24:18.315Z,1551990258.315 [Micromodem](INFO): Runnable
2019-03-07T20:24:18.722Z,1551990258.722 [Micromodem](INFO): Runnable
2019-03-07T20:24:19.126Z,1551990259.126 [Micromodem](INFO): Runnable
2019-03-07T20:24:19.534Z,1551990259.534 [Micromodem](INFO): Runnable
2019-03-07T20:24:19.940Z,1551990259.940 [Micromodem](INFO): Runnable
2019-03-07T20:24:20.338Z,1551990260.338 [Micromodem](INFO): Runnable
2019-03-07T20:24:20.746Z,1551990260.746 [Micromodem](INFO): Runnable
2019-03-07T20:24:21.146Z,1551990261.146 [Micromodem](INFO): Runnable
2019-03-07T20:24:21.554Z,1551990261.554 [Micromodem](INFO): Runnable
2019-03-07T20:24:21.958Z,1551990261.958 [Micromodem](INFO): Runnable
2019-03-07T20:24:22.358Z,1551990262.358 [Micromodem](INFO): Runnable
2019-03-07T20:24:22.762Z,1551990262.762 [Micromodem](INFO): Runnable
2019-03-07T20:24:23.168Z,1551990263.168 [Micromodem](INFO): Runnable
2019-03-07T20:24:23.574Z,1551990263.574 [Micromodem](INFO): Runnable
2019-03-07T20:24:23.974Z,1551990263.974 [Micromodem](INFO): Runnable
2019-03-07T20:24:24.375Z,1551990264.375 [Micromodem](INFO): Runnable
2019-03-07T20:24:24.786Z,1551990264.786 [Micromodem](INFO): Runnable
2019-03-07T20:24:25.190Z,1551990265.190 [Micromodem](INFO): Runnable
2019-03-07T20:24:25.590Z,1551990265.590 [Micromodem](INFO): Runnable
2019-03-07T20:24:25.995Z,1551990265.995 [Micromodem](INFO): Runnable
2019-03-07T20:24:26.410Z,1551990266.410 [Micromodem](INFO): Runnable
2019-03-07T20:24:26.808Z,1551990266.808 [Micromodem](INFO): Runnable
2019-03-07T20:24:27.206Z,1551990267.206 [Micromodem](INFO): Runnable
2019-03-07T20:24:27.614Z,1551990267.614 [Micromodem](INFO): Runnable
2019-03-07T20:24:28.018Z,1551990268.018 [Micromodem](INFO): Runnable
2019-03-07T20:24:28.422Z,1551990268.422 [Micromodem](INFO): Runnable
2019-03-07T20:24:28.822Z,1551990268.822 [Micromodem](INFO): Runnable
2019-03-07T20:24:29.230Z,1551990269.230 [Micromodem](INFO): Runnable
2019-03-07T20:24:29.630Z,1551990269.630 [Micromodem](INFO): Runnable
2019-03-07T20:24:30.036Z,1551990270.036 [Micromodem](INFO): Runnable
2019-03-07T20:24:30.438Z,1551990270.438 [Micromodem](INFO): Runnable
2019-03-07T20:24:30.846Z,1551990270.846 [Micromodem](INFO): Runnable
2019-03-07T20:24:31.250Z,1551990271.250 [Micromodem](INFO): Runnable
2019-03-07T20:24:31.650Z,1551990271.650 [Micromodem](INFO): Runnable
2019-03-07T20:24:32.058Z,1551990272.058 [Micromodem](INFO): Runnable
2019-03-07T20:24:32.458Z,1551990272.458 [Micromodem](INFO): Runnable
2019-03-07T20:24:32.862Z,1551990272.862 [Micromodem](INFO): Runnable
2019-03-07T20:24:33.266Z,1551990273.266 [Micromodem](INFO): Runnable
2019-03-07T20:24:33.676Z,1551990273.676 [Micromodem](INFO): Runnable
2019-03-07T20:24:34.074Z,1551990274.074 [Micromodem](INFO): Runnable
2019-03-07T20:24:34.482Z,1551990274.482 [Micromodem](INFO): Runnable
2019-03-07T20:24:34.886Z,1551990274.886 [Micromodem](INFO): Runnable
2019-03-07T20:24:35.283Z,1551990275.283 [Micromodem](INFO): Runnable
2019-03-07T20:24:35.690Z,1551990275.690 [Micromodem](INFO): Runnable
2019-03-07T20:24:36.094Z,1551990276.094 [Micromodem](INFO): Runnable
2019-03-07T20:24:36.495Z,1551990276.495 [Micromodem](INFO): Runnable
2019-03-07T20:24:36.904Z,1551990276.904 [Micromodem](INFO): Runnable
2019-03-07T20:24:37.310Z,1551990277.310 [Micromodem](INFO): Runnable
2019-03-07T20:24:37.707Z,1551990277.707 [Micromodem](INFO): Runnable
2019-03-07T20:24:38.118Z,1551990278.118 [Micromodem](INFO): Runnable
2019-03-07T20:24:38.522Z,1551990278.522 [Micromodem](INFO): Runnable
2019-03-07T20:24:38.926Z,1551990278.926 [Micromodem](INFO): Runnable
2019-03-07T20:24:39.330Z,1551990279.330 [Micromodem](INFO): Runnable
2019-03-07T20:24:39.734Z,1551990279.734 [Micromodem](INFO): Runnable
2019-03-07T20:24:40.134Z,1551990280.134 [Micromodem](INFO): Runnable
2019-03-07T20:24:40.538Z,1551990280.538 [Micromodem](INFO): Runnable
2019-03-07T20:24:40.946Z,1551990280.946 [Micromodem](INFO): Runnable
2019-03-07T20:24:41.350Z,1551990281.350 [Micromodem](INFO): Runnable
2019-03-07T20:24:41.750Z,1551990281.750 [Micromodem](INFO): Runnable
2019-03-07T20:24:41.780Z,1551990281.780 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-07T20:24:41.780Z,1551990281.780 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-07T20:24:41.780Z,1551990281.780 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-07T20:24:41.783Z,1551990281.783 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-07T20:24:41.783Z,1551990281.783 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-07T20:24:41.783Z,1551990281.783 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-07T20:24:41.784Z,1551990281.784 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-07T20:24:41.784Z,1551990281.784 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-07T20:24:41.784Z,1551990281.784 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-07T20:24:41.820Z,1551990281.820 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-07T20:24:41.820Z,1551990281.820 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-07T20:24:41.820Z,1551990281.820 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-07T20:24:42.154Z,1551990282.154 [Micromodem](INFO): Runnable
2019-03-07T20:24:42.194Z,1551990282.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-07T20:24:42.194Z,1551990282.194 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-07T20:24:42.194Z,1551990282.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-07T20:24:42.195Z,1551990282.195 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-07T20:24:42.195Z,1551990282.195 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-07T20:24:42.195Z,1551990282.195 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-07T20:24:42.558Z,1551990282.558 [Micromodem](INFO): Runnable
2019-03-07T20:24:42.588Z,1551990282.588 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-07T20:24:42.970Z,1551990282.970 [Micromodem](INFO): Runnable
2019-03-07T20:24:43.370Z,1551990283.370 [Micromodem](INFO): Runnable
2019-03-07T20:24:43.772Z,1551990283.772 [Micromodem](INFO): Runnable
2019-03-07T20:24:44.175Z,1551990284.175 [Micromodem](INFO): Runnable
2019-03-07T20:24:44.583Z,1551990284.583 [Micromodem](INFO): Runnable
2019-03-07T20:24:44.986Z,1551990284.986 [Micromodem](INFO): Runnable
2019-03-07T20:24:45.392Z,1551990285.392 [Micromodem](INFO): Runnable
2019-03-07T20:24:45.794Z,1551990285.794 [Micromodem](INFO): Runnable
2019-03-07T20:24:46.194Z,1551990286.194 [Micromodem](INFO): Runnable
2019-03-07T20:24:46.599Z,1551990286.599 [Micromodem](INFO): Runnable
2019-03-07T20:24:47.006Z,1551990287.006 [Micromodem](INFO): Runnable
2019-03-07T20:24:47.416Z,1551990287.416 [Micromodem](INFO): Runnable
2019-03-07T20:24:47.814Z,1551990287.814 [Micromodem](INFO): Runnable
2019-03-07T20:24:48.218Z,1551990288.218 [Micromodem](INFO): Runnable
2019-03-07T20:24:48.622Z,1551990288.622 [Micromodem](INFO): Runnable
2019-03-07T20:24:49.026Z,1551990289.026 [Micromodem](INFO): Runnable
2019-03-07T20:24:49.426Z,1551990289.426 [Micromodem](INFO): Runnable
2019-03-07T20:24:49.827Z,1551990289.827 [Micromodem](INFO): Runnable
2019-03-07T20:24:50.234Z,1551990290.234 [Micromodem](INFO): Runnable
2019-03-07T20:24:50.640Z,1551990290.640 [Micromodem](INFO): Runnable
2019-03-07T20:24:51.042Z,1551990291.042 [Micromodem](INFO): Runnable
2019-03-07T20:24:51.446Z,1551990291.446 [Micromodem](INFO): Runnable
2019-03-07T20:24:51.854Z,1551990291.854 [Micromodem](INFO): Runnable
2019-03-07T20:24:52.258Z,1551990292.258 [Micromodem](INFO): Runnable
2019-03-07T20:24:52.658Z,1551990292.658 [Micromodem](INFO): Runnable
2019-03-07T20:24:53.062Z,1551990293.062 [Micromodem](INFO): Runnable
2019-03-07T20:24:53.466Z,1551990293.466 [Micromodem](INFO): Runnable
2019-03-07T20:24:53.870Z,1551990293.870 [Micromodem](INFO): Runnable
2019-03-07T20:24:54.274Z,1551990294.274 [Micromodem](INFO): Runnable
2019-03-07T20:24:54.678Z,1551990294.678 [Micromodem](INFO): Runnable
2019-03-07T20:24:55.082Z,1551990295.082 [Micromodem](INFO): Runnable
2019-03-07T20:24:55.490Z,1551990295.490 [Micromodem](INFO): Runnable
2019-03-07T20:24:55.894Z,1551990295.894 [Micromodem](INFO): Runnable
2019-03-07T20:24:56.294Z,1551990296.294 [Micromodem](INFO): Runnable
2019-03-07T20:24:56.702Z,1551990296.702 [Micromodem](INFO): Runnable
2019-03-07T20:24:57.102Z,1551990297.102 [Micromodem](INFO): Runnable
2019-03-07T20:24:57.508Z,1551990297.508 [Micromodem](INFO): Runnable
2019-03-07T20:24:57.914Z,1551990297.914 [Micromodem](INFO): Runnable
2019-03-07T20:24:58.314Z,1551990298.314 [Micromodem](INFO): Runnable
2019-03-07T20:24:58.718Z,1551990298.718 [Micromodem](INFO): Runnable
2019-03-07T20:24:59.122Z,1551990299.122 [Micromodem](INFO): Runnable
2019-03-07T20:24:59.530Z,1551990299.530 [Micromodem](INFO): Runnable
2019-03-07T20:24:59.930Z,1551990299.930 [Micromodem](INFO): Runnable
2019-03-07T20:25:00.334Z,1551990300.334 [Micromodem](INFO): Runnable
2019-03-07T20:25:00.738Z,1551990300.738 [Micromodem](INFO): Runnable
2019-03-07T20:25:01.142Z,1551990301.142 [Micromodem](INFO): Runnable
2019-03-07T20:25:01.550Z,1551990301.550 [Micromodem](INFO): Runnable
2019-03-07T20:25:01.950Z,1551990301.950 [Micromodem](INFO): Runnable
2019-03-07T20:25:02.354Z,1551990302.354 [Micromodem](INFO): Runnable
2019-03-07T20:25:02.758Z,1551990302.758 [Micromodem](INFO): Runnable
2019-03-07T20:25:03.162Z,1551990303.162 [Micromodem](INFO): Runnable
2019-03-07T20:25:03.566Z,1551990303.566 [Micromodem](INFO): Runnable
2019-03-07T20:25:03.970Z,1551990303.970 [Micromodem](INFO): Runnable
2019-03-07T20:25:04.393Z,1551990304.393 [Micromodem](INFO): Runnable
2019-03-07T20:25:04.474Z,1551990304.474 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-07T20:24:04.3Z
2019-03-07T20:25:04.475Z,1551990304.475 [Startup:StartupSatComms:A] Stopped
2019-03-07T20:25:04.475Z,1551990304.475 [Startup:StartupSatComms:B] Running Loop=1
2019-03-07T20:25:04.778Z,1551990304.778 [Micromodem](INFO): Runnable
2019-03-07T20:25:04.822Z,1551990304.822 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-07T20:25:05.335Z,1551990305.335 [Micromodem](INFO): Runnable
2019-03-07T20:25:05.730Z,1551990305.730 [Micromodem](INFO): Runnable
2019-03-07T20:25:06.134Z,1551990306.134 [Micromodem](INFO): Runnable
2019-03-07T20:25:06.538Z,1551990306.538 [Micromodem](INFO): Runnable
2019-03-07T20:25:06.943Z,1551990306.943 [Micromodem](INFO): Runnable
2019-03-07T20:25:07.346Z,1551990307.346 [Micromodem](INFO): Runnable
2019-03-07T20:25:07.750Z,1551990307.750 [Micromodem](INFO): Runnable
2019-03-07T20:25:08.154Z,1551990308.154 [Micromodem](INFO): Runnable
2019-03-07T20:25:08.559Z,1551990308.559 [Micromodem](INFO): Runnable
2019-03-07T20:25:08.971Z,1551990308.971 [Micromodem](INFO): Runnable
2019-03-07T20:25:09.368Z,1551990309.368 [Micromodem](INFO): Runnable
2019-03-07T20:25:09.770Z,1551990309.770 [Micromodem](INFO): Runnable
2019-03-07T20:25:10.178Z,1551990310.178 [Micromodem](INFO): Runnable
2019-03-07T20:25:10.528Z,1551990310.528 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005700
2019-03-07T20:25:10.578Z,1551990310.578 [Micromodem](INFO): Runnable
2019-03-07T20:25:10.986Z,1551990310.986 [Micromodem](INFO): Runnable
2019-03-07T20:25:11.394Z,1551990311.394 [Micromodem](INFO): Runnable
2019-03-07T20:25:11.790Z,1551990311.790 [Micromodem](INFO): Runnable
2019-03-07T20:25:12.194Z,1551990312.194 [Micromodem](INFO): Runnable
2019-03-07T20:25:12.599Z,1551990312.599 [Micromodem](INFO): Runnable
2019-03-07T20:25:13.018Z,1551990313.018 [Micromodem](INFO): Runnable
2019-03-07T20:25:13.434Z,1551990313.434 [Micromodem](INFO): Runnable
2019-03-07T20:25:13.842Z,1551990313.842 [Micromodem](INFO): Runnable
2019-03-07T20:25:14.250Z,1551990314.250 [Micromodem](INFO): Runnable
2019-03-07T20:25:14.666Z,1551990314.666 [Micromodem](INFO): Runnable
2019-03-07T20:25:15.058Z,1551990315.058 [Micromodem](INFO): Runnable
2019-03-07T20:25:15.458Z,1551990315.458 [Micromodem](INFO): Runnable
2019-03-07T20:25:15.862Z,1551990315.862 [Micromodem](INFO): Runnable
2019-03-07T20:25:16.290Z,1551990316.290 [Micromodem](INFO): Runnable
2019-03-07T20:25:16.678Z,1551990316.678 [Micromodem](INFO): Runnable
2019-03-07T20:25:17.082Z,1551990317.082 [Micromodem](INFO): Runnable
2019-03-07T20:25:17.482Z,1551990317.482 [Micromodem](INFO): Runnable
2019-03-07T20:25:17.902Z,1551990317.902 [Micromodem](INFO): Runnable
2019-03-07T20:25:18.294Z,1551990318.294 [Micromodem](INFO): Runnable
2019-03-07T20:25:18.698Z,1551990318.698 [Micromodem](INFO): Runnable
2019-03-07T20:25:19.102Z,1551990319.102 [Micromodem](INFO): Runnable
2019-03-07T20:25:19.506Z,1551990319.506 [Micromodem](INFO): Runnable
2019-03-07T20:25:19.906Z,1551990319.906 [Micromodem](INFO): Runnable
2019-03-07T20:25:20.310Z,1551990320.310 [Micromodem](INFO): Runnable
2019-03-07T20:25:20.718Z,1551990320.718 [Micromodem](INFO): Runnable
2019-03-07T20:25:21.118Z,1551990321.118 [Micromodem](INFO): Runnable
2019-03-07T20:25:21.419Z,1551990321.419 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190306T232426/Courier0373.lzma
2019-03-07T20:25:21.522Z,1551990321.522 [Micromodem](INFO): Runnable
2019-03-07T20:25:21.926Z,1551990321.926 [Micromodem](INFO): Runnable
2019-03-07T20:25:22.219Z,1551990322.219 [DataOverHttps](INFO): Moved sent file to Logs/20190306T232426/Courier0373.lzma.bak
2019-03-07T20:25:22.219Z,1551990322.219 [DataOverHttps](INFO): SBD MOMSN=10095514
2019-03-07T20:25:22.338Z,1551990322.338 [Micromodem](INFO): Runnable
2019-03-07T20:25:22.734Z,1551990322.734 [Micromodem](INFO): Runnable
2019-03-07T20:25:23.138Z,1551990323.138 [Micromodem](INFO): Runnable
2019-03-07T20:25:23.546Z,1551990323.546 [Micromodem](INFO): Runnable
2019-03-07T20:25:23.968Z,1551990323.968 [Micromodem](INFO): Runnable
2019-03-07T20:25:24.354Z,1551990324.354 [Micromodem](INFO): Runnable
2019-03-07T20:25:24.498Z,1551990324.498 [CommandLine](IMPORTANT): got command restart application
2019-03-07T20:25:24.754Z,1551990324.754 [Micromodem](INFO): Runnable
2019-03-07T20:25:25.158Z,1551990325.158 [Micromodem](INFO): Runnable
2019-03-07T20:25:25.501Z,1551990325.501 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-03-07T20:25:25.501Z,1551990325.501 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T20:25:25.502Z,1551990325.502 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:25.562Z,1551990325.562 [Micromodem](INFO): Runnable
2019-03-07T20:25:25.629Z,1551990325.629 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-03-07T20:25:25.629Z,1551990325.629 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:25.630Z,1551990325.630 [CommandLine](INFO): Join timeout helper Thread ID is 9974
2019-03-07T20:25:25.631Z,1551990325.631 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-07T20:25:25.631Z,1551990325.631 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:25.631Z,1551990325.631 [NavChartDb](INFO): Join timeout helper Thread ID is 9975
2019-03-07T20:25:25.845Z,1551990325.845 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T20:25:25.846Z,1551990325.846 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:25.861Z,1551990325.861 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-07T20:25:25.861Z,1551990325.861 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:25.861Z,1551990325.861 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9976
2019-03-07T20:25:25.966Z,1551990325.966 [Micromodem](INFO): Runnable
2019-03-07T20:25:25.989Z,1551990325.989 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T20:25:25.989Z,1551990325.989 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-07T20:25:25.990Z,1551990325.990 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:26.005Z,1551990326.005 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-07T20:25:26.005Z,1551990326.005 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:26.005Z,1551990326.005 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9977
2019-03-07T20:25:26.037Z,1551990326.037 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T20:25:26.037Z,1551990326.037 [CTD_NeilBrown](INFO): Powering down
2019-03-07T20:25:26.049Z,1551990326.049 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:26.050Z,1551990326.050 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-07T20:25:26.050Z,1551990326.050 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:26.050Z,1551990326.050 [Radio_Surface](INFO): Join timeout helper Thread ID is 9978
2019-03-07T20:25:26.293Z,1551990326.293 [Radio_Surface](INFO): Powering down
2019-03-07T20:25:26.294Z,1551990326.294 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T20:25:26.294Z,1551990326.294 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:26.298Z,1551990326.298 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-07T20:25:26.298Z,1551990326.298 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:26.298Z,1551990326.298 [DataOverHttps](INFO): Join timeout helper Thread ID is 9979
2019-03-07T20:25:26.378Z,1551990326.378 [Micromodem](INFO): Runnable
2019-03-07T20:25:26.774Z,1551990326.774 [Micromodem](INFO): Runnable
2019-03-07T20:25:27.178Z,1551990327.178 [Micromodem](INFO): Runnable
2019-03-07T20:25:27.582Z,1551990327.582 [Micromodem](INFO): Runnable
2019-03-07T20:25:27.986Z,1551990327.986 [Micromodem](INFO): Runnable
2019-03-07T20:25:28.397Z,1551990328.397 [Micromodem](INFO): Runnable
2019-03-07T20:25:28.794Z,1551990328.794 [Micromodem](INFO): Runnable
2019-03-07T20:25:29.064Z,1551990329.064 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T20:25:29.068Z,1551990329.068 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.074Z,1551990329.074 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-07T20:25:29.074Z,1551990329.074 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.074Z,1551990329.074 [logger](INFO): Join timeout helper Thread ID is 9980
2019-03-07T20:25:29.089Z,1551990329.089 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T20:25:29.089Z,1551990329.089 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.094Z,1551990329.094 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-07T20:25:29.094Z,1551990329.094 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.094Z,1551990329.094 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-07T20:25:29.094Z,1551990329.094 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.094Z,1551990329.094 [controlThread](INFO): Join timeout helper Thread ID is 9981
2019-03-07T20:25:29.189Z,1551990329.189 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T20:25:29.189Z,1551990329.189 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-07T20:25:29.190Z,1551990329.190 [DUSBL_Hydroid](INFO): Powering down
2019-03-07T20:25:29.281Z,1551990329.281 [Micromodem](INFO): uninitialize
2019-03-07T20:25:29.281Z,1551990329.281 [Micromodem](INFO): Powering down
2019-03-07T20:25:29.377Z,1551990329.377 [NAL9602](INFO): Powering down
2019-03-07T20:25:29.449Z,1551990329.449 [RDI_Pathfinder](INFO): Powering down
2019-03-07T20:25:29.450Z,1551990329.450 [RDI_PathfinderUp](INFO): Powering down
2019-03-07T20:25:29.451Z,1551990329.451 [Aanderaa_O2](INFO): Powering down
2019-03-07T20:25:29.452Z,1551990329.452 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-07T20:25:29.453Z,1551990329.453 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-07T20:25:29.454Z,1551990329.454 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-07T20:25:29.455Z,1551990329.455 [MissionManager](INFO): Uninitializing Mission Default
2019-03-07T20:25:29.457Z,1551990329.457 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-07T20:25:29.457Z,1551990329.457 [Startup] Stopped
2019-03-07T20:25:29.457Z,1551990329.457 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-07T20:25:29.457Z,1551990329.457 [Startup:A.GoToSurface] Stopped
2019-03-07T20:25:29.457Z,1551990329.457 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-07T20:25:29.457Z,1551990329.457 [Startup:StartupSatComms] Stopped
2019-03-07T20:25:29.458Z,1551990329.458 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-07T20:25:29.458Z,1551990329.458 [Startup:StartupSatComms:B] Stopped
2019-03-07T20:25:29.459Z,1551990329.459 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-07T20:25:29.459Z,1551990329.459 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-07T20:25:29.460Z,1551990329.460 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-07T20:25:29.460Z,1551990329.460 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-07T20:25:29.460Z,1551990329.460 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-07T20:25:29.460Z,1551990329.460 [BuoyancyServo](INFO): Powering down
2019-03-07T20:25:29.473Z,1551990329.473 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-07T20:25:29.473Z,1551990329.473 [ElevatorServo](INFO): Powering down
2019-03-07T20:25:29.474Z,1551990329.474 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-07T20:25:29.474Z,1551990329.474 [MassServo](INFO): Powering down
2019-03-07T20:25:29.475Z,1551990329.475 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-07T20:25:29.475Z,1551990329.475 [RudderServo](INFO): Powering down
2019-03-07T20:25:29.476Z,1551990329.476 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-07T20:25:29.476Z,1551990329.476 [ThrusterServo](INFO): Powering down
2019-03-07T20:25:29.476Z,1551990329.476 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-07T20:25:29.477Z,1551990329.477 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-07T20:25:29.477Z,1551990329.477 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-07T20:25:29.477Z,1551990329.477 [CBIT](DEBUG): Powering off loads.
2019-03-07T20:25:29.489Z,1551990329.489 [CBIT](DEBUG): Disabling WDT.
2019-03-07T20:25:29.501Z,1551990329.501 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-07T20:25:29.502Z,1551990329.502 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.554Z,1551990329.554 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.563Z,1551990329.563 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.606Z,1551990329.606 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.608Z,1551990329.608 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.662Z,1551990329.662 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-07T20:25:29.717Z,1551990329.717 [logger ThreadHandler](INFO): Thread cancelled.