2019-05-14T20:36:23.428Z,1557866183.428 [Supervisor](DEBUG): Initializing supervisor.
2019-05-14T20:36:23.431Z,1557866183.431 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-14T20:36:23.432Z,1557866183.432 [SyncHandler](INFO): Protected caller Thread ID is 4367
2019-05-14T20:36:23.433Z,1557866183.433 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-14T20:36:23.434Z,1557866183.434 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-14T20:36:23.434Z,1557866183.434 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4368
2019-05-14T20:36:23.438Z,1557866183.438 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-14T20:36:23.450Z,1557866183.450 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-14T20:36:23.451Z,1557866183.451 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-14T20:36:23.451Z,1557866183.451 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4369
2019-05-14T20:36:23.452Z,1557866183.452 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-14T20:36:23.453Z,1557866183.453 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-14T20:36:23.453Z,1557866183.453 [logger ThreadHandler](INFO): Protected caller Thread ID is 4370
2019-05-14T20:36:23.455Z,1557866183.455 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-14T20:36:23.456Z,1557866183.456 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-14T20:36:23.457Z,1557866183.457 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-14T20:36:23.947Z,1557866183.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-14T20:36:23.947Z,1557866183.947 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-14T20:36:24.093Z,1557866184.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-14T20:36:24.094Z,1557866184.094 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-14T20:36:24.177Z,1557866184.177 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-14T20:36:24.278Z,1557866184.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-14T20:36:24.279Z,1557866184.279 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-14T20:36:24.377Z,1557866184.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-14T20:36:24.377Z,1557866184.377 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-14T20:36:24.760Z,1557866184.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-14T20:36:24.761Z,1557866184.761 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-14T20:36:24.866Z,1557866184.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-14T20:36:24.866Z,1557866184.866 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-14T20:36:25.324Z,1557866185.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-14T20:36:25.325Z,1557866185.325 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-14T20:36:25.529Z,1557866185.529 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-14T20:36:25.530Z,1557866185.530 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-14T20:36:25.836Z,1557866185.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-14T20:36:25.836Z,1557866185.836 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-14T20:36:26.051Z,1557866186.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-14T20:36:26.052Z,1557866186.052 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-14T20:36:26.196Z,1557866186.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-14T20:36:26.197Z,1557866186.197 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-14T20:36:26.728Z,1557866186.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-14T20:36:26.729Z,1557866186.729 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-14T20:36:27.064Z,1557866187.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-14T20:36:27.064Z,1557866187.064 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-14T20:36:27.273Z,1557866187.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-14T20:36:27.275Z,1557866187.275 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-05-14T20:36:27.276Z,1557866187.276 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-05-14T20:36:27.519Z,1557866187.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-05-14T20:36:28.055Z,1557866188.055 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-05-14T20:36:28.670Z,1557866188.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-05-14T20:36:29.020Z,1557866189.020 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-05-14T20:36:29.411Z,1557866189.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-14T20:36:29.411Z,1557866189.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-05-14T20:36:29.508Z,1557866189.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-05-14T20:36:29.620Z,1557866189.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-05-14T20:36:29.717Z,1557866189.717 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-05-14T20:36:29.866Z,1557866189.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-05-14T20:36:29.969Z,1557866189.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-05-14T20:36:30.072Z,1557866190.072 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-05-14T20:36:30.073Z,1557866190.073 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-14T20:36:30.078Z,1557866190.078 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-14T20:36:30.102Z,1557866190.102 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-14T20:36:30.103Z,1557866190.103 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-14T20:36:30.186Z,1557866190.186 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-14T20:36:30.296Z,1557866190.296 [VerticalControl] Loaded
2019-05-14T20:36:30.296Z,1557866190.296 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-14T20:36:30.297Z,1557866190.297 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-14T20:36:30.364Z,1557866190.364 [HorizontalControl] Loaded
2019-05-14T20:36:30.364Z,1557866190.364 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-14T20:36:30.365Z,1557866190.365 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-14T20:36:30.370Z,1557866190.370 [SpeedControl] Loaded
2019-05-14T20:36:30.370Z,1557866190.370 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-14T20:36:30.371Z,1557866190.371 [LoopControl](DEBUG): Construct LoopControl.
2019-05-14T20:36:30.371Z,1557866190.371 [LoopControl] Loaded
2019-05-14T20:36:30.372Z,1557866190.372 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-14T20:36:30.372Z,1557866190.372 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-14T20:36:30.373Z,1557866190.373 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-14T20:36:30.511Z,1557866190.511 [BuoyancyServo] Loaded
2019-05-14T20:36:30.512Z,1557866190.512 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-14T20:36:30.523Z,1557866190.523 [ElevatorServo] Loaded
2019-05-14T20:36:30.523Z,1557866190.523 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-14T20:36:30.533Z,1557866190.533 [MassServo] Loaded
2019-05-14T20:36:30.533Z,1557866190.533 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-14T20:36:30.544Z,1557866190.544 [RudderServo] Loaded
2019-05-14T20:36:30.544Z,1557866190.544 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-14T20:36:30.555Z,1557866190.555 [ThrusterServo] Loaded
2019-05-14T20:36:30.555Z,1557866190.555 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-14T20:36:30.556Z,1557866190.556 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-14T20:36:30.556Z,1557866190.556 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-14T20:36:30.609Z,1557866190.609 [DepthRateCalculator] Loaded
2019-05-14T20:36:30.609Z,1557866190.609 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-14T20:36:30.614Z,1557866190.614 [PitchRateCalculator] Loaded
2019-05-14T20:36:30.615Z,1557866190.615 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-14T20:36:30.629Z,1557866190.629 [SpeedCalculator] Loaded
2019-05-14T20:36:30.630Z,1557866190.630 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-14T20:36:30.651Z,1557866190.651 [TempGradientCalculator] Loaded
2019-05-14T20:36:30.651Z,1557866190.651 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-14T20:36:30.656Z,1557866190.656 [YawRateCalculator] Loaded
2019-05-14T20:36:30.656Z,1557866190.656 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-14T20:36:30.684Z,1557866190.684 [ElevatorOffsetCalculator] Loaded
2019-05-14T20:36:30.685Z,1557866190.685 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-14T20:36:30.685Z,1557866190.685 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-14T20:36:30.686Z,1557866190.686 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-14T20:36:30.804Z,1557866190.804 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-14T20:36:30.805Z,1557866190.805 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-14T20:36:30.861Z,1557866190.861 [DeadReckonUsingSpeedCalculator] Loaded
2019-05-14T20:36:30.861Z,1557866190.861 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-05-14T20:36:30.882Z,1557866190.882 [NavChart] Loaded
2019-05-14T20:36:30.882Z,1557866190.882 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-14T20:36:30.886Z,1557866190.886 [UniversalFixResidualReporter] Loaded
2019-05-14T20:36:30.886Z,1557866190.886 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-14T20:36:30.887Z,1557866190.887 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-14T20:36:30.887Z,1557866190.887 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-14T20:36:31.324Z,1557866191.324 [AHRS_M2] Loaded
2019-05-14T20:36:31.324Z,1557866191.324 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-05-14T20:36:31.399Z,1557866191.399 [DataOverHttps] Loaded
2019-05-14T20:36:31.399Z,1557866191.399 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-14T20:36:31.400Z,1557866191.400 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4080D4E0
2019-05-14T20:36:31.400Z,1557866191.400 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4449
2019-05-14T20:36:31.414Z,1557866191.414 [Depth_Keller] Loaded
2019-05-14T20:36:31.414Z,1557866191.414 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-14T20:36:31.419Z,1557866191.419 [DropWeight] Loaded
2019-05-14T20:36:31.419Z,1557866191.419 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-05-14T20:36:31.513Z,1557866191.513 [NAL9602] Loaded
2019-05-14T20:36:31.513Z,1557866191.513 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-14T20:36:31.528Z,1557866191.528 [Onboard] Loaded
2019-05-14T20:36:31.529Z,1557866191.529 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-14T20:36:31.532Z,1557866191.532 [Radio_Surface] Loaded
2019-05-14T20:36:31.532Z,1557866191.532 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-14T20:36:31.533Z,1557866191.533 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4083D4E0
2019-05-14T20:36:31.534Z,1557866191.534 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4450
2019-05-14T20:36:32.999Z,1557866192.999 [BPC1] Loaded
2019-05-14T20:36:32.999Z,1557866192.999 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-14T20:36:32.000Z,1557866193.000 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-14T20:36:33.000Z,1557866193.000 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-14T20:36:33.405Z,1557866193.405 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-14T20:36:33.406Z,1557866193.406 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-14T20:36:33.631Z,1557866193.631 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-14T20:36:33.631Z,1557866193.631 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-14T20:36:33.671Z,1557866193.671 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-14T20:36:33.672Z,1557866193.672 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-14T20:36:33.983Z,1557866193.983 [CTD_NeilBrown] Loaded
2019-05-14T20:36:33.983Z,1557866193.983 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-14T20:36:33.984Z,1557866193.984 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409C94E0
2019-05-14T20:36:33.985Z,1557866193.985 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4451
2019-05-14T20:36:33.994Z,1557866193.994 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-14T20:36:33.999Z,1557866193.999 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-14T20:36:34.001Z,1557866194.001 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-14T20:36:34.006Z,1557866194.006 [CTD_Seabird](INFO): created writer for : depth
2019-05-14T20:36:34.006Z,1557866194.006 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-14T20:36:34.011Z,1557866194.011 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-14T20:36:34.012Z,1557866194.012 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-14T20:36:34.016Z,1557866194.016 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-14T20:36:34.017Z,1557866194.017 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-14T20:36:34.022Z,1557866194.022 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-14T20:36:34.022Z,1557866194.022 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-14T20:36:34.027Z,1557866194.027 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-14T20:36:34.028Z,1557866194.028 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-14T20:36:34.033Z,1557866194.033 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-14T20:36:34.059Z,1557866194.059 [CTD_Seabird] Loaded
2019-05-14T20:36:34.059Z,1557866194.059 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-14T20:36:34.060Z,1557866194.060 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409F94E0
2019-05-14T20:36:34.061Z,1557866194.061 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4452
2019-05-14T20:36:34.075Z,1557866194.075 [PAR_Licor] Loaded
2019-05-14T20:36:34.076Z,1557866194.076 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-14T20:36:34.122Z,1557866194.122 [WetLabsBB2FL] Loaded
2019-05-14T20:36:34.122Z,1557866194.122 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-14T20:36:34.123Z,1557866194.123 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A294E0
2019-05-14T20:36:34.124Z,1557866194.124 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4453
2019-05-14T20:36:34.124Z,1557866194.124 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-14T20:36:34.125Z,1557866194.125 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-14T20:36:34.433Z,1557866194.433 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-14T20:36:34.433Z,1557866194.433 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-14T20:36:34.586Z,1557866194.586 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-14T20:36:34.597Z,1557866194.597 [SBIT] Loaded
2019-05-14T20:36:34.597Z,1557866194.597 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-14T20:36:34.598Z,1557866194.598 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-14T20:36:34.609Z,1557866194.609 [IBIT] Loaded
2019-05-14T20:36:34.609Z,1557866194.609 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-14T20:36:34.612Z,1557866194.612 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-14T20:36:34.748Z,1557866194.748 [CBIT] Loaded
2019-05-14T20:36:34.748Z,1557866194.748 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-14T20:36:34.748Z,1557866194.748 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-14T20:36:34.752Z,1557866194.752 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-14T20:36:34.753Z,1557866194.753 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-14T20:36:34.760Z,1557866194.760 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-14T20:36:34.761Z,1557866194.761 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B014E0
2019-05-14T20:36:34.762Z,1557866194.762 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4454
2019-05-14T20:36:34.767Z,1557866194.767 [Supervisor](INFO): Main Thread ID is 5615
2019-05-14T20:36:34.767Z,1557866194.767 [Supervisor](DEBUG): Running supervisor.
2019-05-14T20:36:34.767Z,1557866194.767 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4455
2019-05-14T20:36:34.770Z,1557866194.770 [controlThread ThreadHandler](INFO): Handler Thread ID is 4456
2019-05-14T20:36:34.770Z,1557866194.770 [controlThread](DEBUG): Initializing ControlThread
2019-05-14T20:36:34.771Z,1557866194.771 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-14T20:36:34.773Z,1557866194.773 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-14T20:36:34.773Z,1557866194.773 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-14T20:36:34.774Z,1557866194.774 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-14T20:36:34.775Z,1557866194.775 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-14T20:36:34.776Z,1557866194.776 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-14T20:36:34.776Z,1557866194.776 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-14T20:36:34.776Z,1557866194.776 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-14T20:36:34.777Z,1557866194.777 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-14T20:36:34.777Z,1557866194.777 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-14T20:36:34.778Z,1557866194.778 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-14T20:36:34.779Z,1557866194.779 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-14T20:36:34.779Z,1557866194.779 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-14T20:36:34.786Z,1557866194.786 [SBIT](INFO): Initialize SBIT Component.
2019-05-14T20:36:34.787Z,1557866194.787 [SBIT](IMPORTANT): git: 2019-04-10-2-gf3e4ef2
2019-05-14T20:36:34.787Z,1557866194.787 [SBIT](INFO): git hash: f3e4ef2c5f3aa5e841d57d486bee5a5f25629231
2019-05-14T20:36:34.787Z,1557866194.787 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-14T20:36:34.788Z,1557866194.788 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-14T20:36:34.790Z,1557866194.790 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-05-14T20:36:34.790Z,1557866194.790 [IBIT](INFO): Initialize IBIT Component.
2019-05-14T20:36:34.791Z,1557866194.791 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-14T20:36:34.792Z,1557866194.792 [logger ThreadHandler](INFO): Handler Thread ID is 4457
2019-05-14T20:36:34.802Z,1557866194.802 [CBIT](DEBUG): Initialized mux pins.
2019-05-14T20:36:34.802Z,1557866194.802 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-14T20:36:34.810Z,1557866194.810 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4458
2019-05-14T20:36:34.811Z,1557866194.811 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-14T20:36:34.822Z,1557866194.822 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4459
2019-05-14T20:36:34.826Z,1557866194.826 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-14T20:36:34.826Z,1557866194.826 [CBIT](DEBUG): Initializing heartbeat.
2019-05-14T20:36:34.834Z,1557866194.834 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4460
2019-05-14T20:36:34.842Z,1557866194.842 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4461
2019-05-14T20:36:34.843Z,1557866194.843 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-14T20:36:34.846Z,1557866194.846 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-14T20:36:34.848Z,1557866194.848 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4463
2019-05-14T20:36:34.849Z,1557866194.849 [WetLabsBB2FL](INFO): Powering down
2019-05-14T20:36:34.874Z,1557866194.874 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4464
2019-05-14T20:36:34.880Z,1557866194.880 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-14T20:36:34.880Z,1557866194.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-14T20:36:34.881Z,1557866194.881 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-14T20:36:34.881Z,1557866194.881 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-14T20:36:34.881Z,1557866194.881 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-14T20:36:34.881Z,1557866194.881 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-14T20:36:34.882Z,1557866194.882 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-14T20:36:34.882Z,1557866194.882 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-14T20:36:34.882Z,1557866194.882 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-14T20:36:34.882Z,1557866194.882 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-14T20:36:34.883Z,1557866194.883 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-14T20:36:34.883Z,1557866194.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-14T20:36:34.883Z,1557866194.883 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-14T20:36:34.883Z,1557866194.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-14T20:36:34.884Z,1557866194.884 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-14T20:36:34.884Z,1557866194.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-14T20:36:34.898Z,1557866194.898 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-14T20:36:34.898Z,1557866194.898 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-14T20:36:34.934Z,1557866194.934 [CBIT](DEBUG): Backplane powered.
2019-05-14T20:36:34.935Z,1557866194.935 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-14T20:36:34.947Z,1557866194.947 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-14T20:36:34.998Z,1557866194.998 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-05-14T20:36:34.999Z,1557866194.999 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-14T20:36:35.067Z,1557866195.067 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-14T20:36:35.069Z,1557866195.069 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-14T20:36:35.070Z,1557866195.070 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-14T20:36:35.105Z,1557866195.105 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-14T20:36:35.116Z,1557866195.116 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-14T20:36:35.158Z,1557866195.158 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-14T20:36:35.161Z,1557866195.161 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-05-14T20:36:35.170Z,1557866195.170 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,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,
2019-05-14T20:36:35.190Z,1557866195.190 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:36:35.226Z,1557866195.226 [Radio_Surface](INFO): Powering up
2019-05-14T20:36:35.352Z,1557866195.352 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:36:35.358Z,1557866195.358 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-14T20:36:35.359Z,1557866195.359 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:36:35.366Z,1557866195.366 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-14T20:36:35.367Z,1557866195.367 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:36:35.374Z,1557866195.374 [MassServo](DEBUG): Initializing MassServo.
2019-05-14T20:36:35.375Z,1557866195.375 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:36:35.382Z,1557866195.382 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-14T20:36:35.383Z,1557866195.383 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:36:35.390Z,1557866195.390 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-14T20:36:35.595Z,1557866195.595 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-05-14T20:36:35.595Z,1557866195.595 [DropWeight] Hardware Fault, FailCount= 1
2019-05-14T20:36:35.595Z,1557866195.595 [DropWeight](ERROR): Hardware Fault
2019-05-14T20:36:35.644Z,1557866195.644 [CommandLine](FAULT): Scheduling is paused
2019-05-14T20:36:35.645Z,1557866195.645 [CBIT](INFO): Critical error at 20190514T203635
2019-05-14T20:36:35.645Z,1557866195.645 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-14T20:36:35.648Z,1557866195.648 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-05-14T20:36:35.648Z,1557866195.648 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-05-14T20:36:36.338Z,1557866196.338 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-05-14T20:36:36.338Z,1557866196.338 [RudderServo](FAULT): Rudder failed to initialize
2019-05-14T20:36:36.338Z,1557866196.338 [RudderServo] Communications Fault, FailCount= 1
2019-05-14T20:36:36.338Z,1557866196.338 [RudderServo](ERROR): Communications Fault
2019-05-14T20:36:36.447Z,1557866196.447 [CBIT](INFO): Critical error at 20190514T203635
2019-05-14T20:36:36.450Z,1557866196.450 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-14T20:36:36.623Z,1557866196.623 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-14T20:36:36.623Z,1557866196.623 [RudderServo](INFO): Powering down
2019-05-14T20:36:37.350Z,1557866197.350 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:36:37.471Z,1557866197.471 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-14T20:36:37.475Z,1557866197.475 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-14T20:36:37.475Z,1557866197.475 [RudderServo] No Fault, FailCount= 1
2019-05-14T20:36:38.798Z,1557866198.798 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:39.318Z,1557866199.318 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:39.938Z,1557866199.938 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:40.378Z,1557866200.378 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:40.790Z,1557866200.790 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:41.270Z,1557866201.270 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:41.834Z,1557866201.834 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:42.570Z,1557866202.570 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:43.006Z,1557866203.006 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:43.442Z,1557866203.442 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:43.930Z,1557866203.930 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:44.350Z,1557866204.350 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:44.786Z,1557866204.786 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:45.314Z,1557866205.314 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:45.738Z,1557866205.738 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:46.138Z,1557866206.138 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:46.574Z,1557866206.574 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:46.958Z,1557866206.958 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:47.378Z,1557866207.378 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:47.814Z,1557866207.814 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:47.894Z,1557866207.894 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-14T20:36:47.894Z,1557866207.894 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-14T20:36:47.894Z,1557866207.894 [CTD_Seabird](ERROR): Communications Fault
2019-05-14T20:36:47.899Z,1557866207.899 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-14T20:36:48.230Z,1557866208.230 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:48.230Z,1557866208.230 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:36:48.230Z,1557866208.230 [AHRS_M2] Communications Fault, FailCount= 1
2019-05-14T20:36:48.230Z,1557866208.230 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:36:48.298Z,1557866208.298 [CTD_Seabird](INFO): Powering down
2019-05-14T20:36:48.319Z,1557866208.319 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:36:48.333Z,1557866208.333 [AHRS_M2](INFO): Powering down
2019-05-14T20:36:49.190Z,1557866209.190 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-14T20:36:49.190Z,1557866209.190 [AHRS_M2] No Fault, FailCount= 1
2019-05-14T20:36:49.534Z,1557866209.534 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:36:49.600Z,1557866209.600 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-14T20:36:49.600Z,1557866209.600 [CTD_Seabird] No Fault, FailCount= 1
2019-05-14T20:36:49.662Z,1557866209.662 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-14T20:36:49.670Z,1557866209.670 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-14T20:36:53.086Z,1557866213.086 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:53.498Z,1557866213.498 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:53.894Z,1557866213.894 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:54.306Z,1557866214.306 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:54.718Z,1557866214.718 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:55.126Z,1557866215.126 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:55.534Z,1557866215.534 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:55.934Z,1557866215.934 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:56.374Z,1557866216.374 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:56.770Z,1557866216.770 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:57.178Z,1557866217.178 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:57.578Z,1557866217.578 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:57.982Z,1557866217.982 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:58.398Z,1557866218.398 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:58.810Z,1557866218.810 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:59.230Z,1557866219.230 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:36:59.630Z,1557866219.630 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:00.034Z,1557866220.034 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:00.450Z,1557866220.450 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:00.866Z,1557866220.866 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:01.282Z,1557866221.282 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:01.670Z,1557866221.670 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:01.675Z,1557866221.675 [NAL9602](INFO): Powering up NAL9602
2019-05-14T20:37:02.078Z,1557866222.078 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:02.482Z,1557866222.482 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:02.482Z,1557866222.482 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:37:02.482Z,1557866222.482 [AHRS_M2] Communications Fault, FailCount= 2
2019-05-14T20:37:02.482Z,1557866222.482 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:37:02.575Z,1557866222.575 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:37:02.588Z,1557866222.588 [AHRS_M2](INFO): Powering down
2019-05-14T20:37:02.714Z,1557866222.714 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-14T20:37:02.714Z,1557866222.714 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-14T20:37:02.714Z,1557866222.714 [CTD_Seabird](ERROR): Communications Fault
2019-05-14T20:37:02.760Z,1557866222.760 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-14T20:37:03.118Z,1557866223.118 [CTD_Seabird](INFO): Powering down
2019-05-14T20:37:03.452Z,1557866223.452 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-14T20:37:03.487Z,1557866223.487 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-14T20:37:03.487Z,1557866223.487 [AHRS_M2] No Fault, FailCount= 2
2019-05-14T20:37:03.488Z,1557866223.488 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-14T20:37:03.798Z,1557866223.798 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:37:04.311Z,1557866224.311 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-14T20:37:04.311Z,1557866224.311 [CTD_Seabird] No Fault, FailCount= 2
2019-05-14T20:37:04.462Z,1557866224.462 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-14T20:37:04.464Z,1557866224.464 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-14T20:37:07.450Z,1557866227.450 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:08.170Z,1557866228.170 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:08.650Z,1557866228.650 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:09.306Z,1557866229.306 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:09.750Z,1557866229.750 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:10.170Z,1557866230.170 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:10.594Z,1557866230.594 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:11.110Z,1557866231.110 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:11.538Z,1557866231.538 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:11.994Z,1557866231.994 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:12.530Z,1557866232.530 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:12.966Z,1557866232.966 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:12.971Z,1557866232.971 [NAL9602](INFO): NAL9602 initialized
2019-05-14T20:37:13.402Z,1557866233.402 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:13.930Z,1557866233.930 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:13.946Z,1557866233.946 [NAL9602](DEBUG): Fix Requested
2019-05-14T20:37:14.382Z,1557866234.382 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:14.898Z,1557866234.898 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:15.438Z,1557866235.438 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:37:15.526Z,1557866235.526 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.027757
CHAN A1 (24V): 0.178028
CHAN A2 (12V): -0.005122
CHAN A3 (5V): -0.002739
CHAN B0 (3.3V): -0.001249
CHAN B1 (3.15aV): -0.001056
CHAN B2 (3.15bV): -0.001464
CHAN B3 (GND): -0.000268
OPEN: 0.004550
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-14T20:37:16.250Z,1557866236.250 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:16.682Z,1557866236.682 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:17.102Z,1557866237.102 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:17.102Z,1557866237.102 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:37:17.102Z,1557866237.102 [AHRS_M2] Communications Fault, FailCount= 3
2019-05-14T20:37:17.102Z,1557866237.102 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:37:17.192Z,1557866237.192 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:37:17.210Z,1557866237.210 [AHRS_M2](INFO): Powering down
2019-05-14T20:37:17.522Z,1557866237.522 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-14T20:37:17.522Z,1557866237.522 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-14T20:37:17.522Z,1557866237.522 [CTD_Seabird](ERROR): Communications Fault
2019-05-14T20:37:17.688Z,1557866237.688 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-14T20:37:17.689Z,1557866237.689 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-14T20:37:17.926Z,1557866237.926 [CTD_Seabird](INFO): Powering down
2019-05-14T20:37:18.103Z,1557866238.103 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-14T20:37:18.103Z,1557866238.103 [AHRS_M2] No Fault, FailCount= 3
2019-05-14T20:37:18.410Z,1557866238.410 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:37:22.058Z,1557866242.058 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:22.558Z,1557866242.558 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:23.022Z,1557866243.022 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:23.482Z,1557866243.482 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:23.998Z,1557866243.998 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:24.666Z,1557866244.666 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:25.110Z,1557866245.110 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:25.558Z,1557866245.558 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:26.018Z,1557866246.018 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:26.482Z,1557866246.482 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:26.918Z,1557866246.918 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:27.350Z,1557866247.350 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:27.806Z,1557866247.806 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:28.230Z,1557866248.230 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:28.650Z,1557866248.650 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:29.094Z,1557866249.094 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:29.518Z,1557866249.518 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:29.942Z,1557866249.942 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:30.342Z,1557866250.342 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:30.774Z,1557866250.774 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:31.194Z,1557866251.194 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:31.610Z,1557866251.610 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:31.610Z,1557866251.610 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:37:31.610Z,1557866251.610 [AHRS_M2] Communications Fault, FailCount= 4
2019-05-14T20:37:31.610Z,1557866251.610 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:37:31.737Z,1557866251.737 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:37:31.743Z,1557866251.743 [AHRS_M2](INFO): Powering down
2019-05-14T20:37:32.631Z,1557866252.631 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-14T20:37:32.631Z,1557866252.631 [AHRS_M2] No Fault, FailCount= 4
2019-05-14T20:37:32.954Z,1557866252.954 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:37:36.510Z,1557866256.510 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:36.926Z,1557866256.926 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:37.318Z,1557866257.318 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:37.722Z,1557866257.722 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:38.126Z,1557866258.126 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:38.538Z,1557866258.538 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:38.942Z,1557866258.942 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:39.346Z,1557866259.346 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:39.770Z,1557866259.770 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:40.154Z,1557866260.154 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:40.550Z,1557866260.550 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:40.954Z,1557866260.954 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:41.358Z,1557866261.358 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:41.766Z,1557866261.766 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:42.174Z,1557866262.174 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:42.582Z,1557866262.582 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:42.994Z,1557866262.994 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:43.394Z,1557866263.394 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:43.782Z,1557866263.782 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:44.186Z,1557866264.186 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:44.590Z,1557866264.590 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:45.002Z,1557866265.002 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:45.406Z,1557866265.406 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:45.938Z,1557866265.938 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-05-14T20:37:45.938Z,1557866265.938 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:37:45.938Z,1557866265.938 [AHRS_M2] Communications Fault, FailCount= 5
2019-05-14T20:37:45.938Z,1557866265.938 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:37:46.446Z,1557866266.446 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:37:46.447Z,1557866266.447 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-05-14T20:37:46.452Z,1557866266.452 [AHRS_M2](INFO): Powering down
2019-05-14T20:37:46.680Z,1557866266.680 [CBIT](INFO): Critical error at 20190514T203746
2019-05-14T20:37:57.412Z,1557866277.412 [SBIT](IMPORTANT): SBIT PASSED
2019-05-14T20:37:57.450Z,1557866277.450 [CommandLine](IMPORTANT): got command configSet list
2019-05-14T20:37:57.450Z,1557866277.450 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-14T20:37:57.451Z,1557866277.451 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-05-14T20:37:57.803Z,1557866277.803 [MissionManager](IMPORTANT): Started mission Startup
2019-05-14T20:37:57.804Z,1557866277.804 [Startup] Running Loop=1
2019-05-14T20:37:57.804Z,1557866277.804 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-14T20:37:57.804Z,1557866277.804 [Startup:A.GoToSurface] Running Loop=1
2019-05-14T20:37:57.804Z,1557866277.804 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-14T20:37:57.805Z,1557866277.805 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-14T20:37:57.805Z,1557866277.805 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-14T20:37:57.805Z,1557866277.805 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-14T20:37:57.806Z,1557866277.806 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-14T20:37:57.806Z,1557866277.806 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-14T20:37:57.808Z,1557866277.808 [Startup:StartupSatComms] Running Loop=1
2019-05-14T20:37:57.808Z,1557866277.808 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-14T20:37:57.808Z,1557866277.808 [Startup:StartupSatComms:A] Running Loop=1
2019-05-14T20:37:58.200Z,1557866278.200 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-14T20:38:34.959Z,1557866314.959 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-14T20:38:34.959Z,1557866314.959 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-14T20:38:34.959Z,1557866314.959 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-14T20:38:34.961Z,1557866314.961 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-14T20:38:34.961Z,1557866314.961 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-05-14T20:38:34.961Z,1557866314.961 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-14T20:38:34.971Z,1557866314.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-14T20:38:34.971Z,1557866314.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-14T20:38:35.375Z,1557866315.375 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-14T20:38:35.375Z,1557866315.375 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-14T20:38:35.375Z,1557866315.375 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-14T20:38:35.375Z,1557866315.375 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-05-14T20:38:35.762Z,1557866315.762 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-14T20:38:57.999Z,1557866337.999 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-14T20:37:57.8Z
2019-05-14T20:38:57.999Z,1557866337.999 [Startup:StartupSatComms:A] Stopped
2019-05-14T20:38:57.999Z,1557866337.999 [Startup:StartupSatComms:B] Running Loop=1
2019-05-14T20:38:58.427Z,1557866338.427 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-14T20:39:00.880Z,1557866340.880 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009098
2019-05-14T20:39:30.008Z,1557866370.008 [CommandLine](IMPORTANT): got command restart application
2019-05-14T20:39:31.014Z,1557866371.014 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:39:31.014Z,1557866371.014 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:31.190Z,1557866371.190 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-14T20:39:31.190Z,1557866371.190 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:31.190Z,1557866371.190 [CommandLine](INFO): Join timeout helper Thread ID is 4489
2019-05-14T20:39:31.193Z,1557866371.193 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-14T20:39:31.193Z,1557866371.193 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:31.193Z,1557866371.193 [NavChartDb](INFO): Join timeout helper Thread ID is 4490
2019-05-14T20:39:31.418Z,1557866371.418 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:39:31.418Z,1557866371.418 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:31.438Z,1557866371.438 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-14T20:39:31.438Z,1557866371.438 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:31.438Z,1557866371.438 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4491
2019-05-14T20:39:31.790Z,1557866371.790 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:39:31.790Z,1557866371.790 [WetLabsBB2FL](INFO): Powering down
2019-05-14T20:39:31.791Z,1557866371.791 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:31.794Z,1557866371.794 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-05-14T20:39:31.794Z,1557866371.794 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:31.794Z,1557866371.794 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4492
2019-05-14T20:39:32.198Z,1557866372.198 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:39:32.198Z,1557866372.198 [CTD_Seabird](INFO): Powering down
2019-05-14T20:39:32.210Z,1557866372.210 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:32.218Z,1557866372.218 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-14T20:39:32.218Z,1557866372.218 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:32.218Z,1557866372.218 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 4493
2019-05-14T20:39:32.258Z,1557866372.258 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:39:32.258Z,1557866372.258 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:32.267Z,1557866372.267 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-14T20:39:32.267Z,1557866372.267 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:32.267Z,1557866372.267 [Radio_Surface](INFO): Join timeout helper Thread ID is 4494
2019-05-14T20:39:32.602Z,1557866372.602 [Radio_Surface](INFO): Powering down
2019-05-14T20:39:32.603Z,1557866372.603 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:39:32.603Z,1557866372.603 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:32.618Z,1557866372.618 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-14T20:39:32.618Z,1557866372.618 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:32.619Z,1557866372.619 [DataOverHttps](INFO): Join timeout helper Thread ID is 4495
2019-05-14T20:39:35.600Z,1557866375.600 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:39:35.603Z,1557866375.603 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.610Z,1557866375.610 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-14T20:39:35.611Z,1557866375.611 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.611Z,1557866375.611 [logger](INFO): Join timeout helper Thread ID is 4496
2019-05-14T20:39:35.654Z,1557866375.654 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:39:35.654Z,1557866375.654 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.659Z,1557866375.659 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-14T20:39:35.659Z,1557866375.659 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.659Z,1557866375.659 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-14T20:39:35.659Z,1557866375.659 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.659Z,1557866375.659 [controlThread](INFO): Join timeout helper Thread ID is 4497
2019-05-14T20:39:35.733Z,1557866375.733 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:39:35.733Z,1557866375.733 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-14T20:39:35.734Z,1557866375.734 [AHRS_M2](INFO): Powering down
2019-05-14T20:39:35.735Z,1557866375.735 [NAL9602](INFO): Powering down
2019-05-14T20:39:35.737Z,1557866375.737 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-14T20:39:35.738Z,1557866375.738 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-14T20:39:35.739Z,1557866375.739 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-14T20:39:35.740Z,1557866375.740 [MissionManager](INFO): Uninitializing Mission Default
2019-05-14T20:39:35.742Z,1557866375.742 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-14T20:39:35.742Z,1557866375.742 [Startup] Stopped
2019-05-14T20:39:35.742Z,1557866375.742 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-14T20:39:35.742Z,1557866375.742 [Startup:A.GoToSurface] Stopped
2019-05-14T20:39:35.742Z,1557866375.742 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-14T20:39:35.742Z,1557866375.742 [Startup:StartupSatComms] Stopped
2019-05-14T20:39:35.742Z,1557866375.742 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-14T20:39:35.742Z,1557866375.742 [Startup:StartupSatComms:B] Stopped
2019-05-14T20:39:35.744Z,1557866375.744 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-14T20:39:35.744Z,1557866375.744 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-14T20:39:35.744Z,1557866375.744 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-14T20:39:35.744Z,1557866375.744 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-14T20:39:35.745Z,1557866375.745 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-14T20:39:35.745Z,1557866375.745 [BuoyancyServo](INFO): Powering down
2019-05-14T20:39:35.758Z,1557866375.758 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-14T20:39:35.758Z,1557866375.758 [ElevatorServo](INFO): Powering down
2019-05-14T20:39:35.759Z,1557866375.759 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-14T20:39:35.759Z,1557866375.759 [MassServo](INFO): Powering down
2019-05-14T20:39:35.760Z,1557866375.760 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-14T20:39:35.760Z,1557866375.760 [RudderServo](INFO): Powering down
2019-05-14T20:39:35.760Z,1557866375.760 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-14T20:39:35.761Z,1557866375.761 [ThrusterServo](INFO): Powering down
2019-05-14T20:39:35.761Z,1557866375.761 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-14T20:39:35.762Z,1557866375.762 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-14T20:39:35.762Z,1557866375.762 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-14T20:39:35.762Z,1557866375.762 [CBIT](DEBUG): Powering off loads.
2019-05-14T20:39:35.774Z,1557866375.774 [CBIT](DEBUG): Disabling WDT.
2019-05-14T20:39:35.786Z,1557866375.786 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-14T20:39:35.787Z,1557866375.787 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.876Z,1557866375.876 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.882Z,1557866375.882 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.916Z,1557866375.916 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.919Z,1557866375.919 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.923Z,1557866375.923 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:35.953Z,1557866375.953 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:39:36.009Z,1557866376.009 [logger ThreadHandler](INFO): Thread cancelled.