2018-10-19T19:09:38.470Z,1539976178.470 [Supervisor](DEBUG): Initializing supervisor.
2018-10-19T19:09:38.473Z,1539976178.473 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-19T19:09:38.473Z,1539976178.473 [SyncHandler](INFO): Protected caller Thread ID is 1870
2018-10-19T19:09:38.474Z,1539976178.474 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-19T19:09:38.475Z,1539976178.475 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-19T19:09:38.475Z,1539976178.475 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1871
2018-10-19T19:09:38.478Z,1539976178.478 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-19T19:09:38.489Z,1539976178.489 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-19T19:09:38.490Z,1539976178.490 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-19T19:09:38.490Z,1539976178.490 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1872
2018-10-19T19:09:38.491Z,1539976178.491 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-19T19:09:38.492Z,1539976178.492 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-19T19:09:38.493Z,1539976178.493 [logger ThreadHandler](INFO): Protected caller Thread ID is 1873
2018-10-19T19:09:38.495Z,1539976178.495 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-19T19:09:38.495Z,1539976178.495 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-19T19:09:38.496Z,1539976178.496 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-19T19:09:38.931Z,1539976178.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-19T19:09:38.932Z,1539976178.932 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-19T19:09:39.379Z,1539976179.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-19T19:09:39.380Z,1539976179.380 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-19T19:09:39.574Z,1539976179.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-19T19:09:39.574Z,1539976179.574 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-19T19:09:39.719Z,1539976179.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-19T19:09:39.720Z,1539976179.720 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-19T19:09:40.156Z,1539976180.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-19T19:09:40.158Z,1539976180.158 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-19T19:09:40.252Z,1539976180.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-19T19:09:40.253Z,1539976180.253 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-19T19:09:40.464Z,1539976180.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-19T19:09:40.464Z,1539976180.464 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-19T19:09:40.564Z,1539976180.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-19T19:09:40.564Z,1539976180.564 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-19T19:09:40.669Z,1539976180.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-19T19:09:40.669Z,1539976180.669 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-19T19:09:41.012Z,1539976181.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-19T19:09:41.013Z,1539976181.013 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-19T19:09:41.214Z,1539976181.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-19T19:09:41.214Z,1539976181.214 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-19T19:09:41.425Z,1539976181.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-19T19:09:41.426Z,1539976181.426 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-19T19:09:41.506Z,1539976181.506 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-19T19:09:41.855Z,1539976181.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-19T19:09:41.856Z,1539976181.856 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-19T19:09:42.172Z,1539976182.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-19T19:09:42.174Z,1539976182.174 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-10-19T19:09:42.175Z,1539976182.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-10-19T19:09:42.259Z,1539976182.259 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-10-19T19:09:42.402Z,1539976182.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-10-19T19:09:42.499Z,1539976182.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-10-19T19:09:42.671Z,1539976182.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-10-19T19:09:42.752Z,1539976182.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-10-19T19:09:42.975Z,1539976182.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-19T19:09:42.976Z,1539976182.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-10-19T19:09:43.089Z,1539976183.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-10-19T19:09:43.189Z,1539976183.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-10-19T19:09:43.284Z,1539976183.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-10-19T19:09:43.386Z,1539976183.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-10-19T19:09:43.531Z,1539976183.531 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2018-10-19T19:09:43.531Z,1539976183.531 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-10-19T19:09:43.533Z,1539976183.533 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-19T19:09:43.640Z,1539976183.640 [DepthRateCalculator] Loaded
2018-10-19T19:09:43.653Z,1539976183.653 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-19T19:09:43.659Z,1539976183.659 [PitchRateCalculator] Loaded
2018-10-19T19:09:43.659Z,1539976183.659 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-19T19:09:43.782Z,1539976183.782 [SpeedCalculator] Loaded
2018-10-19T19:09:43.782Z,1539976183.782 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-19T19:09:43.803Z,1539976183.803 [TempGradientCalculator] Loaded
2018-10-19T19:09:43.803Z,1539976183.803 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-19T19:09:43.810Z,1539976183.810 [YawRateCalculator] Loaded
2018-10-19T19:09:43.812Z,1539976183.812 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-19T19:09:43.948Z,1539976183.948 [ElevatorOffsetCalculator] Loaded
2018-10-19T19:09:43.948Z,1539976183.948 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-19T19:09:43.949Z,1539976183.949 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-19T19:09:43.950Z,1539976183.950 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-19T19:09:44.372Z,1539976184.372 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-19T19:09:44.372Z,1539976184.372 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-19T19:09:44.686Z,1539976184.686 [AHRS_M2] Loaded
2018-10-19T19:09:44.686Z,1539976184.686 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-10-19T19:09:44.757Z,1539976184.757 [DataOverHttps] Loaded
2018-10-19T19:09:44.757Z,1539976184.757 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-19T19:09:44.770Z,1539976184.770 [Depth_Keller] Loaded
2018-10-19T19:09:44.771Z,1539976184.771 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-19T19:09:44.775Z,1539976184.775 [DropWeight] Loaded
2018-10-19T19:09:44.776Z,1539976184.776 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-19T19:09:44.906Z,1539976184.906 [DVL_micro] Loaded
2018-10-19T19:09:44.906Z,1539976184.906 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2018-10-19T19:09:45.004Z,1539976185.004 [NAL9602] Loaded
2018-10-19T19:09:45.005Z,1539976185.005 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-19T19:09:45.021Z,1539976185.021 [Onboard] Loaded
2018-10-19T19:09:45.021Z,1539976185.021 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-19T19:09:45.031Z,1539976185.031 [Radio_Surface] Loaded
2018-10-19T19:09:45.032Z,1539976185.032 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-19T19:09:45.033Z,1539976185.033 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-10-19T19:09:45.033Z,1539976185.033 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1953
2018-10-19T19:09:45.040Z,1539976185.040 [SCPI] Loaded
2018-10-19T19:09:45.040Z,1539976185.040 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-10-19T19:09:45.085Z,1539976185.085 [PNI_TCM] Loaded
2018-10-19T19:09:45.086Z,1539976185.086 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-10-19T19:09:46.855Z,1539976186.855 [BPC1] Loaded
2018-10-19T19:09:46.855Z,1539976186.855 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-10-19T19:09:46.856Z,1539976186.856 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-19T19:09:46.856Z,1539976186.856 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-19T19:09:46.922Z,1539976186.922 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-19T19:09:46.923Z,1539976186.923 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-19T19:09:47.018Z,1539976187.018 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-10-19T19:09:47.019Z,1539976187.019 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-10-19T19:09:47.073Z,1539976187.073 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-19T19:09:47.073Z,1539976187.073 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-19T19:09:47.089Z,1539976187.089 [NavChart] Loaded
2018-10-19T19:09:47.089Z,1539976187.089 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-19T19:09:47.093Z,1539976187.093 [UniversalFixResidualReporter] Loaded
2018-10-19T19:09:47.093Z,1539976187.093 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-19T19:09:47.094Z,1539976187.094 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-19T19:09:47.094Z,1539976187.094 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-19T19:09:47.322Z,1539976187.322 [CTD_NeilBrown] Loaded
2018-10-19T19:09:47.322Z,1539976187.322 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-10-19T19:09:47.323Z,1539976187.323 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-10-19T19:09:47.324Z,1539976187.324 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1954
2018-10-19T19:09:47.380Z,1539976187.380 [CTD_Seabird] Loaded
2018-10-19T19:09:47.380Z,1539976187.380 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-10-19T19:09:47.381Z,1539976187.381 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2018-10-19T19:09:47.381Z,1539976187.381 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1955
2018-10-19T19:09:47.431Z,1539976187.431 [WetLabsBB2FL] Loaded
2018-10-19T19:09:47.431Z,1539976187.431 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-10-19T19:09:47.432Z,1539976187.432 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0
2018-10-19T19:09:47.433Z,1539976187.433 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1956
2018-10-19T19:09:47.433Z,1539976187.433 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-19T19:09:47.434Z,1539976187.434 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-19T19:09:47.503Z,1539976187.503 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-19T19:09:47.603Z,1539976187.603 [VerticalControl] Loaded
2018-10-19T19:09:47.603Z,1539976187.603 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-19T19:09:47.604Z,1539976187.604 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-19T19:09:47.673Z,1539976187.673 [HorizontalControl] Loaded
2018-10-19T19:09:47.673Z,1539976187.673 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-19T19:09:47.674Z,1539976187.674 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-19T19:09:47.679Z,1539976187.679 [SpeedControl] Loaded
2018-10-19T19:09:47.680Z,1539976187.680 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-19T19:09:47.680Z,1539976187.680 [LoopControl](DEBUG): Construct LoopControl.
2018-10-19T19:09:47.681Z,1539976187.681 [LoopControl] Loaded
2018-10-19T19:09:47.681Z,1539976187.681 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-19T19:09:47.682Z,1539976187.682 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-19T19:09:47.682Z,1539976187.682 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-19T19:09:47.695Z,1539976187.695 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-19T19:09:47.696Z,1539976187.696 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-19T19:09:47.828Z,1539976187.828 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-19T19:09:47.839Z,1539976187.839 [SBIT] Loaded
2018-10-19T19:09:47.839Z,1539976187.839 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-19T19:09:47.840Z,1539976187.840 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-19T19:09:47.852Z,1539976187.852 [IBIT] Loaded
2018-10-19T19:09:47.853Z,1539976187.853 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-19T19:09:47.856Z,1539976187.856 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-19T19:09:48.007Z,1539976188.007 [CBIT] Loaded
2018-10-19T19:09:48.008Z,1539976188.008 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-19T19:09:48.008Z,1539976188.008 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-19T19:09:48.009Z,1539976188.009 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-19T19:09:48.110Z,1539976188.110 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-19T19:09:48.111Z,1539976188.111 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-19T19:09:48.135Z,1539976188.135 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-19T19:09:48.136Z,1539976188.136 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-19T19:09:48.237Z,1539976188.237 [BuoyancyServo] Loaded
2018-10-19T19:09:48.237Z,1539976188.237 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-19T19:09:48.248Z,1539976188.248 [ElevatorServo] Loaded
2018-10-19T19:09:48.249Z,1539976188.249 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-19T19:09:48.259Z,1539976188.259 [MassServo] Loaded
2018-10-19T19:09:48.260Z,1539976188.260 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-19T19:09:48.271Z,1539976188.271 [RudderServo] Loaded
2018-10-19T19:09:48.271Z,1539976188.271 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-19T19:09:48.282Z,1539976188.282 [ThrusterServo] Loaded
2018-10-19T19:09:48.282Z,1539976188.282 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-19T19:09:48.283Z,1539976188.283 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-19T19:09:48.286Z,1539976188.286 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-19T19:09:48.287Z,1539976188.287 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-19T19:09:48.294Z,1539976188.294 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-19T19:09:48.295Z,1539976188.295 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2018-10-19T19:09:48.295Z,1539976188.295 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1957
2018-10-19T19:09:48.300Z,1539976188.300 [Supervisor](INFO): Main Thread ID is 1869
2018-10-19T19:09:48.300Z,1539976188.300 [Supervisor](DEBUG): Running supervisor.
2018-10-19T19:09:48.301Z,1539976188.301 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1958
2018-10-19T19:09:48.303Z,1539976188.303 [controlThread ThreadHandler](INFO): Handler Thread ID is 1959
2018-10-19T19:09:48.303Z,1539976188.303 [controlThread](DEBUG): Initializing ControlThread
2018-10-19T19:09:48.304Z,1539976188.304 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-19T19:09:48.305Z,1539976188.305 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-19T19:09:48.305Z,1539976188.305 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-19T19:09:48.305Z,1539976188.305 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-19T19:09:48.306Z,1539976188.306 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-19T19:09:48.306Z,1539976188.306 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-19T19:09:48.312Z,1539976188.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-19T19:09:48.313Z,1539976188.313 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:09:48.313Z,1539976188.313 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:09:48.313Z,1539976188.313 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-19T19:09:48.314Z,1539976188.314 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:09:48.314Z,1539976188.314 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:09:48.315Z,1539976188.315 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-19T19:09:48.315Z,1539976188.315 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-19T19:09:48.315Z,1539976188.315 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-19T19:09:48.317Z,1539976188.317 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-19T19:09:48.318Z,1539976188.318 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-19T19:09:48.319Z,1539976188.319 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-19T19:09:48.319Z,1539976188.319 [SBIT](INFO): Initialize SBIT Component.
2018-10-19T19:09:48.319Z,1539976188.319 [SBIT](IMPORTANT): git: 2018-10-09-31-g8f1ca26
2018-10-19T19:09:48.320Z,1539976188.320 [SBIT](INFO): git hash: 8f1ca2616133f591d84f59109522710795260801
2018-10-19T19:09:48.320Z,1539976188.320 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-19T19:09:48.320Z,1539976188.320 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-19T19:09:48.321Z,1539976188.321 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-10-19T19:09:48.322Z,1539976188.322 [IBIT](INFO): Initialize IBIT Component.
2018-10-19T19:09:48.323Z,1539976188.323 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-19T19:09:48.323Z,1539976188.323 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-10-19T19:09:48.324Z,1539976188.324 [logger ThreadHandler](INFO): Handler Thread ID is 1960
2018-10-19T19:09:48.341Z,1539976188.341 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1961
2018-10-19T19:09:48.347Z,1539976188.347 [Radio_Surface](INFO): Powering up
2018-10-19T19:09:48.353Z,1539976188.353 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1962
2018-10-19T19:09:48.365Z,1539976188.365 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1963
2018-10-19T19:09:48.366Z,1539976188.366 [CTD_Seabird](INFO): Initializing
2018-10-19T19:09:48.366Z,1539976188.366 [CTD_Seabird](INFO): Checking LCM
2018-10-19T19:09:48.375Z,1539976188.375 [CTD_Seabird](INFO): LCM OK
2018-10-19T19:09:48.376Z,1539976188.376 [CTD_Seabird](INFO): Powering up
2018-10-19T19:09:48.386Z,1539976188.386 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1964
2018-10-19T19:09:48.387Z,1539976188.387 [WetLabsBB2FL](INFO): Powering down
2018-10-19T19:09:48.421Z,1539976188.421 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1965
2018-10-19T19:09:48.424Z,1539976188.424 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-19T19:09:48.424Z,1539976188.424 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-19T19:09:48.425Z,1539976188.425 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-19T19:09:48.425Z,1539976188.425 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-19T19:09:48.425Z,1539976188.425 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-19T19:09:48.425Z,1539976188.425 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-19T19:09:48.425Z,1539976188.425 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-19T19:09:48.425Z,1539976188.425 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-19T19:09:48.426Z,1539976188.426 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-19T19:09:48.426Z,1539976188.426 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-19T19:09:48.426Z,1539976188.426 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-19T19:09:48.426Z,1539976188.426 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-19T19:09:48.426Z,1539976188.426 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-19T19:09:48.427Z,1539976188.427 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-19T19:09:48.427Z,1539976188.427 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-19T19:09:48.427Z,1539976188.427 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-19T19:09:48.431Z,1539976188.431 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-19T19:09:48.446Z,1539976188.446 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-19T19:09:48.463Z,1539976188.463 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-10-19T19:09:48.464Z,1539976188.464 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-19T19:09:48.566Z,1539976188.566 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-19T19:09:48.567Z,1539976188.567 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-19T19:09:48.568Z,1539976188.568 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-19T19:09:48.612Z,1539976188.612 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-19T19:09:48.614Z,1539976188.614 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-19T19:09:48.640Z,1539976188.640 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-19T19:09:48.644Z,1539976188.644 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-10-19T19:09:48.653Z,1539976188.653 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-19T19:09:48.677Z,1539976188.677 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:09:48.743Z,1539976188.743 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-19T19:09:48.842Z,1539976188.842 [DVL_micro](INFO): Initializing
2018-10-19T19:09:48.882Z,1539976188.882 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-19T19:09:48.882Z,1539976188.882 [Onboard](FAULT): Can't read temperature LSB.
2018-10-19T19:09:48.882Z,1539976188.882 [Onboard] Hardware Fault, FailCount= 1
2018-10-19T19:09:48.882Z,1539976188.882 [Onboard](ERROR): Hardware Fault
2018-10-19T19:09:48.883Z,1539976188.883 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-19T19:09:48.883Z,1539976188.883 [Onboard](FAULT): Can't read temperature MSB.
2018-10-19T19:09:48.884Z,1539976188.884 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-19T19:09:48.884Z,1539976188.884 [Onboard](FAULT): Can't read pressure LSB.
2018-10-19T19:09:48.885Z,1539976188.885 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-19T19:09:48.885Z,1539976188.885 [Onboard](FAULT): Can't read pressure MSB.
2018-10-19T19:09:49.226Z,1539976189.226 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:09:49.233Z,1539976189.233 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-19T19:09:49.271Z,1539976189.271 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:09:49.281Z,1539976189.281 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-19T19:09:49.303Z,1539976189.303 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:09:49.313Z,1539976189.313 [MassServo](DEBUG): Initializing MassServo.
2018-10-19T19:09:49.337Z,1539976189.337 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:09:49.345Z,1539976189.345 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-19T19:09:49.366Z,1539976189.366 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:09:49.373Z,1539976189.373 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-19T19:09:49.422Z,1539976189.422 [CBIT](ERROR): Hardware Fault in component: Onboard
2018-10-19T19:09:49.422Z,1539976189.422 [CBIT](CRITICAL): Hardware Fault in component: Onboard
2018-10-19T19:09:49.512Z,1539976189.512 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-10-19T19:09:49.512Z,1539976189.512 [DropWeight] Hardware Fault, FailCount= 1
2018-10-19T19:09:49.512Z,1539976189.512 [DropWeight](ERROR): Hardware Fault
2018-10-19T19:09:49.570Z,1539976189.570 [CommandLine](FAULT): Scheduling is paused
2018-10-19T19:09:49.570Z,1539976189.570 [CBIT](INFO): Critical error at 20181019T190949
2018-10-19T19:09:49.570Z,1539976189.570 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-10-19T19:09:49.573Z,1539976189.573 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-10-19T19:09:49.573Z,1539976189.573 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-10-19T19:09:50.193Z,1539976190.193 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-10-19T19:09:50.193Z,1539976190.193 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-10-19T19:09:50.193Z,1539976190.193 [BuoyancyServo] Communications Fault, FailCount= 1
2018-10-19T19:09:50.193Z,1539976190.193 [BuoyancyServo](ERROR): Communications Fault
2018-10-19T19:09:50.395Z,1539976190.395 [CBIT](INFO): Critical error at 20181019T190949
2018-10-19T19:09:50.398Z,1539976190.398 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-10-19T19:09:50.441Z,1539976190.441 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-19T19:09:50.441Z,1539976190.441 [BuoyancyServo](INFO): Powering down
2018-10-19T19:09:52.329Z,1539976192.329 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:52.837Z,1539976192.837 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:52.885Z,1539976192.885 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-19T19:09:52.885Z,1539976192.885 [BuoyancyServo] No Fault, FailCount= 1
2018-10-19T19:09:53.229Z,1539976193.229 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:53.293Z,1539976193.293 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:09:53.414Z,1539976193.414 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-19T19:09:53.861Z,1539976193.861 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:53.889Z,1539976193.889 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-19T19:09:54.011Z,1539976194.011 [Radio_Surface](INFO): Powering down
2018-10-19T19:09:54.136Z,1539976194.136 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-10-19T19:09:54.183Z,1539976194.183 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-10-19T19:09:54.514Z,1539976194.514 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-19T19:09:54.514Z,1539976194.514 [CTD_Seabird](INFO): Powering down
2018-10-19T19:09:54.597Z,1539976194.597 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:55.029Z,1539976195.029 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:55.449Z,1539976195.449 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:55.461Z,1539976195.461 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2018-10-19T19:09:55.461Z,1539976195.461 [PNI_TCM] Data Fault, FailCount= 1
2018-10-19T19:09:55.461Z,1539976195.461 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:09:55.570Z,1539976195.570 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:09:55.570Z,1539976195.570 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-10-19T19:09:55.901Z,1539976195.901 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:55.980Z,1539976195.980 [CBIT](INFO): Critical error at 20181019T190955
2018-10-19T19:09:56.313Z,1539976196.313 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:56.773Z,1539976196.773 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:57.349Z,1539976197.349 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:57.825Z,1539976197.825 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:58.253Z,1539976198.253 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:58.542Z,1539976198.542 [CommandLine](IMPORTANT): got command get depth
2018-10-19T19:09:58.542Z,1539976198.542 [CommandLine](IMPORTANT): depth 25.595078 m
2018-10-19T19:09:58.653Z,1539976198.653 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:58.722Z,1539976198.722 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2139112%. Temp:4991592 C. ABORTING MISSION
2018-10-19T19:09:59.053Z,1539976199.053 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:09:59.053Z,1539976199.053 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:09:59.053Z,1539976199.053 [AHRS_M2] Communications Fault, FailCount= 1
2018-10-19T19:09:59.053Z,1539976199.053 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:09:59.153Z,1539976199.153 [CBIT](INFO): Critical error at 20181019T190958
2018-10-19T19:09:59.155Z,1539976199.155 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:09:59.169Z,1539976199.169 [AHRS_M2](INFO): Powering down
2018-10-19T19:10:02.788Z,1539976202.788 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:10:02.788Z,1539976202.788 [AHRS_M2] No Fault, FailCount= 1
2018-10-19T19:10:03.165Z,1539976203.165 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:10:06.677Z,1539976206.677 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:07.081Z,1539976207.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:07.513Z,1539976207.513 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:07.909Z,1539976207.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:08.309Z,1539976208.309 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:08.701Z,1539976208.701 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:09.097Z,1539976209.097 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:09.509Z,1539976209.509 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:09.909Z,1539976209.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:10.301Z,1539976210.301 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:10.701Z,1539976210.701 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:11.109Z,1539976211.109 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:11.509Z,1539976211.509 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:11.909Z,1539976211.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:12.317Z,1539976212.317 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:12.809Z,1539976212.809 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:13.209Z,1539976213.209 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:13.209Z,1539976213.209 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:10:13.209Z,1539976213.209 [AHRS_M2] Communications Fault, FailCount= 2
2018-10-19T19:10:13.209Z,1539976213.209 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:10:13.248Z,1539976213.248 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:10:13.278Z,1539976213.278 [AHRS_M2](INFO): Powering down
2018-10-19T19:10:13.550Z,1539976213.550 [CommandLine](IMPORTANT): got command get depth
2018-10-19T19:10:13.550Z,1539976213.550 [CommandLine](IMPORTANT): depth 25.602837 m
2018-10-19T19:10:15.283Z,1539976215.283 [NAL9602](INFO): Powering up NAL9602
2018-10-19T19:10:16.904Z,1539976216.904 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-19T19:10:16.919Z,1539976216.919 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:10:16.920Z,1539976216.920 [AHRS_M2] No Fault, FailCount= 2
2018-10-19T19:10:16.921Z,1539976216.921 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-19T19:10:17.277Z,1539976217.277 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:10:19.543Z,1539976219.543 [CommandLine](IMPORTANT): got command quit
2018-10-19T19:10:20.552Z,1539976220.552 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:10:20.552Z,1539976220.552 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:20.565Z,1539976220.565 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-19T19:10:20.565Z,1539976220.565 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:20.566Z,1539976220.566 [CommandLine](INFO): Join timeout helper Thread ID is 1984
2018-10-19T19:10:20.577Z,1539976220.577 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-19T19:10:20.577Z,1539976220.577 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:20.577Z,1539976220.577 [NavChartDb](INFO): Join timeout helper Thread ID is 1985
2018-10-19T19:10:20.857Z,1539976220.857 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:21.513Z,1539976221.513 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:21.985Z,1539976221.985 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:22.121Z,1539976222.121 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:10:22.122Z,1539976222.122 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.125Z,1539976222.125 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-10-19T19:10:22.125Z,1539976222.125 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.125Z,1539976222.125 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1986
2018-10-19T19:10:22.193Z,1539976222.193 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:10:22.193Z,1539976222.193 [WetLabsBB2FL](INFO): Powering down
2018-10-19T19:10:22.194Z,1539976222.194 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.209Z,1539976222.209 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-10-19T19:10:22.209Z,1539976222.209 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.209Z,1539976222.209 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1987
2018-10-19T19:10:22.429Z,1539976222.429 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:10:22.515Z,1539976222.515 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-19T19:10:22.516Z,1539976222.516 [CTD_Seabird](INFO): Powering down
2018-10-19T19:10:22.517Z,1539976222.517 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.529Z,1539976222.529 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-10-19T19:10:22.529Z,1539976222.529 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.530Z,1539976222.530 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1989
2018-10-19T19:10:22.605Z,1539976222.605 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:22.678Z,1539976222.678 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:10:22.679Z,1539976222.679 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.694Z,1539976222.694 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-19T19:10:22.694Z,1539976222.694 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.694Z,1539976222.694 [Radio_Surface](INFO): Join timeout helper Thread ID is 1990
2018-10-19T19:10:22.729Z,1539976222.729 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:10:22.729Z,1539976222.729 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.750Z,1539976222.750 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-19T19:10:22.750Z,1539976222.750 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.750Z,1539976222.750 [logger](INFO): Join timeout helper Thread ID is 1991
2018-10-19T19:10:22.785Z,1539976222.785 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:10:22.785Z,1539976222.785 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.790Z,1539976222.790 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-19T19:10:22.790Z,1539976222.790 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.790Z,1539976222.790 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-19T19:10:22.790Z,1539976222.790 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:22.790Z,1539976222.790 [controlThread](INFO): Join timeout helper Thread ID is 1992
2018-10-19T19:10:23.029Z,1539976223.029 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:10:23.088Z,1539976223.088 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:10:23.089Z,1539976223.089 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-19T19:10:23.089Z,1539976223.089 [AHRS_M2](INFO): Powering down
2018-10-19T19:10:23.162Z,1539976223.162 [DVL_micro](INFO): uninitialize:Powering down
2018-10-19T19:10:23.162Z,1539976223.162 [NAL9602](INFO): Powering down
2018-10-19T19:10:23.187Z,1539976223.187 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-19T19:10:23.188Z,1539976223.188 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-19T19:10:23.188Z,1539976223.188 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-19T19:10:23.189Z,1539976223.189 [MissionManager](INFO): Uninitializing Mission Default
2018-10-19T19:10:23.192Z,1539976223.192 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-19T19:10:23.194Z,1539976223.194 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-19T19:10:23.194Z,1539976223.194 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-19T19:10:23.194Z,1539976223.194 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-19T19:10:23.194Z,1539976223.194 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-19T19:10:23.195Z,1539976223.195 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-19T19:10:23.195Z,1539976223.195 [BuoyancyServo](INFO): Powering down
2018-10-19T19:10:23.209Z,1539976223.209 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-19T19:10:23.209Z,1539976223.209 [ElevatorServo](INFO): Powering down
2018-10-19T19:10:23.210Z,1539976223.210 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-19T19:10:23.210Z,1539976223.210 [MassServo](INFO): Powering down
2018-10-19T19:10:23.211Z,1539976223.211 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-19T19:10:23.211Z,1539976223.211 [RudderServo](INFO): Powering down
2018-10-19T19:10:23.212Z,1539976223.212 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-19T19:10:23.212Z,1539976223.212 [ThrusterServo](INFO): Powering down
2018-10-19T19:10:23.213Z,1539976223.213 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-19T19:10:23.213Z,1539976223.213 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-19T19:10:23.214Z,1539976223.214 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-19T19:10:23.215Z,1539976223.215 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:23.315Z,1539976223.315 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:23.358Z,1539976223.358 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:23.361Z,1539976223.361 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:23.364Z,1539976223.364 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:23.532Z,1539976223.532 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:10:23.578Z,1539976223.578 [logger ThreadHandler](INFO): Thread cancelled.