2020-09-30T17:54:55.246Z,1601488495.246 [Supervisor](DEBUG): Initializing supervisor.
2020-09-30T17:54:55.249Z,1601488495.249 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-30T17:54:55.250Z,1601488495.250 [SyncHandler](INFO): Protected caller Thread ID is 6727
2020-09-30T17:54:55.250Z,1601488495.250 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-09-30T17:54:55.251Z,1601488495.251 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-09-30T17:54:55.252Z,1601488495.252 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6728
2020-09-30T17:54:55.255Z,1601488495.255 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-09-30T17:54:55.270Z,1601488495.270 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-09-30T17:54:55.271Z,1601488495.271 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-09-30T17:54:55.272Z,1601488495.272 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6729
2020-09-30T17:54:55.272Z,1601488495.272 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-09-30T17:54:55.273Z,1601488495.273 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-09-30T17:54:55.274Z,1601488495.274 [logger ThreadHandler](INFO): Protected caller Thread ID is 6730
2020-09-30T17:54:55.276Z,1601488495.276 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-09-30T17:54:55.276Z,1601488495.276 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-09-30T17:54:55.278Z,1601488495.278 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-09-30T17:54:55.371Z,1601488495.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-09-30T17:54:55.372Z,1601488495.372 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-09-30T17:54:55.945Z,1601488495.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-09-30T17:54:55.946Z,1601488495.946 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-09-30T17:54:56.138Z,1601488496.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-09-30T17:54:56.139Z,1601488496.139 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-09-30T17:54:56.236Z,1601488496.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-09-30T17:54:56.237Z,1601488496.237 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-09-30T17:54:56.336Z,1601488496.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-09-30T17:54:56.336Z,1601488496.336 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-09-30T17:54:56.416Z,1601488496.416 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-09-30T17:54:56.552Z,1601488496.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-09-30T17:54:56.552Z,1601488496.552 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-09-30T17:54:56.834Z,1601488496.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-09-30T17:54:56.834Z,1601488496.834 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-09-30T17:54:57.276Z,1601488497.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-09-30T17:54:57.276Z,1601488497.276 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-09-30T17:54:57.417Z,1601488497.417 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-09-30T17:54:57.418Z,1601488497.418 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-09-30T17:54:57.601Z,1601488497.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-09-30T17:54:57.601Z,1601488497.601 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-09-30T17:54:58.085Z,1601488498.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-09-30T17:54:58.086Z,1601488498.086 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-09-30T17:54:58.297Z,1601488498.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-09-30T17:54:58.298Z,1601488498.298 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-09-30T17:54:58.493Z,1601488498.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-09-30T17:54:58.493Z,1601488498.493 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-09-30T17:54:58.892Z,1601488498.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-09-30T17:54:58.892Z,1601488498.892 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-09-30T17:54:59.207Z,1601488499.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-09-30T17:54:59.209Z,1601488499.209 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-09-30T17:54:59.210Z,1601488499.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-09-30T17:54:59.326Z,1601488499.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-09-30T17:54:59.537Z,1601488499.537 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-09-30T17:54:59.754Z,1601488499.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-09-30T17:54:59.912Z,1601488499.912 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-09-30T17:55:00.145Z,1601488500.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-09-30T17:55:00.391Z,1601488500.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-09-30T17:55:00.700Z,1601488500.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-09-30T17:55:00.700Z,1601488500.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-09-30T17:55:00.792Z,1601488500.792 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-09-30T17:55:00.888Z,1601488500.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-09-30T17:55:01.014Z,1601488501.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-09-30T17:55:01.113Z,1601488501.113 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-09-30T17:55:01.113Z,1601488501.113 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-09-30T17:55:01.130Z,1601488501.130 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-09-30T17:55:01.207Z,1601488501.207 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-09-30T17:55:01.208Z,1601488501.208 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-09-30T17:55:01.254Z,1601488501.254 [VerticalControl](DEBUG): Construct VerticalControl.
2020-09-30T17:55:01.365Z,1601488501.365 [VerticalControl] Loaded
2020-09-30T17:55:01.365Z,1601488501.365 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-09-30T17:55:01.366Z,1601488501.366 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-09-30T17:55:01.446Z,1601488501.446 [HorizontalControl] Loaded
2020-09-30T17:55:01.446Z,1601488501.446 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-09-30T17:55:01.447Z,1601488501.447 [SpeedControl](DEBUG): Construct SpeedControl.
2020-09-30T17:55:01.453Z,1601488501.453 [SpeedControl] Loaded
2020-09-30T17:55:01.453Z,1601488501.453 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-09-30T17:55:01.454Z,1601488501.454 [LoopControl](DEBUG): Construct LoopControl.
2020-09-30T17:55:01.454Z,1601488501.454 [LoopControl] Loaded
2020-09-30T17:55:01.454Z,1601488501.454 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-09-30T17:55:01.455Z,1601488501.455 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-09-30T17:55:01.455Z,1601488501.455 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-09-30T17:55:01.496Z,1601488501.496 [DepthRateCalculator] Loaded
2020-09-30T17:55:01.496Z,1601488501.496 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-09-30T17:55:01.501Z,1601488501.501 [PitchRateCalculator] Loaded
2020-09-30T17:55:01.501Z,1601488501.501 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-09-30T17:55:01.517Z,1601488501.517 [SpeedCalculator] Loaded
2020-09-30T17:55:01.517Z,1601488501.517 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-09-30T17:55:01.538Z,1601488501.538 [TempGradientCalculator] Loaded
2020-09-30T17:55:01.538Z,1601488501.538 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-09-30T17:55:01.543Z,1601488501.543 [YawRateCalculator] Loaded
2020-09-30T17:55:01.543Z,1601488501.543 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-09-30T17:55:01.573Z,1601488501.573 [ElevatorOffsetCalculator] Loaded
2020-09-30T17:55:01.573Z,1601488501.573 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-09-30T17:55:01.573Z,1601488501.573 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-09-30T17:55:01.574Z,1601488501.574 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-09-30T17:55:01.600Z,1601488501.600 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-09-30T17:55:01.601Z,1601488501.601 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-09-30T17:55:01.625Z,1601488501.625 [Module Loader](ERROR): Cannot load library: Modules/Estimation.so: undefined symbol: _ZN9Component10logVoltageEff
2020-09-30T17:55:01.625Z,1601488501.625 [Module Loader](ERROR): Could not load the module at Modules/Estimation.so
2020-09-30T17:55:01.625Z,1601488501.625 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-09-30T17:55:01.911Z,1601488501.911 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-09-30T17:55:01.911Z,1601488501.911 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-09-30T17:55:02.008Z,1601488502.008 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-09-30T17:55:02.009Z,1601488502.009 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-09-30T17:55:02.337Z,1601488502.337 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-09-30T17:55:02.342Z,1601488502.342 [AHRS_M2](INFO): created writer for : platform_orientation
2020-09-30T17:55:02.343Z,1601488502.343 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-09-30T17:55:02.349Z,1601488502.349 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-09-30T17:55:02.349Z,1601488502.349 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-09-30T17:55:02.354Z,1601488502.354 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-09-30T17:55:02.354Z,1601488502.354 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-09-30T17:55:02.360Z,1601488502.360 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-09-30T17:55:02.431Z,1601488502.431 [AHRS_M2] Loaded
2020-09-30T17:55:02.431Z,1601488502.431 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-09-30T17:55:02.508Z,1601488502.508 [DataOverHttps] Loaded
2020-09-30T17:55:02.508Z,1601488502.508 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-09-30T17:55:02.509Z,1601488502.509 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B04E0
2020-09-30T17:55:02.510Z,1601488502.510 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6812
2020-09-30T17:55:02.572Z,1601488502.572 [Depth_Keller] Loaded
2020-09-30T17:55:02.572Z,1601488502.572 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-09-30T17:55:02.685Z,1601488502.685 [NAL9602] Loaded
2020-09-30T17:55:02.685Z,1601488502.685 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-09-30T17:55:02.733Z,1601488502.733 [Onboard] Loaded
2020-09-30T17:55:02.733Z,1601488502.733 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-09-30T17:55:02.734Z,1601488502.734 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E04E0
2020-09-30T17:55:02.734Z,1601488502.734 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6813
2020-09-30T17:55:02.754Z,1601488502.754 [Radio_Surface] Loaded
2020-09-30T17:55:02.754Z,1601488502.754 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-09-30T17:55:02.755Z,1601488502.755 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409104E0
2020-09-30T17:55:02.755Z,1601488502.755 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6814
2020-09-30T17:55:02.903Z,1601488502.903 [DAT] Loaded
2020-09-30T17:55:02.904Z,1601488502.904 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-09-30T17:55:04.382Z,1601488504.382 [BPC1] Loaded
2020-09-30T17:55:04.382Z,1601488504.382 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-09-30T17:55:04.382Z,1601488504.382 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-09-30T17:55:04.383Z,1601488504.383 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-09-30T17:55:04.731Z,1601488504.731 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-09-30T17:55:04.732Z,1601488504.732 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-09-30T17:55:04.753Z,1601488504.753 [NavChart] Loaded
2020-09-30T17:55:04.753Z,1601488504.753 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-09-30T17:55:04.757Z,1601488504.757 [UniversalFixResidualReporter] Loaded
2020-09-30T17:55:04.757Z,1601488504.757 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-09-30T17:55:04.758Z,1601488504.758 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-09-30T17:55:04.758Z,1601488504.758 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-09-30T17:55:05.031Z,1601488505.031 [SBIT](DEBUG): Construct Startup Built In Test.
2020-09-30T17:55:05.042Z,1601488505.042 [SBIT] Loaded
2020-09-30T17:55:05.042Z,1601488505.042 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-09-30T17:55:05.042Z,1601488505.042 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-09-30T17:55:05.055Z,1601488505.055 [IBIT] Loaded
2020-09-30T17:55:05.055Z,1601488505.055 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-09-30T17:55:05.058Z,1601488505.058 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-09-30T17:55:05.199Z,1601488505.199 [CBIT] Loaded
2020-09-30T17:55:05.199Z,1601488505.199 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-09-30T17:55:05.199Z,1601488505.199 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-09-30T17:55:05.200Z,1601488505.200 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-09-30T17:55:05.320Z,1601488505.320 [BuoyancyServo] Loaded
2020-09-30T17:55:05.321Z,1601488505.321 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-09-30T17:55:05.348Z,1601488505.348 [ElevatorServo] Loaded
2020-09-30T17:55:05.349Z,1601488505.349 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-09-30T17:55:05.376Z,1601488505.376 [MassServo] Loaded
2020-09-30T17:55:05.376Z,1601488505.376 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-09-30T17:55:05.403Z,1601488505.403 [RudderServo] Loaded
2020-09-30T17:55:05.403Z,1601488505.403 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-09-30T17:55:05.430Z,1601488505.430 [ThrusterServo] Loaded
2020-09-30T17:55:05.430Z,1601488505.430 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-09-30T17:55:05.431Z,1601488505.431 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-09-30T17:55:05.431Z,1601488505.431 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-09-30T17:55:05.449Z,1601488505.449 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-09-30T17:55:05.450Z,1601488505.450 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-09-30T17:55:05.689Z,1601488505.689 [BackSeatDriver] Loaded
2020-09-30T17:55:05.689Z,1601488505.689 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-09-30T17:55:05.690Z,1601488505.690 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40AA84E0
2020-09-30T17:55:05.690Z,1601488505.690 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 6815
2020-09-30T17:55:05.718Z,1601488505.718 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-09-30T17:55:05.724Z,1601488505.724 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-09-30T17:55:05.724Z,1601488505.724 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-09-30T17:55:05.729Z,1601488505.729 [CTD_Seabird](INFO): created writer for : depth
2020-09-30T17:55:05.729Z,1601488505.729 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-09-30T17:55:05.734Z,1601488505.734 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-09-30T17:55:05.734Z,1601488505.734 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-09-30T17:55:05.740Z,1601488505.740 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-09-30T17:55:05.740Z,1601488505.740 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-09-30T17:55:05.745Z,1601488505.745 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-09-30T17:55:05.746Z,1601488505.746 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-09-30T17:55:05.752Z,1601488505.752 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-09-30T17:55:05.753Z,1601488505.753 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-09-30T17:55:05.758Z,1601488505.758 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-09-30T17:55:05.785Z,1601488505.785 [CTD_Seabird] Loaded
2020-09-30T17:55:05.785Z,1601488505.785 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-09-30T17:55:05.786Z,1601488505.786 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AD84E0
2020-09-30T17:55:05.787Z,1601488505.787 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6816
2020-09-30T17:55:05.836Z,1601488505.836 [ESPComponent] Loaded
2020-09-30T17:55:05.836Z,1601488505.836 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-09-30T17:55:05.858Z,1601488505.858 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-09-30T17:55:05.859Z,1601488505.859 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-09-30T17:55:05.863Z,1601488505.863 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-09-30T17:55:05.863Z,1601488505.863 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-09-30T17:55:05.867Z,1601488505.867 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-09-30T17:55:05.867Z,1601488505.867 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-09-30T17:55:05.871Z,1601488505.871 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-09-30T17:55:05.871Z,1601488505.871 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-09-30T17:55:05.875Z,1601488505.875 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-09-30T17:55:05.876Z,1601488505.876 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-09-30T17:55:05.880Z,1601488505.880 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-09-30T17:55:05.880Z,1601488505.880 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-09-30T17:55:05.884Z,1601488505.884 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-09-30T17:55:05.884Z,1601488505.884 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-09-30T17:55:05.888Z,1601488505.888 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:55:05.893Z,1601488505.893 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:55:05.894Z,1601488505.894 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:55:05.894Z,1601488505.894 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:55:05.898Z,1601488505.898 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:55:05.898Z,1601488505.898 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:55:05.902Z,1601488505.902 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:55:05.902Z,1601488505.902 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:55:05.907Z,1601488505.907 [WetLabsBB2FL] Loaded
2020-09-30T17:55:05.907Z,1601488505.907 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-09-30T17:55:05.908Z,1601488505.908 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B084E0
2020-09-30T17:55:05.908Z,1601488505.908 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6817
2020-09-30T17:55:05.909Z,1601488505.909 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-09-30T17:55:05.913Z,1601488505.913 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-09-30T17:55:05.914Z,1601488505.914 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-09-30T17:55:05.920Z,1601488505.920 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-09-30T17:55:05.921Z,1601488505.921 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B384E0
2020-09-30T17:55:05.922Z,1601488505.922 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6818
2020-09-30T17:55:05.926Z,1601488505.926 [Supervisor](INFO): Main Thread ID is 5459
2020-09-30T17:55:05.926Z,1601488505.926 [Supervisor](DEBUG): Running supervisor.
2020-09-30T17:55:05.927Z,1601488505.927 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6819
2020-09-30T17:55:05.929Z,1601488505.929 [controlThread ThreadHandler](INFO): Handler Thread ID is 6820
2020-09-30T17:55:05.930Z,1601488505.930 [controlThread](DEBUG): Initializing ControlThread
2020-09-30T17:55:05.931Z,1601488505.931 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-09-30T17:55:05.932Z,1601488505.932 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-09-30T17:55:05.933Z,1601488505.933 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-09-30T17:55:05.934Z,1601488505.934 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-09-30T17:55:05.934Z,1601488505.934 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-09-30T17:55:05.934Z,1601488505.934 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-09-30T17:55:05.935Z,1601488505.935 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-09-30T17:55:05.935Z,1601488505.935 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-09-30T17:55:05.935Z,1601488505.935 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-09-30T17:55:05.936Z,1601488505.936 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-09-30T17:55:05.940Z,1601488505.940 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-09-30T17:55:05.941Z,1601488505.941 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-09-30T17:55:05.941Z,1601488505.941 [SBIT](INFO): Initialize SBIT Component.
2020-09-30T17:55:05.941Z,1601488505.941 [SBIT](IMPORTANT): git: 2020-09-25
2020-09-30T17:55:05.942Z,1601488505.942 [SBIT](INFO): git hash: 37975fc996eaab47e1849088435330a6c93ea7b4
2020-09-30T17:55:05.942Z,1601488505.942 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-09-30T17:55:05.943Z,1601488505.943 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-09-30T17:55:05.944Z,1601488505.944 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2020-09-30T17:55:05.945Z,1601488505.945 [IBIT](INFO): Initialize IBIT Component.
2020-09-30T17:55:05.945Z,1601488505.945 [CBIT](DEBUG): Initialize CBIT Component.
2020-09-30T17:55:05.946Z,1601488505.946 [logger ThreadHandler](INFO): Handler Thread ID is 6821
2020-09-30T17:55:05.959Z,1601488505.959 [CBIT](DEBUG): Initialized mux pins.
2020-09-30T17:55:05.959Z,1601488505.959 [CBIT](DEBUG): Initializing the watchdog timer.
2020-09-30T17:55:05.963Z,1601488505.963 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6822
2020-09-30T17:55:05.964Z,1601488505.964 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-09-30T17:55:05.972Z,1601488505.972 [Onboard ThreadHandler](INFO): Handler Thread ID is 6823
2020-09-30T17:55:05.984Z,1601488505.984 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2020-09-30T17:55:05.984Z,1601488505.984 [CBIT](DEBUG): Initializing heartbeat.
2020-09-30T17:55:05.993Z,1601488505.993 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6824
2020-09-30T17:55:06.015Z,1601488506.015 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 6825
2020-09-30T17:55:06.027Z,1601488506.027 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6826
2020-09-30T17:55:06.028Z,1601488506.028 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-09-30T17:55:06.032Z,1601488506.032 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6828
2020-09-30T17:55:06.033Z,1601488506.033 [WetLabsBB2FL](INFO): Powering down
2020-09-30T17:55:06.055Z,1601488506.055 [CBIT](DEBUG): Deactivating GF circuits.
2020-09-30T17:55:06.055Z,1601488506.055 [CBIT](DEBUG): Deactivating emergency mode.
2020-09-30T17:55:06.067Z,1601488506.067 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6829
2020-09-30T17:55:06.070Z,1601488506.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-09-30T17:55:06.070Z,1601488506.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-09-30T17:55:06.071Z,1601488506.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-09-30T17:55:06.071Z,1601488506.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-09-30T17:55:06.071Z,1601488506.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-09-30T17:55:06.071Z,1601488506.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-09-30T17:55:06.071Z,1601488506.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-09-30T17:55:06.071Z,1601488506.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-09-30T17:55:06.072Z,1601488506.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-09-30T17:55:06.072Z,1601488506.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-09-30T17:55:06.072Z,1601488506.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-09-30T17:55:06.072Z,1601488506.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-09-30T17:55:06.072Z,1601488506.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-09-30T17:55:06.072Z,1601488506.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-09-30T17:55:06.073Z,1601488506.073 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-09-30T17:55:06.073Z,1601488506.073 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-09-30T17:55:06.091Z,1601488506.091 [CBIT](DEBUG): Backplane powered.
2020-09-30T17:55:06.093Z,1601488506.093 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-09-30T17:55:06.103Z,1601488506.103 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-30T17:55:06.113Z,1601488506.113 [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>


2020-09-30T17:55:06.114Z,1601488506.114 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-09-30T17:55:06.182Z,1601488506.182 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-09-30T17:55:06.199Z,1601488506.199 [Default:A.Wait](DEBUG): Construct Wait.
2020-09-30T17:55:06.201Z,1601488506.201 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-30T17:55:06.227Z,1601488506.227 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-09-30T17:55:06.245Z,1601488506.245 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-09-30T17:55:06.250Z,1601488506.250 [Default:E.Execute](DEBUG): Construct Execute.
2020-09-30T17:55:06.275Z,1601488506.275 [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>


2020-09-30T17:55:06.280Z,1601488506.280 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-09-30T17:55:06.303Z,1601488506.303 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-30T17:55:06.333Z,1601488506.333 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-09-30T17:55:06.345Z,1601488506.345 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-09-30T17:55:06.345Z,1601488506.345 [DAT](INFO): Powering up
2020-09-30T17:55:06.345Z,1601488506.345 [DAT](DEBUG): Initializing DAT.
2020-09-30T17:55:06.395Z,1601488506.395 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-09-30T17:55:06.399Z,1601488506.399 [Radio_Surface](INFO): Powering up
2020-09-30T17:55:06.423Z,1601488506.423 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:55:06.427Z,1601488506.427 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-09-30T17:55:06.451Z,1601488506.451 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-09-30T17:55:06.453Z,1601488506.453 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:55:06.467Z,1601488506.467 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-30T17:55:06.468Z,1601488506.468 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:55:06.479Z,1601488506.479 [MassServo](DEBUG): Initializing MassServo.
2020-09-30T17:55:06.480Z,1601488506.480 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:55:06.491Z,1601488506.491 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-30T17:55:06.492Z,1601488506.492 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:55:06.503Z,1601488506.503 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-09-30T17:55:06.511Z,1601488506.511 [CBIT](CRITICAL): SIGSEGV: address not mapped to object
2020-09-30T17:55:06.513Z,1601488506.513 [CBIT](CRITICAL): Bad address: 0x0
2020-09-30T17:55:06.513Z,1601488506.513 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x17e238]
Modules/BIT.so(_ZN4CBIT3runEv+0xc) [0x4094b7c8]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x17dd84]

2020-09-30T17:55:06.513Z,1601488506.513 [CBIT] Software Fault, FailCount= 1
2020-09-30T17:55:06.513Z,1601488506.513 [CBIT](ERROR): Software Fault
2020-09-30T17:55:06.551Z,1601488506.551 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-30T17:55:06.551Z,1601488506.551 [SyncHandler](INFO): Protected caller Thread ID is 6838
2020-09-30T17:55:06.752Z,1601488506.752 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-09-30T17:55:06.752Z,1601488506.752 [CBIT](DEBUG): Powering off loads.
2020-09-30T17:55:06.763Z,1601488506.763 [CBIT](DEBUG): Disabling WDT.
2020-09-30T17:55:06.775Z,1601488506.775 [CBIT](DEBUG): Opening all GF detection circuits.
2020-09-30T17:55:07.419Z,1601488507.419 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-09-30T17:55:07.419Z,1601488507.419 [RudderServo](FAULT): Rudder failed to initialize
2020-09-30T17:55:07.419Z,1601488507.419 [RudderServo] Communications Fault, FailCount= 1
2020-09-30T17:55:07.419Z,1601488507.419 [RudderServo](ERROR): Communications Fault
2020-09-30T17:55:07.743Z,1601488507.743 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2020-09-30T17:55:08.067Z,1601488508.067 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2020-09-30T17:55:08.067Z,1601488508.067 [ThrusterServo](FAULT): Thruster failed to initialize
2020-09-30T17:55:08.067Z,1601488508.067 [ThrusterServo] Communications Fault, FailCount= 1
2020-09-30T17:55:08.067Z,1601488508.067 [ThrusterServo](ERROR): Communications Fault
2020-09-30T17:55:08.423Z,1601488508.423 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2020-09-30T17:55:08.423Z,1601488508.423 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2020-09-30T17:55:08.423Z,1601488508.423 [BuoyancyServo] Communications Fault, FailCount= 1
2020-09-30T17:55:08.423Z,1601488508.423 [BuoyancyServo](ERROR): Communications Fault
2020-09-30T17:55:08.731Z,1601488508.731 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2020-09-30T17:55:08.731Z,1601488508.731 [ElevatorServo](FAULT): Elevator failed to initialize
2020-09-30T17:55:08.731Z,1601488508.731 [ElevatorServo] Communications Fault, FailCount= 1
2020-09-30T17:55:08.731Z,1601488508.731 [ElevatorServo](ERROR): Communications Fault
2020-09-30T17:55:08.732Z,1601488508.732 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-30T17:55:08.732Z,1601488508.732 [RudderServo](INFO): Powering down
2020-09-30T17:55:08.733Z,1601488508.733 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-09-30T17:55:08.734Z,1601488508.734 [ThrusterServo](INFO): Powering down
2020-09-30T17:55:08.812Z,1601488508.812 [BuoyancyServo](INFO): Stop
2020-09-30T17:55:08.814Z,1601488508.814 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-09-30T17:55:08.814Z,1601488508.814 [ElevatorServo](INFO): Powering down
2020-09-30T17:55:09.158Z,1601488509.158 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:55:09.276Z,1601488509.276 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-30T17:55:09.621Z,1601488509.621 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:55:09.740Z,1601488509.740 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-30T17:55:10.043Z,1601488510.043 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2020-09-30T17:55:10.043Z,1601488510.043 [MassServo] Communications Fault, FailCount= 1
2020-09-30T17:55:10.043Z,1601488510.043 [MassServo](ERROR): Communications Fault
2020-09-30T17:55:10.106Z,1601488510.106 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-09-30T17:55:10.106Z,1601488510.106 [MassServo](INFO): Powering down
2020-09-30T17:55:10.505Z,1601488510.505 [MassServo](FAULT): LCB fault:  Current Limiter Activated.
2020-09-30T17:55:10.505Z,1601488510.505 [MassServo] Hardware Fault, FailCount= 1
2020-09-30T17:55:10.505Z,1601488510.505 [MassServo](ERROR): Hardware Fault
2020-09-30T17:55:10.944Z,1601488510.944 [BuoyancyServo](INFO): is needed
2020-09-30T17:55:10.945Z,1601488510.945 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:55:11.064Z,1601488511.064 [MassServo](DEBUG): Initializing MassServo.
2020-09-30T17:55:19.083Z,1601488519.083 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-09-30T17:55:19.083Z,1601488519.083 [CTD_Seabird] Communications Fault, FailCount= 1
2020-09-30T17:55:19.083Z,1601488519.083 [CTD_Seabird](ERROR): Communications Fault
2020-09-30T17:55:19.487Z,1601488519.487 [CTD_Seabird](INFO): Powering down
2020-09-30T17:55:24.603Z,1601488524.603 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-09-30T17:55:24.603Z,1601488524.603 [AHRS_M2] Data Fault, FailCount= 1
2020-09-30T17:55:24.603Z,1601488524.603 [AHRS_M2](ERROR): Data Fault
2020-09-30T17:55:24.995Z,1601488524.995 [AHRS_M2](INFO): Powering down
2020-09-30T17:55:25.400Z,1601488525.400 [AHRS_M2](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2020-09-30T17:55:25.400Z,1601488525.400 [AHRS_M2] Hardware Fault, FailCount= 1
2020-09-30T17:55:25.400Z,1601488525.400 [AHRS_M2](ERROR): Hardware Fault
2020-09-30T17:55:32.688Z,1601488532.688 [NAL9602](INFO): Powering up NAL9602
2020-09-30T17:55:53.280Z,1601488553.280 [NAL9602](ERROR): NAL9602 initialization error.
2020-09-30T17:55:53.281Z,1601488553.281 [NAL9602] Communications Fault, FailCount= 1
2020-09-30T17:55:53.281Z,1601488553.281 [NAL9602](ERROR): Communications Fault
2020-09-30T17:55:53.684Z,1601488553.684 [NAL9602](INFO): Powering down
2020-09-30T17:56:06.612Z,1601488566.612 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2020-09-30T17:56:06.612Z,1601488566.612 [DAT] Communications Fault, FailCount= 1
2020-09-30T17:56:06.613Z,1601488566.613 [DAT](ERROR): Communications Fault
2020-09-30T17:56:07.016Z,1601488567.016 [DAT](INFO): Powering down
2020-09-30T17:56:07.420Z,1601488567.420 [DAT](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2020-09-30T17:56:07.420Z,1601488567.420 [DAT] Hardware Fault, FailCount= 1
2020-09-30T17:56:07.420Z,1601488567.420 [DAT](ERROR): Hardware Fault
2020-09-30T17:56:38.873Z,1601488598.873 [CommandLine](IMPORTANT): got command restart application
2020-09-30T17:56:39.879Z,1601488599.879 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-09-30T17:56:39.879Z,1601488599.879 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:39.880Z,1601488599.880 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.035Z,1601488600.035 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-09-30T17:56:40.035Z,1601488600.035 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.036Z,1601488600.036 [CommandLine](INFO): Join timeout helper Thread ID is 6844
2020-09-30T17:56:40.036Z,1601488600.036 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-09-30T17:56:40.036Z,1601488600.036 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.037Z,1601488600.037 [NavChartDb](INFO): Join timeout helper Thread ID is 6845
2020-09-30T17:56:40.207Z,1601488600.207 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:40.207Z,1601488600.207 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.215Z,1601488600.215 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-09-30T17:56:40.215Z,1601488600.215 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.215Z,1601488600.215 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6846
2020-09-30T17:56:40.291Z,1601488600.291 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:40.292Z,1601488600.292 [WetLabsBB2FL](INFO): Powering down
2020-09-30T17:56:40.292Z,1601488600.292 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.299Z,1601488600.299 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-09-30T17:56:40.299Z,1601488600.299 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.299Z,1601488600.299 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6847
2020-09-30T17:56:40.419Z,1601488600.419 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:40.419Z,1601488600.419 [CTD_Seabird](INFO): Powering down
2020-09-30T17:56:40.431Z,1601488600.431 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.449Z,1601488600.449 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-09-30T17:56:40.449Z,1601488600.449 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.450Z,1601488600.450 [BackSeatDriver](INFO): Join timeout helper Thread ID is 6848
2020-09-30T17:56:40.619Z,1601488600.619 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:40.619Z,1601488600.619 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.636Z,1601488600.636 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-09-30T17:56:40.636Z,1601488600.636 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.636Z,1601488600.636 [Radio_Surface](INFO): Join timeout helper Thread ID is 6849
2020-09-30T17:56:40.935Z,1601488600.935 [Radio_Surface](INFO): Powering down
2020-09-30T17:56:40.936Z,1601488600.936 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:40.936Z,1601488600.936 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.944Z,1601488600.944 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-09-30T17:56:40.944Z,1601488600.944 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:40.944Z,1601488600.944 [Onboard](INFO): Join timeout helper Thread ID is 6850
2020-09-30T17:56:41.047Z,1601488601.047 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:41.047Z,1601488601.047 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:41.052Z,1601488601.052 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-09-30T17:56:41.052Z,1601488601.052 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:41.052Z,1601488601.052 [DataOverHttps](INFO): Join timeout helper Thread ID is 6851
2020-09-30T17:56:41.363Z,1601488601.363 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:41.363Z,1601488601.363 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:41.372Z,1601488601.372 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-09-30T17:56:41.372Z,1601488601.372 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:41.372Z,1601488601.372 [logger](INFO): Join timeout helper Thread ID is 6852
2020-09-30T17:56:41.387Z,1601488601.387 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:41.387Z,1601488601.387 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:41.392Z,1601488601.392 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-09-30T17:56:41.392Z,1601488601.392 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:41.392Z,1601488601.392 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-09-30T17:56:41.392Z,1601488601.392 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:41.392Z,1601488601.392 [controlThread](INFO): Join timeout helper Thread ID is 6853
2020-09-30T17:56:41.751Z,1601488601.751 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:56:41.751Z,1601488601.751 [controlThread](DEBUG): Uninitializing ControlThread
2020-09-30T17:56:41.752Z,1601488601.752 [AHRS_M2](INFO): Powering down
2020-09-30T17:56:41.753Z,1601488601.753 [NAL9602](INFO): Powering down
2020-09-30T17:56:41.754Z,1601488601.754 [DAT](INFO): Powering down
2020-09-30T17:56:41.756Z,1601488601.756 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-09-30T17:56:41.757Z,1601488601.757 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-09-30T17:56:41.758Z,1601488601.758 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-09-30T17:56:41.758Z,1601488601.758 [MissionManager](INFO): Uninitializing Mission Default
2020-09-30T17:56:41.760Z,1601488601.760 [MissionManager](INFO): Uninitializing Mission Startup
2020-09-30T17:56:41.761Z,1601488601.761 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-09-30T17:56:41.762Z,1601488601.762 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-09-30T17:56:41.762Z,1601488601.762 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-09-30T17:56:41.762Z,1601488601.762 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-09-30T17:56:41.762Z,1601488601.762 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-09-30T17:56:41.763Z,1601488601.763 [BuoyancyServo](INFO): Powering down
2020-09-30T17:56:41.895Z,1601488601.895 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-09-30T17:56:41.895Z,1601488601.895 [ElevatorServo](INFO): Powering down
2020-09-30T17:56:41.896Z,1601488601.896 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-09-30T17:56:41.896Z,1601488601.896 [MassServo](INFO): Powering down
2020-09-30T17:56:41.897Z,1601488601.897 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-30T17:56:41.897Z,1601488601.897 [RudderServo](INFO): Powering down
2020-09-30T17:56:41.898Z,1601488601.898 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-09-30T17:56:41.898Z,1601488601.898 [ThrusterServo](INFO): Powering down
2020-09-30T17:56:41.899Z,1601488601.899 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-09-30T17:56:41.899Z,1601488601.899 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-09-30T17:56:41.899Z,1601488601.899 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-09-30T17:56:41.899Z,1601488601.899 [CBIT](DEBUG): Powering off loads.
2020-09-30T17:56:41.911Z,1601488601.911 [CBIT](DEBUG): Disabling WDT.
2020-09-30T17:56:41.923Z,1601488601.923 [CBIT](DEBUG): Opening all GF detection circuits.
2020-09-30T17:56:41.924Z,1601488601.924 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:42.010Z,1601488602.010 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:42.012Z,1601488602.012 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:42.018Z,1601488602.018 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:42.431Z,1601488602.431 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:42.436Z,1601488602.436 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:42.439Z,1601488602.439 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:42.449Z,1601488602.449 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:56:42.511Z,1601488602.511 [logger ThreadHandler](INFO): Thread cancelled.