2018-10-19T19:42:58.324Z,1539978178.324 [Supervisor](DEBUG): Initializing supervisor.
2018-10-19T19:42:58.327Z,1539978178.327 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-19T19:42:58.328Z,1539978178.328 [SyncHandler](INFO): Protected caller Thread ID is 3043
2018-10-19T19:42:58.328Z,1539978178.328 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-19T19:42:58.329Z,1539978178.329 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-19T19:42:58.329Z,1539978178.329 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3044
2018-10-19T19:42:58.332Z,1539978178.332 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-19T19:42:58.344Z,1539978178.344 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-19T19:42:58.345Z,1539978178.345 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-19T19:42:58.345Z,1539978178.345 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3045
2018-10-19T19:42:58.346Z,1539978178.346 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-19T19:42:58.347Z,1539978178.347 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-19T19:42:58.347Z,1539978178.347 [logger ThreadHandler](INFO): Protected caller Thread ID is 3046
2018-10-19T19:42:58.349Z,1539978178.349 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-19T19:42:58.349Z,1539978178.349 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-19T19:42:58.351Z,1539978178.351 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-19T19:42:58.785Z,1539978178.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-19T19:42:58.786Z,1539978178.786 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-19T19:42:59.232Z,1539978179.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-19T19:42:59.232Z,1539978179.232 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-19T19:42:59.425Z,1539978179.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-19T19:42:59.425Z,1539978179.425 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-19T19:42:59.569Z,1539978179.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-19T19:42:59.570Z,1539978179.570 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-19T19:43:00.006Z,1539978180.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-19T19:43:00.007Z,1539978180.007 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-19T19:43:00.101Z,1539978180.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-19T19:43:00.102Z,1539978180.102 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-19T19:43:00.242Z,1539978180.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-19T19:43:00.243Z,1539978180.243 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-19T19:43:00.342Z,1539978180.342 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-19T19:43:00.343Z,1539978180.343 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-19T19:43:00.445Z,1539978180.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-19T19:43:00.445Z,1539978180.445 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-19T19:43:00.788Z,1539978180.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-19T19:43:00.789Z,1539978180.789 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-19T19:43:00.989Z,1539978180.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-19T19:43:00.990Z,1539978180.990 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-19T19:43:01.201Z,1539978181.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-19T19:43:01.202Z,1539978181.202 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-19T19:43:01.282Z,1539978181.282 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-19T19:43:01.631Z,1539978181.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-19T19:43:01.631Z,1539978181.631 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-19T19:43:01.948Z,1539978181.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-19T19:43:01.950Z,1539978181.950 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-10-19T19:43:01.951Z,1539978181.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-10-19T19:43:02.035Z,1539978182.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-10-19T19:43:02.183Z,1539978182.183 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-10-19T19:43:02.281Z,1539978182.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-10-19T19:43:02.451Z,1539978182.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-10-19T19:43:02.532Z,1539978182.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-10-19T19:43:02.756Z,1539978182.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-19T19:43:02.756Z,1539978182.756 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-10-19T19:43:02.870Z,1539978182.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-10-19T19:43:02.969Z,1539978182.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-10-19T19:43:03.107Z,1539978183.107 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-10-19T19:43:03.354Z,1539978183.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-10-19T19:43:03.725Z,1539978183.725 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-10-19T19:43:03.727Z,1539978183.727 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-19T19:43:03.772Z,1539978183.772 [DepthRateCalculator] Loaded
2018-10-19T19:43:03.772Z,1539978183.772 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-19T19:43:03.778Z,1539978183.778 [PitchRateCalculator] Loaded
2018-10-19T19:43:03.778Z,1539978183.778 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-19T19:43:03.794Z,1539978183.794 [SpeedCalculator] Loaded
2018-10-19T19:43:03.794Z,1539978183.794 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-19T19:43:03.815Z,1539978183.815 [TempGradientCalculator] Loaded
2018-10-19T19:43:03.815Z,1539978183.815 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-19T19:43:03.821Z,1539978183.821 [YawRateCalculator] Loaded
2018-10-19T19:43:03.821Z,1539978183.821 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-19T19:43:03.861Z,1539978183.861 [ElevatorOffsetCalculator] Loaded
2018-10-19T19:43:03.862Z,1539978183.862 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-19T19:43:03.862Z,1539978183.862 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-19T19:43:03.863Z,1539978183.863 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-19T19:43:04.107Z,1539978184.107 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-19T19:43:04.108Z,1539978184.108 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-19T19:43:04.422Z,1539978184.422 [AHRS_M2] Loaded
2018-10-19T19:43:04.423Z,1539978184.423 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-10-19T19:43:04.493Z,1539978184.493 [DataOverHttps] Loaded
2018-10-19T19:43:04.494Z,1539978184.494 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-19T19:43:04.507Z,1539978184.507 [Depth_Keller] Loaded
2018-10-19T19:43:04.507Z,1539978184.507 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-19T19:43:04.512Z,1539978184.512 [DropWeight] Loaded
2018-10-19T19:43:04.512Z,1539978184.512 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-19T19:43:04.642Z,1539978184.642 [DVL_micro] Loaded
2018-10-19T19:43:04.642Z,1539978184.642 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2018-10-19T19:43:04.741Z,1539978184.741 [NAL9602] Loaded
2018-10-19T19:43:04.742Z,1539978184.742 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-19T19:43:04.757Z,1539978184.757 [Onboard] Loaded
2018-10-19T19:43:04.758Z,1539978184.758 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-19T19:43:04.768Z,1539978184.768 [Radio_Surface] Loaded
2018-10-19T19:43:04.768Z,1539978184.768 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-19T19:43:04.770Z,1539978184.770 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-10-19T19:43:04.770Z,1539978184.770 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3125
2018-10-19T19:43:04.777Z,1539978184.777 [SCPI] Loaded
2018-10-19T19:43:04.777Z,1539978184.777 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-10-19T19:43:04.822Z,1539978184.822 [PNI_TCM] Loaded
2018-10-19T19:43:04.822Z,1539978184.822 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-10-19T19:43:06.596Z,1539978186.596 [BPC1] Loaded
2018-10-19T19:43:06.596Z,1539978186.596 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-10-19T19:43:06.596Z,1539978186.596 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-19T19:43:06.597Z,1539978186.597 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-19T19:43:06.662Z,1539978186.662 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-19T19:43:06.663Z,1539978186.663 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-19T19:43:06.758Z,1539978186.758 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-10-19T19:43:06.758Z,1539978186.758 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-10-19T19:43:06.812Z,1539978186.812 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-19T19:43:06.813Z,1539978186.813 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-19T19:43:06.829Z,1539978186.829 [NavChart] Loaded
2018-10-19T19:43:06.829Z,1539978186.829 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-19T19:43:06.833Z,1539978186.833 [UniversalFixResidualReporter] Loaded
2018-10-19T19:43:06.833Z,1539978186.833 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-19T19:43:06.834Z,1539978186.834 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-19T19:43:06.834Z,1539978186.834 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-19T19:43:07.061Z,1539978187.061 [CTD_NeilBrown] Loaded
2018-10-19T19:43:07.062Z,1539978187.062 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-10-19T19:43:07.063Z,1539978187.063 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-10-19T19:43:07.063Z,1539978187.063 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3126
2018-10-19T19:43:07.119Z,1539978187.119 [CTD_Seabird] Loaded
2018-10-19T19:43:07.119Z,1539978187.119 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-10-19T19:43:07.120Z,1539978187.120 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2018-10-19T19:43:07.121Z,1539978187.121 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3127
2018-10-19T19:43:07.170Z,1539978187.170 [WetLabsBB2FL] Loaded
2018-10-19T19:43:07.171Z,1539978187.171 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-10-19T19:43:07.172Z,1539978187.172 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0
2018-10-19T19:43:07.172Z,1539978187.172 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3128
2018-10-19T19:43:07.173Z,1539978187.173 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-19T19:43:07.173Z,1539978187.173 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-19T19:43:07.242Z,1539978187.242 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-19T19:43:07.341Z,1539978187.341 [VerticalControl] Loaded
2018-10-19T19:43:07.342Z,1539978187.342 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-19T19:43:07.342Z,1539978187.342 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-19T19:43:07.411Z,1539978187.411 [HorizontalControl] Loaded
2018-10-19T19:43:07.411Z,1539978187.411 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-19T19:43:07.412Z,1539978187.412 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-19T19:43:07.417Z,1539978187.417 [SpeedControl] Loaded
2018-10-19T19:43:07.418Z,1539978187.418 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-19T19:43:07.418Z,1539978187.418 [LoopControl](DEBUG): Construct LoopControl.
2018-10-19T19:43:07.419Z,1539978187.419 [LoopControl] Loaded
2018-10-19T19:43:07.419Z,1539978187.419 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-19T19:43:07.420Z,1539978187.420 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-19T19:43:07.420Z,1539978187.420 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-19T19:43:07.434Z,1539978187.434 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-19T19:43:07.434Z,1539978187.434 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-19T19:43:07.566Z,1539978187.566 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-19T19:43:07.578Z,1539978187.578 [SBIT] Loaded
2018-10-19T19:43:07.578Z,1539978187.578 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-19T19:43:07.579Z,1539978187.579 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-19T19:43:07.590Z,1539978187.590 [IBIT] Loaded
2018-10-19T19:43:07.591Z,1539978187.591 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-19T19:43:07.594Z,1539978187.594 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-19T19:43:07.745Z,1539978187.745 [CBIT] Loaded
2018-10-19T19:43:07.746Z,1539978187.746 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-19T19:43:07.746Z,1539978187.746 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-19T19:43:07.747Z,1539978187.747 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-19T19:43:07.849Z,1539978187.849 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-19T19:43:07.849Z,1539978187.849 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-19T19:43:07.874Z,1539978187.874 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-19T19:43:07.875Z,1539978187.875 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-19T19:43:07.978Z,1539978187.978 [BuoyancyServo] Loaded
2018-10-19T19:43:07.978Z,1539978187.978 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-19T19:43:07.989Z,1539978187.989 [ElevatorServo] Loaded
2018-10-19T19:43:07.990Z,1539978187.990 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-19T19:43:08.000Z,1539978188.000 [MassServo] Loaded
2018-10-19T19:43:08.001Z,1539978188.001 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-19T19:43:08.012Z,1539978188.012 [RudderServo] Loaded
2018-10-19T19:43:08.012Z,1539978188.012 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-19T19:43:08.023Z,1539978188.023 [ThrusterServo] Loaded
2018-10-19T19:43:08.024Z,1539978188.024 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-19T19:43:08.024Z,1539978188.024 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-19T19:43:08.028Z,1539978188.028 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-19T19:43:08.029Z,1539978188.029 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-19T19:43:08.035Z,1539978188.035 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-19T19:43:08.036Z,1539978188.036 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2018-10-19T19:43:08.037Z,1539978188.037 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3129
2018-10-19T19:43:08.041Z,1539978188.041 [Supervisor](INFO): Main Thread ID is 3042
2018-10-19T19:43:08.041Z,1539978188.041 [Supervisor](DEBUG): Running supervisor.
2018-10-19T19:43:08.042Z,1539978188.042 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3130
2018-10-19T19:43:08.044Z,1539978188.044 [controlThread ThreadHandler](INFO): Handler Thread ID is 3131
2018-10-19T19:43:08.045Z,1539978188.045 [controlThread](DEBUG): Initializing ControlThread
2018-10-19T19:43:08.045Z,1539978188.046 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-19T19:43:08.046Z,1539978188.046 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-19T19:43:08.046Z,1539978188.046 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-19T19:43:08.046Z,1539978188.046 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-19T19:43:08.047Z,1539978188.047 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-19T19:43:08.047Z,1539978188.047 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-19T19:43:08.055Z,1539978188.055 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-19T19:43:08.056Z,1539978188.056 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:43:08.057Z,1539978188.057 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:43:08.057Z,1539978188.057 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-19T19:43:08.058Z,1539978188.058 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:43:08.058Z,1539978188.058 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:43:08.059Z,1539978188.059 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-19T19:43:08.059Z,1539978188.059 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-19T19:43:08.059Z,1539978188.059 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-19T19:43:08.061Z,1539978188.061 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-19T19:43:08.062Z,1539978188.062 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-19T19:43:08.062Z,1539978188.062 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-19T19:43:08.063Z,1539978188.063 [SBIT](INFO): Initialize SBIT Component.
2018-10-19T19:43:08.063Z,1539978188.063 [SBIT](IMPORTANT): git: 2018-10-09-31-g8f1ca26
2018-10-19T19:43:08.063Z,1539978188.063 [SBIT](INFO): git hash: 8f1ca2616133f591d84f59109522710795260801
2018-10-19T19:43:08.064Z,1539978188.064 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-19T19:43:08.064Z,1539978188.064 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-19T19:43:08.065Z,1539978188.065 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-10-19T19:43:08.066Z,1539978188.066 [IBIT](INFO): Initialize IBIT Component.
2018-10-19T19:43:08.067Z,1539978188.067 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-19T19:43:08.067Z,1539978188.067 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-10-19T19:43:08.068Z,1539978188.068 [logger ThreadHandler](INFO): Handler Thread ID is 3132
2018-10-19T19:43:08.085Z,1539978188.085 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3133
2018-10-19T19:43:08.091Z,1539978188.091 [Radio_Surface](INFO): Powering up
2018-10-19T19:43:08.097Z,1539978188.097 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3134
2018-10-19T19:43:08.109Z,1539978188.109 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3135
2018-10-19T19:43:08.110Z,1539978188.110 [CTD_Seabird](INFO): Initializing
2018-10-19T19:43:08.110Z,1539978188.110 [CTD_Seabird](INFO): Checking LCM
2018-10-19T19:43:08.119Z,1539978188.119 [CTD_Seabird](INFO): LCM OK
2018-10-19T19:43:08.119Z,1539978188.119 [CTD_Seabird](INFO): Powering up
2018-10-19T19:43:08.129Z,1539978188.129 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3136
2018-10-19T19:43:08.131Z,1539978188.131 [WetLabsBB2FL](INFO): Powering down
2018-10-19T19:43:08.157Z,1539978188.157 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3137
2018-10-19T19:43:08.161Z,1539978188.161 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-19T19:43:08.161Z,1539978188.161 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-19T19:43:08.163Z,1539978188.163 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-19T19:43:08.163Z,1539978188.163 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-19T19:43:08.164Z,1539978188.164 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-19T19:43:08.165Z,1539978188.165 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-19T19:43:08.165Z,1539978188.165 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-19T19:43:08.165Z,1539978188.165 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-19T19:43:08.165Z,1539978188.165 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-19T19:43:08.166Z,1539978188.166 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-19T19:43:08.166Z,1539978188.166 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-19T19:43:08.166Z,1539978188.166 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-19T19:43:08.166Z,1539978188.166 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-19T19:43:08.166Z,1539978188.166 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-19T19:43:08.167Z,1539978188.167 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-19T19:43:08.167Z,1539978188.167 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-19T19:43:08.175Z,1539978188.175 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-19T19:43:08.186Z,1539978188.186 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-19T19:43:08.258Z,1539978188.258 [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:43:08.259Z,1539978188.259 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-19T19:43:08.343Z,1539978188.343 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-19T19:43:08.344Z,1539978188.344 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-19T19:43:08.346Z,1539978188.346 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-19T19:43:08.384Z,1539978188.384 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-19T19:43:08.403Z,1539978188.403 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-19T19:43:08.425Z,1539978188.425 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-19T19:43:08.429Z,1539978188.429 [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:43:08.441Z,1539978188.441 [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:43:08.471Z,1539978188.471 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:43:08.533Z,1539978188.533 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-19T19:43:08.542Z,1539978188.542 [Depth_Keller](ERROR): Pressure reading out of range: 1624.131348 decibar
2018-10-19T19:43:08.572Z,1539978188.572 [DVL_micro](INFO): Initializing
2018-10-19T19:43:08.630Z,1539978188.630 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-19T19:43:08.630Z,1539978188.630 [Onboard](FAULT): Can't read temperature LSB.
2018-10-19T19:43:08.630Z,1539978188.630 [Onboard] Hardware Fault, FailCount= 1
2018-10-19T19:43:08.630Z,1539978188.630 [Onboard](ERROR): Hardware Fault
2018-10-19T19:43:08.631Z,1539978188.631 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-19T19:43:08.631Z,1539978188.631 [Onboard](FAULT): Can't read temperature MSB.
2018-10-19T19:43:08.632Z,1539978188.632 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-19T19:43:08.632Z,1539978188.632 [Onboard](FAULT): Can't read pressure LSB.
2018-10-19T19:43:08.633Z,1539978188.633 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-19T19:43:08.633Z,1539978188.633 [Onboard](FAULT): Can't read pressure MSB.
2018-10-19T19:43:08.741Z,1539978188.741 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-19T19:43:08.937Z,1539978188.937 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:43:08.945Z,1539978188.945 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-19T19:43:08.959Z,1539978188.959 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:43:08.965Z,1539978188.965 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-19T19:43:08.979Z,1539978188.979 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:43:08.985Z,1539978188.985 [MassServo](DEBUG): Initializing MassServo.
2018-10-19T19:43:09.006Z,1539978189.006 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:43:09.013Z,1539978189.013 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-19T19:43:09.023Z,1539978189.023 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:43:09.029Z,1539978189.029 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-19T19:43:09.065Z,1539978189.065 [CBIT](ERROR): Hardware Fault in component: Onboard
2018-10-19T19:43:09.065Z,1539978189.065 [CBIT](CRITICAL): Hardware Fault in component: Onboard
2018-10-19T19:43:09.279Z,1539978189.279 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-10-19T19:43:09.279Z,1539978189.279 [DropWeight] Hardware Fault, FailCount= 1
2018-10-19T19:43:09.279Z,1539978189.279 [DropWeight](ERROR): Hardware Fault
2018-10-19T19:43:09.326Z,1539978189.326 [CommandLine](FAULT): Scheduling is paused
2018-10-19T19:43:09.327Z,1539978189.327 [CBIT](INFO): Critical error at 20181019T194309
2018-10-19T19:43:09.327Z,1539978189.327 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-10-19T19:43:09.330Z,1539978189.330 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-10-19T19:43:09.330Z,1539978189.330 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-10-19T19:43:09.861Z,1539978189.861 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-10-19T19:43:09.861Z,1539978189.861 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-10-19T19:43:09.861Z,1539978189.861 [BuoyancyServo] Communications Fault, FailCount= 1
2018-10-19T19:43:09.861Z,1539978189.861 [BuoyancyServo](ERROR): Communications Fault
2018-10-19T19:43:10.098Z,1539978190.098 [CBIT](INFO): Critical error at 20181019T194309
2018-10-19T19:43:10.101Z,1539978190.101 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-10-19T19:43:10.156Z,1539978190.156 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-19T19:43:10.157Z,1539978190.157 [BuoyancyServo](INFO): Powering down
2018-10-19T19:43:12.033Z,1539978192.033 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:12.453Z,1539978192.453 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:12.526Z,1539978192.526 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-19T19:43:12.526Z,1539978192.526 [BuoyancyServo] No Fault, FailCount= 1
2018-10-19T19:43:12.860Z,1539978192.860 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:12.905Z,1539978192.905 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-19T19:43:13.026Z,1539978193.026 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-19T19:43:13.473Z,1539978193.473 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:13.750Z,1539978193.750 [Radio_Surface](INFO): Powering down
2018-10-19T19:43:13.985Z,1539978193.985 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-19T19:43:14.068Z,1539978194.068 [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:43:14.089Z,1539978194.089 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:14.146Z,1539978194.146 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-10-19T19:43:14.573Z,1539978194.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:14.674Z,1539978194.674 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-19T19:43:14.674Z,1539978194.674 [CTD_Seabird](INFO): Powering down
2018-10-19T19:43:15.021Z,1539978195.021 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:15.421Z,1539978195.421 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:15.829Z,1539978195.829 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:15.835Z,1539978195.835 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:43:15.835Z,1539978195.835 [PNI_TCM] Data Fault, FailCount= 1
2018-10-19T19:43:15.835Z,1539978195.835 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:43:15.926Z,1539978195.926 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:43:16.257Z,1539978196.257 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:16.263Z,1539978196.263 [PNI_TCM](INFO): Powering down
2018-10-19T19:43:16.769Z,1539978196.769 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:17.221Z,1539978197.221 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:17.749Z,1539978197.749 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:17.814Z,1539978197.814 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-10-19T19:43:17.814Z,1539978197.814 [PNI_TCM] No Fault, FailCount= 1
2018-10-19T19:43:18.149Z,1539978198.149 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:18.234Z,1539978198.234 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2139112%. Temp:4992688 C. ABORTING MISSION
2018-10-19T19:43:18.569Z,1539978198.569 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:18.569Z,1539978198.569 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:43:18.569Z,1539978198.569 [AHRS_M2] Communications Fault, FailCount= 1
2018-10-19T19:43:18.569Z,1539978198.569 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:43:18.665Z,1539978198.665 [CBIT](INFO): Critical error at 20181019T194318
2018-10-19T19:43:18.670Z,1539978198.670 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:43:18.697Z,1539978198.697 [AHRS_M2](INFO): Powering down
2018-10-19T19:43:22.341Z,1539978202.341 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:43:22.341Z,1539978202.341 [AHRS_M2] No Fault, FailCount= 1
2018-10-19T19:43:22.705Z,1539978202.705 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:43:25.099Z,1539978205.099 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:43:25.099Z,1539978205.099 [PNI_TCM] Data Fault, FailCount= 2
2018-10-19T19:43:25.100Z,1539978205.100 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:43:25.146Z,1539978205.146 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:43:25.499Z,1539978205.499 [PNI_TCM](INFO): Powering down
2018-10-19T19:43:26.217Z,1539978206.217 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:26.617Z,1539978206.617 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:26.682Z,1539978206.682 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-10-19T19:43:26.682Z,1539978206.682 [PNI_TCM] No Fault, FailCount= 2
2018-10-19T19:43:27.029Z,1539978207.029 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:27.453Z,1539978207.453 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:27.849Z,1539978207.849 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:28.293Z,1539978208.293 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:28.697Z,1539978208.697 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:29.105Z,1539978209.105 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:29.529Z,1539978209.529 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:29.925Z,1539978209.925 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:30.329Z,1539978210.329 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:30.737Z,1539978210.737 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:31.145Z,1539978211.145 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:31.565Z,1539978211.565 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:31.965Z,1539978211.965 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:32.365Z,1539978212.365 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:32.765Z,1539978212.765 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:32.765Z,1539978212.765 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:43:32.765Z,1539978212.765 [AHRS_M2] Communications Fault, FailCount= 2
2018-10-19T19:43:32.765Z,1539978212.765 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:43:32.825Z,1539978212.825 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:43:32.849Z,1539978212.849 [AHRS_M2](INFO): Powering down
2018-10-19T19:43:34.455Z,1539978214.455 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:43:34.455Z,1539978214.455 [PNI_TCM] Data Fault, FailCount= 3
2018-10-19T19:43:34.455Z,1539978214.455 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:43:34.510Z,1539978214.510 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:43:34.855Z,1539978214.855 [NAL9602](INFO): Powering up NAL9602
2018-10-19T19:43:34.856Z,1539978214.856 [PNI_TCM](INFO): Powering down
2018-10-19T19:43:36.098Z,1539978216.098 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-10-19T19:43:36.098Z,1539978216.098 [PNI_TCM] No Fault, FailCount= 3
2018-10-19T19:43:36.515Z,1539978216.515 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-19T19:43:36.519Z,1539978216.519 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:43:36.519Z,1539978216.519 [AHRS_M2] No Fault, FailCount= 2
2018-10-19T19:43:36.520Z,1539978216.520 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-19T19:43:36.849Z,1539978216.849 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:43:40.373Z,1539978220.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:40.985Z,1539978220.985 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:41.457Z,1539978221.457 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:42.105Z,1539978222.105 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:42.593Z,1539978222.593 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:43.029Z,1539978223.029 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:43.545Z,1539978223.545 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:43.551Z,1539978223.551 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:43:43.551Z,1539978223.551 [PNI_TCM] Data Fault, FailCount= 4
2018-10-19T19:43:43.552Z,1539978223.552 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:43:43.658Z,1539978223.658 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:43:44.085Z,1539978224.085 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:44.091Z,1539978224.091 [PNI_TCM](INFO): Powering down
2018-10-19T19:43:44.605Z,1539978224.605 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:45.049Z,1539978225.049 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:45.573Z,1539978225.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:45.678Z,1539978225.678 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-10-19T19:43:45.679Z,1539978225.679 [PNI_TCM] No Fault, FailCount= 4
2018-10-19T19:43:46.005Z,1539978226.005 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:46.429Z,1539978226.429 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:46.929Z,1539978226.929 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:46.929Z,1539978226.929 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:43:46.929Z,1539978226.929 [AHRS_M2] Communications Fault, FailCount= 3
2018-10-19T19:43:46.929Z,1539978226.929 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:43:47.062Z,1539978227.062 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:43:47.069Z,1539978227.069 [AHRS_M2](INFO): Powering down
2018-10-19T19:43:48.354Z,1539978228.354 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007511
CHAN A1 (24V): 0.001789
CHAN A2 (12V): -0.005567
CHAN A3 (5V): -0.002814
CHAN B0 (3.3V): -0.001510
CHAN B1 (3.15aV): -0.000864
CHAN B2 (3.15bV): -0.001344
CHAN B3 (GND): -0.000065
OPEN: -0.001280
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-19T19:43:49.544Z,1539978229.544 [SBIT](FAULT): Mass: EXPECTED:0.009000 ACTUAL:0.005563
2018-10-19T19:43:50.766Z,1539978230.766 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:43:50.766Z,1539978230.766 [AHRS_M2] No Fault, FailCount= 3
2018-10-19T19:43:51.069Z,1539978231.069 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:43:51.934Z,1539978231.934 [CommandLine](IMPORTANT): got command get depth
2018-10-19T19:43:51.934Z,1539978231.934 [CommandLine](IMPORTANT): depth 21.075586 m
2018-10-19T19:43:53.075Z,1539978233.075 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:43:53.076Z,1539978233.076 [PNI_TCM] Data Fault, FailCount= 5
2018-10-19T19:43:53.076Z,1539978233.076 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:43:53.226Z,1539978233.226 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:43:53.226Z,1539978233.226 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-10-19T19:43:53.475Z,1539978233.475 [PNI_TCM](INFO): Powering down
2018-10-19T19:43:53.677Z,1539978233.677 [CBIT](INFO): Critical error at 20181019T194353
2018-10-19T19:43:54.585Z,1539978234.585 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:55.065Z,1539978235.065 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:55.525Z,1539978235.525 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:55.985Z,1539978235.985 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:55.991Z,1539978235.991 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-19T19:43:55.991Z,1539978235.991 [NAL9602] Communications Fault, FailCount= 1
2018-10-19T19:43:55.991Z,1539978235.991 [NAL9602](ERROR): Communications Fault
2018-10-19T19:43:56.158Z,1539978236.158 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-19T19:43:56.505Z,1539978236.505 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:56.521Z,1539978236.521 [NAL9602](INFO): Powering down
2018-10-19T19:43:56.985Z,1539978236.985 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:57.649Z,1539978237.649 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:57.750Z,1539978237.750 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-19T19:43:57.750Z,1539978237.750 [NAL9602] No Fault, FailCount= 1
2018-10-19T19:43:58.085Z,1539978238.085 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:58.497Z,1539978238.497 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:58.921Z,1539978238.921 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:59.349Z,1539978239.349 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:43:59.761Z,1539978239.761 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:00.181Z,1539978240.181 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:00.609Z,1539978240.609 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:01.045Z,1539978241.045 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:01.481Z,1539978241.481 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:01.481Z,1539978241.481 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:44:01.481Z,1539978241.481 [AHRS_M2] Communications Fault, FailCount= 4
2018-10-19T19:44:01.481Z,1539978241.481 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:44:01.635Z,1539978241.635 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:44:01.646Z,1539978241.646 [AHRS_M2](INFO): Powering down
2018-10-19T19:44:02.329Z,1539978242.329 [Radio_Surface](INFO): Powering up
2018-10-19T19:44:05.276Z,1539978245.276 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:44:05.277Z,1539978245.277 [AHRS_M2] No Fault, FailCount= 4
2018-10-19T19:44:05.645Z,1539978245.645 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:44:07.986Z,1539978247.986 [Radio_Surface](INFO): Powering down
2018-10-19T19:44:09.169Z,1539978249.169 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:09.569Z,1539978249.569 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:09.981Z,1539978249.981 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:10.385Z,1539978250.385 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:10.765Z,1539978250.765 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:11.173Z,1539978251.173 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:11.573Z,1539978251.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:11.973Z,1539978251.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:12.373Z,1539978252.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:12.773Z,1539978252.773 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:13.173Z,1539978253.173 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:13.573Z,1539978253.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:13.973Z,1539978253.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:14.365Z,1539978254.365 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:14.773Z,1539978254.773 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:15.173Z,1539978255.173 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:15.573Z,1539978255.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:15.973Z,1539978255.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:44:15.973Z,1539978255.973 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:44:15.973Z,1539978255.973 [AHRS_M2] Communications Fault, FailCount= 5
2018-10-19T19:44:15.973Z,1539978255.973 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:44:16.001Z,1539978256.001 [BPC1](ERROR): BPC1B: No match for serial number 0273 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2018-10-19T19:44:16.067Z,1539978256.067 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:44:16.067Z,1539978256.067 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2018-10-19T19:44:16.079Z,1539978256.079 [AHRS_M2](INFO): Powering down
2018-10-19T19:44:16.159Z,1539978256.159 [BPC1](ERROR): BPC1A: No match for serial number 0226 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2018-10-19T19:44:16.173Z,1539978256.173 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-10-19T19:44:16.173Z,1539978256.173 [BPC1] Data Fault, FailCount= 1
2018-10-19T19:44:16.173Z,1539978256.173 [BPC1](ERROR): Data Fault
2018-10-19T19:44:16.190Z,1539978256.190 [CBIT](INFO): Critical error at 20181019T194416
2018-10-19T19:44:16.193Z,1539978256.193 [CBIT](ERROR): Data Fault in component: BPC1
2018-10-19T19:44:26.544Z,1539978266.544 [NAL9602](INFO): Powering up NAL9602
2018-10-19T19:44:29.769Z,1539978269.769 [SBIT](CRITICAL): SBIT FAILED
2018-10-19T19:44:29.813Z,1539978269.813 [CBIT](INFO): Critical error at 20181019T194429
2018-10-19T19:44:29.834Z,1539978269.834 [CommandLine](IMPORTANT): got command configSet list
2018-10-19T19:44:29.834Z,1539978269.834 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-19T19:44:29.835Z,1539978269.835 [CommandLine](IMPORTANT): No configSet variables persisted
2018-10-19T19:44:30.159Z,1539978270.159 [MissionManager](IMPORTANT): Started mission Startup
2018-10-19T19:44:30.159Z,1539978270.159 [Startup] Running Loop=1
2018-10-19T19:44:30.159Z,1539978270.159 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-19T19:44:30.159Z,1539978270.159 [Startup:A.GoToSurface] Running Loop=1
2018-10-19T19:44:30.159Z,1539978270.159 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-19T19:44:30.160Z,1539978270.160 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-19T19:44:30.160Z,1539978270.160 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-19T19:44:30.193Z,1539978270.193 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-19T19:44:30.194Z,1539978270.194 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-19T19:44:30.194Z,1539978270.194 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-19T19:44:30.242Z,1539978270.242 [Radio_Surface](INFO): Powering up
2018-10-19T19:44:37.363Z,1539978277.363 [NAL9602](INFO): NAL9602 initialized
2018-10-19T19:44:38.996Z,1539978278.996 [VerticalControl](CRITICAL): Excessive depth excursion=30.716087 m, failToGoUpDepth_=35.359261 m, depthRate=1.511302 m/s, pitch=nan deg.
2018-10-19T19:44:39.052Z,1539978279.052 [CBIT](INFO): Critical error at 20181019T194438
2018-10-19T19:44:44.179Z,1539978284.179 [VerticalControl](CRITICAL): Dropping drop weight at excessive depth excursion=44.910072, failToGoUpDepth_=35.359261, depthRate=2.129368 m/s, pitch=nan deg.
2018-10-19T19:44:44.244Z,1539978284.244 [CBIT](INFO): Critical error at 20181019T194444
2018-10-19T19:45:08.173Z,1539978308.173 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-19T19:45:08.173Z,1539978308.173 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-10-19T19:45:08.173Z,1539978308.173 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-19T19:45:08.175Z,1539978308.175 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-19T19:45:08.175Z,1539978308.175 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-19T19:45:08.175Z,1539978308.175 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-19T19:45:08.221Z,1539978308.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-19T19:45:08.222Z,1539978308.222 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-19T19:45:08.581Z,1539978308.581 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-19T19:45:08.581Z,1539978308.581 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-10-19T19:45:08.582Z,1539978308.582 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-19T19:45:08.582Z,1539978308.582 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-19T19:45:08.968Z,1539978308.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-19T19:45:08.969Z,1539978308.969 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:45:08.969Z,1539978308.969 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:45:08.970Z,1539978308.970 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-19T19:45:08.970Z,1539978308.970 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:45:08.970Z,1539978308.970 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:45:41.390Z,1539978341.390 [Radio_Surface](INFO): Powering down
2018-10-19T19:45:47.450Z,1539978347.450 [Radio_Surface](INFO): Powering up
2018-10-19T19:46:10.566Z,1539978370.566 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2018-10-19T19:46:10.566Z,1539978370.566 [DVL_micro] Data Fault, FailCount= 1
2018-10-19T19:46:10.566Z,1539978370.566 [DVL_micro](ERROR): Data Fault
2018-10-19T19:46:10.611Z,1539978370.611 [CBIT](ERROR): Data Fault in component: DVL_micro
2018-10-19T19:46:10.612Z,1539978370.612 [CBIT](CRITICAL): Data Fault in component: DVL_micro
2018-10-19T19:46:10.964Z,1539978370.964 [DVL_micro](INFO): uninitialize:Powering down
2018-10-19T19:46:11.020Z,1539978371.020 [CBIT](INFO): Critical error at 20181019T194610
2018-10-19T19:46:52.931Z,1539978412.931 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004576
2018-10-19T19:47:09.341Z,1539978429.341 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-19T19:47:09.341Z,1539978429.341 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-10-19T19:47:09.341Z,1539978429.341 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-19T19:47:09.342Z,1539978429.342 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-19T19:47:09.343Z,1539978429.343 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-10-19T19:47:09.343Z,1539978429.343 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-19T19:47:09.384Z,1539978429.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-19T19:47:09.384Z,1539978429.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-19T19:47:09.750Z,1539978429.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-19T19:47:09.750Z,1539978429.750 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-10-19T19:47:09.750Z,1539978429.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-19T19:47:09.750Z,1539978429.750 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-10-19T19:47:10.136Z,1539978430.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-19T19:47:10.137Z,1539978430.137 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:47:10.137Z,1539978430.137 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:47:10.138Z,1539978430.138 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-19T19:47:10.138Z,1539978430.138 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:47:10.139Z,1539978430.139 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:48:53.203Z,1539978533.203 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-10-19T19:48:53.203Z,1539978533.203 [PNI_TCM] No Fault, FailCount= 5
2018-10-19T19:49:00.767Z,1539978540.767 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:49:00.767Z,1539978540.767 [PNI_TCM] Data Fault, FailCount= 1
2018-10-19T19:49:00.767Z,1539978540.767 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:49:00.824Z,1539978540.824 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:49:03.862Z,1539978543.862 [PNI_TCM](INFO): Powering down
2018-10-19T19:49:05.218Z,1539978545.218 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-10-19T19:49:05.218Z,1539978545.218 [PNI_TCM] No Fault, FailCount= 1
2018-10-19T19:49:10.409Z,1539978550.409 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-19T19:49:10.409Z,1539978550.409 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-10-19T19:49:10.409Z,1539978550.409 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-19T19:49:10.411Z,1539978550.411 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-19T19:49:10.411Z,1539978550.411 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-10-19T19:49:10.411Z,1539978550.411 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-19T19:49:10.425Z,1539978550.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-19T19:49:10.425Z,1539978550.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-19T19:49:10.834Z,1539978550.834 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-19T19:49:10.834Z,1539978550.834 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-10-19T19:49:10.835Z,1539978550.835 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-19T19:49:10.835Z,1539978550.835 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-10-19T19:49:11.216Z,1539978551.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-19T19:49:11.216Z,1539978551.216 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:49:11.216Z,1539978551.216 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:49:11.217Z,1539978551.217 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-19T19:49:11.218Z,1539978551.218 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:49:11.218Z,1539978551.218 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:49:12.795Z,1539978552.795 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:49:12.795Z,1539978552.795 [PNI_TCM] Data Fault, FailCount= 2
2018-10-19T19:49:12.795Z,1539978552.795 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:49:12.843Z,1539978552.843 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:49:13.192Z,1539978553.192 [PNI_TCM](INFO): Powering down
2018-10-19T19:49:14.439Z,1539978554.439 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-10-19T19:49:14.439Z,1539978554.439 [PNI_TCM] No Fault, FailCount= 2
2018-10-19T19:49:16.043Z,1539978556.043 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:49:16.043Z,1539978556.043 [AHRS_M2] No Fault, FailCount= 5
2018-10-19T19:49:16.385Z,1539978556.385 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:49:19.909Z,1539978559.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:20.309Z,1539978560.309 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:20.721Z,1539978560.721 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:21.109Z,1539978561.109 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:21.509Z,1539978561.509 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:21.921Z,1539978561.921 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:21.931Z,1539978561.931 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:49:21.931Z,1539978561.931 [PNI_TCM] Data Fault, FailCount= 3
2018-10-19T19:49:21.931Z,1539978561.931 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:49:21.950Z,1539978561.950 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:49:22.325Z,1539978562.325 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:22.335Z,1539978562.335 [PNI_TCM](INFO): Powering down
2018-10-19T19:49:22.773Z,1539978562.773 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:23.166Z,1539978563.166 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:23.566Z,1539978563.566 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:23.594Z,1539978563.594 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-10-19T19:49:23.594Z,1539978563.594 [PNI_TCM] No Fault, FailCount= 3
2018-10-19T19:49:23.977Z,1539978563.977 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:24.377Z,1539978564.377 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:24.777Z,1539978564.777 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:25.185Z,1539978565.185 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:25.573Z,1539978565.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:25.981Z,1539978565.981 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:26.377Z,1539978566.377 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:26.781Z,1539978566.781 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:26.781Z,1539978566.781 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:49:26.781Z,1539978566.781 [AHRS_M2] Communications Fault, FailCount= 1
2018-10-19T19:49:26.781Z,1539978566.781 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:49:26.804Z,1539978566.804 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:49:26.857Z,1539978566.857 [AHRS_M2](INFO): Powering down
2018-10-19T19:49:30.499Z,1539978570.499 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:49:30.499Z,1539978570.499 [AHRS_M2] No Fault, FailCount= 1
2018-10-19T19:49:30.857Z,1539978570.857 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:49:31.263Z,1539978571.263 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:49:31.263Z,1539978571.263 [PNI_TCM] Data Fault, FailCount= 4
2018-10-19T19:49:31.264Z,1539978571.264 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:49:31.316Z,1539978571.316 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:49:31.663Z,1539978571.663 [PNI_TCM](INFO): Powering down
2018-10-19T19:49:32.894Z,1539978572.894 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-10-19T19:49:32.895Z,1539978572.895 [PNI_TCM] No Fault, FailCount= 4
2018-10-19T19:49:34.373Z,1539978574.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:34.393Z,1539978574.393 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2018-10-19T19:49:34.909Z,1539978574.909 [NAL9602](FAULT): received: 
2018-10-19T19:49:34.909Z,1539978574.909 [NAL9602] Data Fault, FailCount= 2
2018-10-19T19:49:34.909Z,1539978574.909 [NAL9602](ERROR): Data Fault
2018-10-19T19:49:34.972Z,1539978574.972 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-19T19:49:35.313Z,1539978575.313 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:35.315Z,1539978575.315 [NAL9602](INFO): Powering down
2018-10-19T19:49:35.705Z,1539978575.705 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:36.105Z,1539978576.105 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:36.168Z,1539978576.168 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-19T19:49:36.168Z,1539978576.168 [NAL9602] No Fault, FailCount= 2
2018-10-19T19:49:36.509Z,1539978576.509 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:36.905Z,1539978576.905 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:37.305Z,1539978577.305 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:40.365Z,1539978580.365 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:40.757Z,1539978580.757 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:41.157Z,1539978581.157 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:41.157Z,1539978581.157 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:49:41.157Z,1539978581.157 [AHRS_M2] Communications Fault, FailCount= 2
2018-10-19T19:49:41.157Z,1539978581.157 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:49:41.164Z,1539978581.164 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-10-19T19:49:41.165Z,1539978581.165 [PNI_TCM] Data Fault, FailCount= 5
2018-10-19T19:49:41.165Z,1539978581.165 [PNI_TCM](ERROR): Data Fault
2018-10-19T19:49:41.183Z,1539978581.183 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:49:41.184Z,1539978581.184 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-10-19T19:49:41.184Z,1539978581.184 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-10-19T19:49:41.234Z,1539978581.234 [AHRS_M2](INFO): Powering down
2018-10-19T19:49:41.312Z,1539978581.312 [PNI_TCM](INFO): Powering down
2018-10-19T19:49:41.419Z,1539978581.419 [CBIT](INFO): Critical error at 20181019T194941
2018-10-19T19:49:44.867Z,1539978584.867 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:49:44.867Z,1539978584.867 [AHRS_M2] No Fault, FailCount= 2
2018-10-19T19:49:45.233Z,1539978585.233 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:49:48.746Z,1539978588.746 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:49.146Z,1539978589.146 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:49.546Z,1539978589.546 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:49.946Z,1539978589.946 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:50.346Z,1539978590.346 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:50.746Z,1539978590.746 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:51.146Z,1539978591.146 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:51.547Z,1539978591.547 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:51.946Z,1539978591.946 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:52.346Z,1539978592.346 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:52.746Z,1539978592.746 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:53.146Z,1539978593.146 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:53.546Z,1539978593.546 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:53.946Z,1539978593.946 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:54.346Z,1539978594.346 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:54.746Z,1539978594.746 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:55.149Z,1539978595.149 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:55.546Z,1539978595.546 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:49:55.546Z,1539978595.546 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:49:55.546Z,1539978595.546 [AHRS_M2] Communications Fault, FailCount= 3
2018-10-19T19:49:55.546Z,1539978595.546 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:49:55.568Z,1539978595.568 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:49:55.633Z,1539978595.633 [AHRS_M2](INFO): Powering down
2018-10-19T19:49:59.269Z,1539978599.269 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:49:59.269Z,1539978599.269 [AHRS_M2] No Fault, FailCount= 3
2018-10-19T19:49:59.633Z,1539978599.633 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:50:03.149Z,1539978603.149 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:03.557Z,1539978603.557 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:03.949Z,1539978603.949 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:04.357Z,1539978604.357 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:04.757Z,1539978604.757 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:05.157Z,1539978605.157 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:05.557Z,1539978605.557 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:05.560Z,1539978605.560 [NAL9602](INFO): Powering up NAL9602
2018-10-19T19:50:05.949Z,1539978605.949 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:06.349Z,1539978606.349 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:06.749Z,1539978606.749 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:07.157Z,1539978607.157 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:07.557Z,1539978607.557 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:07.957Z,1539978607.957 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:08.357Z,1539978608.357 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:08.757Z,1539978608.757 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:09.157Z,1539978609.157 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:09.549Z,1539978609.549 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:09.958Z,1539978609.958 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:09.958Z,1539978609.958 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:50:09.958Z,1539978609.958 [AHRS_M2] Communications Fault, FailCount= 4
2018-10-19T19:50:09.958Z,1539978609.958 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:50:09.984Z,1539978609.984 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:50:10.045Z,1539978610.045 [AHRS_M2](INFO): Powering down
2018-10-19T19:50:14.784Z,1539978614.784 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-19T19:50:14.784Z,1539978614.784 [AHRS_M2] No Fault, FailCount= 4
2018-10-19T19:50:15.157Z,1539978615.157 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-19T19:50:16.366Z,1539978616.366 [NAL9602](INFO): NAL9602 initialized
2018-10-19T19:50:18.681Z,1539978618.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:19.081Z,1539978619.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:19.481Z,1539978619.481 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:19.490Z,1539978619.490 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-10-19T19:50:19.881Z,1539978619.881 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:20.281Z,1539978620.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:20.681Z,1539978620.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:21.074Z,1539978621.074 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:21.489Z,1539978621.489 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:21.889Z,1539978621.889 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:22.289Z,1539978622.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:22.689Z,1539978622.689 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:23.089Z,1539978623.089 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:23.493Z,1539978623.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:23.889Z,1539978623.889 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:24.289Z,1539978624.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:24.689Z,1539978624.689 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:25.089Z,1539978625.089 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:25.489Z,1539978625.489 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-19T19:50:25.489Z,1539978625.489 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-19T19:50:25.489Z,1539978625.489 [AHRS_M2] Communications Fault, FailCount= 5
2018-10-19T19:50:25.489Z,1539978625.489 [AHRS_M2](ERROR): Communications Fault
2018-10-19T19:50:25.517Z,1539978625.517 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-19T19:50:25.517Z,1539978625.517 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2018-10-19T19:50:25.566Z,1539978625.566 [AHRS_M2](INFO): Powering down
2018-10-19T19:50:25.670Z,1539978625.670 [CBIT](INFO): Critical error at 20181019T195025
2018-10-19T19:50:32.391Z,1539978632.391 [VerticalControl](CRITICAL): Excessive depth excursion=14.480202 m, failToGoUpDepth_=76.858261 m, depthRate=2.028051 m/s, pitch=nan deg.
2018-10-19T19:50:32.433Z,1539978632.433 [CBIT](INFO): Critical error at 20181019T195032
2018-10-19T19:51:11.515Z,1539978671.515 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-19T19:51:11.516Z,1539978671.516 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-10-19T19:51:11.516Z,1539978671.516 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-19T19:51:11.517Z,1539978671.517 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-19T19:51:11.517Z,1539978671.517 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-10-19T19:51:11.517Z,1539978671.517 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-19T19:51:11.531Z,1539978671.531 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-19T19:51:11.532Z,1539978671.532 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-19T19:51:11.934Z,1539978671.934 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-19T19:51:11.934Z,1539978671.934 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-10-19T19:51:11.934Z,1539978671.934 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-19T19:51:11.934Z,1539978671.934 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-10-19T19:51:12.296Z,1539978672.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-19T19:51:12.297Z,1539978672.297 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:51:12.297Z,1539978672.297 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:51:12.298Z,1539978672.298 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-19T19:51:12.298Z,1539978672.298 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-19T19:51:12.298Z,1539978672.298 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-19T19:51:13.427Z,1539978673.427 [CommandLine](IMPORTANT): got command restart application
2018-10-19T19:51:14.436Z,1539978674.436 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:51:14.436Z,1539978674.436 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:14.629Z,1539978674.629 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-19T19:51:14.629Z,1539978674.629 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:14.630Z,1539978674.630 [CommandLine](INFO): Join timeout helper Thread ID is 3183
2018-10-19T19:51:14.630Z,1539978674.630 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-19T19:51:14.630Z,1539978674.630 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:14.631Z,1539978674.631 [NavChartDb](INFO): Join timeout helper Thread ID is 3184
2018-10-19T19:51:14.633Z,1539978674.633 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:51:14.633Z,1539978674.633 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:14.649Z,1539978674.649 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-10-19T19:51:14.649Z,1539978674.649 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:14.649Z,1539978674.649 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3185
2018-10-19T19:51:14.709Z,1539978674.709 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:51:14.709Z,1539978674.709 [WetLabsBB2FL](INFO): Powering down
2018-10-19T19:51:14.710Z,1539978674.710 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:14.721Z,1539978674.721 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-10-19T19:51:14.721Z,1539978674.721 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:14.721Z,1539978674.721 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3186
2018-10-19T19:51:14.905Z,1539978674.905 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:51:14.992Z,1539978674.992 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-19T19:51:14.992Z,1539978674.992 [CTD_Seabird](INFO): Powering down
2018-10-19T19:51:14.993Z,1539978674.993 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.001Z,1539978675.001 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-10-19T19:51:15.001Z,1539978675.001 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.002Z,1539978675.002 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3188
2018-10-19T19:51:15.121Z,1539978675.121 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:51:15.121Z,1539978675.121 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.134Z,1539978675.134 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-19T19:51:15.134Z,1539978675.134 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.134Z,1539978675.134 [Radio_Surface](INFO): Join timeout helper Thread ID is 3189
2018-10-19T19:51:15.293Z,1539978675.293 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:51:15.293Z,1539978675.293 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.306Z,1539978675.306 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-19T19:51:15.306Z,1539978675.306 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.306Z,1539978675.306 [logger](INFO): Join timeout helper Thread ID is 3190
2018-10-19T19:51:15.317Z,1539978675.317 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:51:15.317Z,1539978675.317 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.326Z,1539978675.326 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-19T19:51:15.326Z,1539978675.326 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.326Z,1539978675.326 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-19T19:51:15.326Z,1539978675.326 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.326Z,1539978675.326 [controlThread](INFO): Join timeout helper Thread ID is 3191
2018-10-19T19:51:15.481Z,1539978675.481 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-19T19:51:15.481Z,1539978675.481 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-19T19:51:15.482Z,1539978675.482 [AHRS_M2](INFO): Powering down
2018-10-19T19:51:15.483Z,1539978675.483 [DVL_micro](INFO): uninitialize:Powering down
2018-10-19T19:51:15.484Z,1539978675.484 [NAL9602](INFO): Powering down
2018-10-19T19:51:15.485Z,1539978675.485 [PNI_TCM](INFO): Powering down
2018-10-19T19:51:15.486Z,1539978675.486 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-19T19:51:15.487Z,1539978675.487 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-19T19:51:15.487Z,1539978675.487 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-19T19:51:15.488Z,1539978675.488 [MissionManager](INFO): Uninitializing Mission Default
2018-10-19T19:51:15.491Z,1539978675.491 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-19T19:51:15.491Z,1539978675.491 [Startup] Stopped
2018-10-19T19:51:15.491Z,1539978675.491 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-19T19:51:15.491Z,1539978675.491 [Startup:A.GoToSurface] Stopped
2018-10-19T19:51:15.491Z,1539978675.491 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-19T19:51:15.493Z,1539978675.493 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-19T19:51:15.494Z,1539978675.494 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-19T19:51:15.494Z,1539978675.494 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-19T19:51:15.494Z,1539978675.494 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-19T19:51:15.494Z,1539978675.494 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-19T19:51:15.495Z,1539978675.495 [BuoyancyServo](INFO): Powering down
2018-10-19T19:51:15.509Z,1539978675.509 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-19T19:51:15.509Z,1539978675.509 [ElevatorServo](INFO): Powering down
2018-10-19T19:51:15.510Z,1539978675.510 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-19T19:51:15.510Z,1539978675.510 [MassServo](INFO): Powering down
2018-10-19T19:51:15.511Z,1539978675.511 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-19T19:51:15.511Z,1539978675.511 [RudderServo](INFO): Powering down
2018-10-19T19:51:15.512Z,1539978675.512 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-19T19:51:15.512Z,1539978675.512 [ThrusterServo](INFO): Powering down
2018-10-19T19:51:15.513Z,1539978675.513 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-19T19:51:15.514Z,1539978675.514 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-19T19:51:15.514Z,1539978675.514 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-19T19:51:15.515Z,1539978675.515 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.618Z,1539978675.618 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.663Z,1539978675.663 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.666Z,1539978675.666 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.669Z,1539978675.669 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.717Z,1539978675.717 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-19T19:51:15.774Z,1539978675.774 [logger ThreadHandler](INFO): Thread cancelled.