2021-04-27T16:59:33.695Z,1619542773.695 [Supervisor](DEBUG): Initializing supervisor. 2021-04-27T16:59:33.697Z,1619542773.697 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-04-27T16:59:33.698Z,1619542773.698 [SyncHandler](INFO): Protected caller Thread ID is 831 2021-04-27T16:59:33.698Z,1619542773.698 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-04-27T16:59:33.699Z,1619542773.699 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-04-27T16:59:33.700Z,1619542773.700 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 832 2021-04-27T16:59:33.703Z,1619542773.703 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-04-27T16:59:33.720Z,1619542773.720 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-04-27T16:59:33.721Z,1619542773.721 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-04-27T16:59:33.721Z,1619542773.721 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 833 2021-04-27T16:59:33.722Z,1619542773.722 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-04-27T16:59:33.723Z,1619542773.723 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-04-27T16:59:33.723Z,1619542773.723 [logger ThreadHandler](INFO): Protected caller Thread ID is 834 2021-04-27T16:59:33.725Z,1619542773.725 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-04-27T16:59:33.726Z,1619542773.726 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-04-27T16:59:33.730Z,1619542773.730 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-04-27T16:59:34.187Z,1619542774.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-04-27T16:59:34.189Z,1619542774.189 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-04-27T16:59:34.332Z,1619542774.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-04-27T16:59:34.334Z,1619542774.334 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-04-27T16:59:34.416Z,1619542774.416 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-04-27T16:59:34.625Z,1619542774.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-04-27T16:59:34.627Z,1619542774.627 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-04-27T16:59:34.725Z,1619542774.725 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2021-04-27T16:59:34.727Z,1619542774.727 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-04-27T16:59:34.824Z,1619542774.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-04-27T16:59:34.824Z,1619542774.824 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-04-27T16:59:35.219Z,1619542775.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-04-27T16:59:35.221Z,1619542775.221 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-04-27T16:59:35.328Z,1619542775.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-04-27T16:59:35.329Z,1619542775.329 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-04-27T16:59:35.839Z,1619542775.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-04-27T16:59:35.841Z,1619542775.841 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-04-27T16:59:36.037Z,1619542776.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-04-27T16:59:36.037Z,1619542776.037 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-04-27T16:59:36.344Z,1619542776.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-04-27T16:59:36.344Z,1619542776.344 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-04-27T16:59:36.562Z,1619542776.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-04-27T16:59:36.563Z,1619542776.563 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-04-27T16:59:36.706Z,1619542776.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-04-27T16:59:36.707Z,1619542776.707 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-04-27T16:59:37.384Z,1619542777.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-04-27T16:59:37.888Z,1619542777.888 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-04-27T16:59:38.310Z,1619542778.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-04-27T16:59:38.312Z,1619542778.312 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-04-27T16:59:38.519Z,1619542778.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-04-27T16:59:38.521Z,1619542778.521 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2021-04-27T16:59:38.523Z,1619542778.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2021-04-27T16:59:38.718Z,1619542778.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2021-04-27T16:59:38.802Z,1619542778.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2021-04-27T16:59:38.957Z,1619542778.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2021-04-27T16:59:39.043Z,1619542779.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2021-04-27T16:59:39.297Z,1619542779.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-04-27T16:59:39.298Z,1619542779.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2021-04-27T16:59:39.405Z,1619542779.405 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2021-04-27T16:59:39.515Z,1619542779.515 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2021-04-27T16:59:39.609Z,1619542779.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2021-04-27T16:59:39.757Z,1619542779.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2021-04-27T16:59:39.857Z,1619542779.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2021-04-27T16:59:39.958Z,1619542779.958 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-04-27T16:59:39.961Z,1619542779.961 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-04-27T16:59:39.991Z,1619542779.991 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-04-27T16:59:39.992Z,1619542779.992 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-04-27T16:59:40.101Z,1619542780.101 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-04-27T16:59:40.102Z,1619542780.102 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-04-27T16:59:40.165Z,1619542780.165 [VerticalControl](DEBUG): Construct VerticalControl. 2021-04-27T16:59:40.224Z,1619542780.224 [VerticalControl] Loaded 2021-04-27T16:59:40.224Z,1619542780.224 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-04-27T16:59:40.225Z,1619542780.225 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-04-27T16:59:40.262Z,1619542780.262 [HorizontalControl] Loaded 2021-04-27T16:59:40.262Z,1619542780.262 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-04-27T16:59:40.263Z,1619542780.263 [SpeedControl](DEBUG): Construct SpeedControl. 2021-04-27T16:59:40.266Z,1619542780.266 [SpeedControl] Loaded 2021-04-27T16:59:40.266Z,1619542780.266 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-04-27T16:59:40.267Z,1619542780.267 [LoopControl](DEBUG): Construct LoopControl. 2021-04-27T16:59:40.267Z,1619542780.267 [LoopControl] Loaded 2021-04-27T16:59:40.268Z,1619542780.268 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-04-27T16:59:40.268Z,1619542780.268 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-04-27T16:59:40.269Z,1619542780.269 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-04-27T16:59:40.423Z,1619542780.423 [BuoyancyServo] Loaded 2021-04-27T16:59:40.423Z,1619542780.423 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-04-27T16:59:40.439Z,1619542780.439 [ElevatorServo] Loaded 2021-04-27T16:59:40.439Z,1619542780.439 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-04-27T16:59:40.455Z,1619542780.455 [MassServo] Loaded 2021-04-27T16:59:40.455Z,1619542780.455 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-04-27T16:59:40.470Z,1619542780.470 [RudderServo] Loaded 2021-04-27T16:59:40.470Z,1619542780.470 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-04-27T16:59:40.485Z,1619542780.485 [ThrusterServo] Loaded 2021-04-27T16:59:40.486Z,1619542780.486 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-04-27T16:59:40.486Z,1619542780.486 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-04-27T16:59:40.487Z,1619542780.487 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-04-27T16:59:40.537Z,1619542780.537 [DepthRateCalculator] Loaded 2021-04-27T16:59:40.538Z,1619542780.538 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-04-27T16:59:40.540Z,1619542780.540 [PitchRateCalculator] Loaded 2021-04-27T16:59:40.541Z,1619542780.541 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-04-27T16:59:40.548Z,1619542780.548 [SpeedCalculator] Loaded 2021-04-27T16:59:40.549Z,1619542780.549 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-04-27T16:59:40.560Z,1619542780.560 [TempGradientCalculator] Loaded 2021-04-27T16:59:40.560Z,1619542780.560 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-04-27T16:59:40.563Z,1619542780.563 [YawRateCalculator] Loaded 2021-04-27T16:59:40.563Z,1619542780.563 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-04-27T16:59:40.578Z,1619542780.578 [ElevatorOffsetCalculator] Loaded 2021-04-27T16:59:40.578Z,1619542780.578 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-04-27T16:59:40.578Z,1619542780.578 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-04-27T16:59:40.579Z,1619542780.579 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-04-27T16:59:40.675Z,1619542780.675 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-04-27T16:59:40.676Z,1619542780.676 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-04-27T16:59:40.686Z,1619542780.686 [NavChart] Loaded 2021-04-27T16:59:40.687Z,1619542780.687 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-04-27T16:59:40.691Z,1619542780.691 [UniversalFixResidualReporter] Loaded 2021-04-27T16:59:40.691Z,1619542780.691 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-04-27T16:59:40.691Z,1619542780.691 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-04-27T16:59:40.692Z,1619542780.692 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-04-27T16:59:41.226Z,1619542781.226 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2021-04-27T16:59:41.229Z,1619542781.229 [AHRS_M2](INFO): created writer for : platform_orientation 2021-04-27T16:59:41.230Z,1619542781.230 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2021-04-27T16:59:41.233Z,1619542781.233 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2021-04-27T16:59:41.234Z,1619542781.234 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2021-04-27T16:59:41.236Z,1619542781.236 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2021-04-27T16:59:41.237Z,1619542781.237 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2021-04-27T16:59:41.240Z,1619542781.240 [AHRS_M2](INFO): created writer for : platform_roll_angle 2021-04-27T16:59:41.322Z,1619542781.322 [AHRS_M2] Loaded 2021-04-27T16:59:41.322Z,1619542781.322 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-04-27T16:59:41.463Z,1619542781.463 [DataOverHttps] Loaded 2021-04-27T16:59:41.463Z,1619542781.463 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-04-27T16:59:41.464Z,1619542781.464 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408514E0 2021-04-27T16:59:41.465Z,1619542781.465 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 916 2021-04-27T16:59:41.481Z,1619542781.481 [Depth_Keller] Loaded 2021-04-27T16:59:41.482Z,1619542781.482 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-04-27T16:59:41.484Z,1619542781.484 [DropWeight] Loaded 2021-04-27T16:59:41.484Z,1619542781.484 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-04-27T16:59:41.515Z,1619542781.515 [DVL_micro] Loaded 2021-04-27T16:59:41.516Z,1619542781.516 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2021-04-27T16:59:41.568Z,1619542781.568 [NAL9602] Loaded 2021-04-27T16:59:41.569Z,1619542781.569 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-04-27T16:59:41.595Z,1619542781.595 [Onboard] Loaded 2021-04-27T16:59:41.595Z,1619542781.595 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-04-27T16:59:41.596Z,1619542781.596 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408814E0 2021-04-27T16:59:41.596Z,1619542781.596 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 917 2021-04-27T16:59:41.606Z,1619542781.606 [Radio_Surface] Loaded 2021-04-27T16:59:41.607Z,1619542781.607 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-04-27T16:59:41.608Z,1619542781.608 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0 2021-04-27T16:59:41.608Z,1619542781.608 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 918 2021-04-27T16:59:41.683Z,1619542781.683 [DAT] Loaded 2021-04-27T16:59:41.683Z,1619542781.683 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-04-27T16:59:42.463Z,1619542782.463 [BPC1] Loaded 2021-04-27T16:59:42.463Z,1619542782.463 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-04-27T16:59:42.464Z,1619542782.464 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-04-27T16:59:42.465Z,1619542782.465 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-04-27T16:59:43.017Z,1619542783.017 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-04-27T16:59:43.017Z,1619542783.017 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-04-27T16:59:43.243Z,1619542783.243 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-04-27T16:59:43.245Z,1619542783.245 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-04-27T16:59:43.285Z,1619542783.285 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-04-27T16:59:43.286Z,1619542783.286 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-04-27T16:59:43.564Z,1619542783.564 [Aanderaa_O2] Loaded 2021-04-27T16:59:43.564Z,1619542783.564 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2021-04-27T16:59:43.583Z,1619542783.583 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2021-04-27T16:59:43.586Z,1619542783.586 [CTD_Seabird](INFO): created writer for : sea_water_density 2021-04-27T16:59:43.586Z,1619542783.586 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2021-04-27T16:59:43.589Z,1619542783.589 [CTD_Seabird](INFO): created writer for : depth 2021-04-27T16:59:43.589Z,1619542783.589 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2021-04-27T16:59:43.592Z,1619542783.592 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2021-04-27T16:59:43.592Z,1619542783.592 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2021-04-27T16:59:43.595Z,1619542783.595 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2021-04-27T16:59:43.596Z,1619542783.596 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2021-04-27T16:59:43.598Z,1619542783.598 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2021-04-27T16:59:43.599Z,1619542783.599 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2021-04-27T16:59:43.602Z,1619542783.602 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2021-04-27T16:59:43.603Z,1619542783.603 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2021-04-27T16:59:43.605Z,1619542783.605 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2021-04-27T16:59:43.618Z,1619542783.618 [CTD_Seabird] Loaded 2021-04-27T16:59:43.619Z,1619542783.619 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-04-27T16:59:43.620Z,1619542783.620 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0 2021-04-27T16:59:43.620Z,1619542783.620 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 919 2021-04-27T16:59:43.650Z,1619542783.650 [ESPComponent] Loaded 2021-04-27T16:59:43.650Z,1619542783.650 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2021-04-27T16:59:43.666Z,1619542783.666 [PAR_Licor] Loaded 2021-04-27T16:59:43.667Z,1619542783.667 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-04-27T16:59:43.679Z,1619542783.679 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2021-04-27T16:59:43.679Z,1619542783.679 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2021-04-27T16:59:43.681Z,1619542783.681 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2021-04-27T16:59:43.681Z,1619542783.681 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2021-04-27T16:59:43.683Z,1619542783.683 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2021-04-27T16:59:43.683Z,1619542783.683 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2021-04-27T16:59:43.685Z,1619542783.685 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2021-04-27T16:59:43.685Z,1619542783.685 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2021-04-27T16:59:43.687Z,1619542783.687 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2021-04-27T16:59:43.687Z,1619542783.687 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2021-04-27T16:59:43.690Z,1619542783.690 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2021-04-27T16:59:43.690Z,1619542783.690 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2021-04-27T16:59:43.692Z,1619542783.692 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2021-04-27T16:59:43.692Z,1619542783.692 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2021-04-27T16:59:43.694Z,1619542783.694 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:59:43.696Z,1619542783.696 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:59:43.696Z,1619542783.696 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:59:43.696Z,1619542783.696 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:59:43.698Z,1619542783.698 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:59:43.698Z,1619542783.698 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:59:43.700Z,1619542783.700 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:59:43.701Z,1619542783.701 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:59:43.702Z,1619542783.702 [WetLabsBB2FL] Loaded 2021-04-27T16:59:43.703Z,1619542783.703 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-04-27T16:59:43.704Z,1619542783.704 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0 2021-04-27T16:59:43.704Z,1619542783.704 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 920 2021-04-27T16:59:43.705Z,1619542783.705 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-04-27T16:59:43.705Z,1619542783.705 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-04-27T16:59:44.038Z,1619542784.038 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-04-27T16:59:44.040Z,1619542784.040 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-04-27T16:59:44.197Z,1619542784.197 [SBIT](DEBUG): Construct Startup Built In Test. 2021-04-27T16:59:44.205Z,1619542784.205 [SBIT] Loaded 2021-04-27T16:59:44.205Z,1619542784.205 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-04-27T16:59:44.206Z,1619542784.206 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-04-27T16:59:44.218Z,1619542784.218 [IBIT] Loaded 2021-04-27T16:59:44.219Z,1619542784.219 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-04-27T16:59:44.222Z,1619542784.222 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-04-27T16:59:44.319Z,1619542784.319 [CBIT] Loaded 2021-04-27T16:59:44.319Z,1619542784.319 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-04-27T16:59:44.320Z,1619542784.320 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-04-27T16:59:44.324Z,1619542784.324 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-04-27T16:59:44.325Z,1619542784.325 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-04-27T16:59:44.333Z,1619542784.333 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-04-27T16:59:44.334Z,1619542784.334 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0 2021-04-27T16:59:44.334Z,1619542784.334 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 921 2021-04-27T16:59:44.339Z,1619542784.339 [Supervisor](INFO): Main Thread ID is 827 2021-04-27T16:59:44.339Z,1619542784.339 [Supervisor](DEBUG): Running supervisor. 2021-04-27T16:59:44.340Z,1619542784.340 [CommandLine ThreadHandler](INFO): Handler Thread ID is 922 2021-04-27T16:59:44.342Z,1619542784.342 [controlThread ThreadHandler](INFO): Handler Thread ID is 923 2021-04-27T16:59:44.342Z,1619542784.342 [controlThread](DEBUG): Initializing ControlThread 2021-04-27T16:59:44.343Z,1619542784.343 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-04-27T16:59:44.345Z,1619542784.345 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-04-27T16:59:44.346Z,1619542784.346 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-04-27T16:59:44.346Z,1619542784.346 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-04-27T16:59:44.347Z,1619542784.347 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-04-27T16:59:44.348Z,1619542784.348 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-04-27T16:59:44.348Z,1619542784.348 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-04-27T16:59:44.348Z,1619542784.348 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-04-27T16:59:44.349Z,1619542784.349 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-04-27T16:59:44.349Z,1619542784.349 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-04-27T16:59:44.350Z,1619542784.350 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-04-27T16:59:44.351Z,1619542784.351 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-04-27T16:59:44.360Z,1619542784.360 [SBIT](INFO): Initialize SBIT Component. 2021-04-27T16:59:44.360Z,1619542784.360 [SBIT](IMPORTANT): git: 2020-11-24-6-gcb638d650 2021-04-27T16:59:44.360Z,1619542784.360 [SBIT](INFO): git hash: cb638d650ac65501896d406bf5988c2fe747cbc6 2021-04-27T16:59:44.361Z,1619542784.361 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-04-27T16:59:44.362Z,1619542784.362 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020 2021-04-27T16:59:44.363Z,1619542784.363 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2021-04-27T16:59:44.363Z,1619542784.363 [IBIT](INFO): Initialize IBIT Component. 2021-04-27T16:59:44.364Z,1619542784.364 [CBIT](DEBUG): Initialize CBIT Component. 2021-04-27T16:59:44.365Z,1619542784.365 [logger ThreadHandler](INFO): Handler Thread ID is 924 2021-04-27T16:59:44.375Z,1619542784.375 [CBIT](DEBUG): Initialized mux pins. 2021-04-27T16:59:44.375Z,1619542784.375 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2021-04-27T16:59:44.375Z,1619542784.375 [CBIT](DEBUG): Initializing the watchdog timer. 2021-04-27T16:59:44.384Z,1619542784.384 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 925 2021-04-27T16:59:44.385Z,1619542784.385 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-04-27T16:59:44.396Z,1619542784.396 [Onboard ThreadHandler](INFO): Handler Thread ID is 926 2021-04-27T16:59:44.400Z,1619542784.400 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-04-27T16:59:44.400Z,1619542784.400 [CBIT](DEBUG): Initializing heartbeat. 2021-04-27T16:59:44.413Z,1619542784.413 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 927 2021-04-27T16:59:44.432Z,1619542784.432 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 928 2021-04-27T16:59:44.432Z,1619542784.432 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-04-27T16:59:44.437Z,1619542784.437 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 930 2021-04-27T16:59:44.438Z,1619542784.438 [WetLabsBB2FL](INFO): Powering down 2021-04-27T16:59:44.471Z,1619542784.471 [CBIT](DEBUG): Deactivating GF circuits. 2021-04-27T16:59:44.471Z,1619542784.471 [CBIT](DEBUG): Deactivating emergency mode. 2021-04-27T16:59:44.507Z,1619542784.507 [CBIT](DEBUG): Backplane powered. 2021-04-27T16:59:44.550Z,1619542784.550 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-04-27T16:59:44.561Z,1619542784.561 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 931 2021-04-27T16:59:44.718Z,1619542784.718 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-04-27T16:59:44.725Z,1619542784.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-04-27T16:59:44.725Z,1619542784.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-04-27T16:59:44.725Z,1619542784.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-04-27T16:59:44.725Z,1619542784.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-04-27T16:59:44.726Z,1619542784.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-04-27T16:59:44.726Z,1619542784.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-04-27T16:59:44.726Z,1619542784.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-04-27T16:59:44.726Z,1619542784.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-04-27T16:59:44.726Z,1619542784.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-04-27T16:59:44.726Z,1619542784.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-04-27T16:59:44.727Z,1619542784.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-04-27T16:59:44.727Z,1619542784.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-04-27T16:59:44.727Z,1619542784.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-04-27T16:59:44.727Z,1619542784.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-04-27T16:59:44.727Z,1619542784.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-04-27T16:59:44.727Z,1619542784.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-04-27T16:59:44.753Z,1619542784.753 [MissionManager](DEBUG): 2021-04-27T16:59:44.754Z,1619542784.754 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-04-27T16:59:44.822Z,1619542784.822 [Radio_Surface](INFO): Powering up 2021-04-27T16:59:44.890Z,1619542784.890 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-04-27T16:59:44.891Z,1619542784.891 [Default:A.Wait](DEBUG): Construct Wait. 2021-04-27T16:59:44.892Z,1619542784.892 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-04-27T16:59:44.942Z,1619542784.942 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-04-27T16:59:44.944Z,1619542784.944 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-04-27T16:59:44.965Z,1619542784.965 [Default:E.Execute](DEBUG): Construct Execute. 2021-04-27T16:59:44.969Z,1619542784.969 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2021-04-27T16:59:44.973Z,1619542784.973 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2021-04-27T16:59:44.991Z,1619542784.991 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-27T16:59:45.123Z,1619542785.123 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2021-04-27T16:59:45.143Z,1619542785.143 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2021-04-27T16:59:45.143Z,1619542785.143 [DAT](INFO): Powering up 2021-04-27T16:59:45.143Z,1619542785.143 [DAT](DEBUG): Initializing DAT. 2021-04-27T16:59:45.212Z,1619542785.212 [DepthRateCalculator](ERROR): Depth measurement is not active 2021-04-27T16:59:45.229Z,1619542785.229 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:59:45.236Z,1619542785.236 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-04-27T16:59:45.237Z,1619542785.237 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:59:45.240Z,1619542785.240 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2021-04-27T16:59:45.276Z,1619542785.276 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-04-27T16:59:45.277Z,1619542785.277 [MassServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:59:45.280Z,1619542785.280 [MassServo](DEBUG): Initializing MassServo. 2021-04-27T16:59:45.281Z,1619542785.281 [RudderServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:59:45.292Z,1619542785.292 [RudderServo](DEBUG): Initializing RudderServo. 2021-04-27T16:59:45.293Z,1619542785.293 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:59:45.304Z,1619542785.304 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-04-27T16:59:46.119Z,1619542786.119 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2021-04-27T16:59:46.119Z,1619542786.119 [RudderServo](FAULT): Rudder failed to initialize 2021-04-27T16:59:46.120Z,1619542786.120 [RudderServo] Communications Fault, FailCount= 1 2021-04-27T16:59:46.120Z,1619542786.120 [RudderServo](ERROR): Communications Fault 2021-04-27T16:59:46.219Z,1619542786.219 [CBIT](ERROR): Communications Fault in component: RudderServo 2021-04-27T16:59:46.420Z,1619542786.420 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-04-27T16:59:46.420Z,1619542786.420 [RudderServo](INFO): Powering down 2021-04-27T16:59:47.076Z,1619542787.076 [RudderServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:59:47.196Z,1619542787.196 [RudderServo](DEBUG): Initializing RudderServo. 2021-04-27T16:59:47.200Z,1619542787.200 [CBIT](INFO): Clearing failed state for component RudderServo 2021-04-27T16:59:47.200Z,1619542787.200 [RudderServo] No Fault, FailCount= 1 2021-04-27T16:59:55.526Z,1619542795.526 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2021-04-27T16:59:55.526Z,1619542795.526 [Aanderaa_O2] Communications Fault, FailCount= 1 2021-04-27T16:59:55.526Z,1619542795.526 [Aanderaa_O2](ERROR): Communications Fault 2021-04-27T16:59:55.580Z,1619542795.580 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2021-04-27T16:59:56.051Z,1619542796.051 [Aanderaa_O2](INFO): Powering down 2021-04-27T16:59:56.828Z,1619542796.828 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2021-04-27T16:59:56.828Z,1619542796.828 [Aanderaa_O2] No Fault, FailCount= 1 2021-04-27T16:59:57.951Z,1619542797.951 [DAT](INFO): commRate: 800 2021-04-27T16:59:59.969Z,1619542799.969 [DAT](INFO): entering command mode 2021-04-27T17:00:00.373Z,1619542800.373 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:00.781Z,1619542800.781 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:01.177Z,1619542801.177 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:01.585Z,1619542801.585 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:01.985Z,1619542801.985 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:02.388Z,1619542802.388 [DVL_micro](FAULT): Failed to initialize within timeout. 2021-04-27T17:00:02.389Z,1619542802.389 [DVL_micro] Communications Fault, FailCount= 1 2021-04-27T17:00:02.389Z,1619542802.389 [DVL_micro](ERROR): Communications Fault 2021-04-27T17:00:02.390Z,1619542802.390 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:02.467Z,1619542802.467 [CBIT](ERROR): Communications Fault in component: DVL_micro 2021-04-27T17:00:02.867Z,1619542802.867 [DVL_micro](INFO): Powering down 2021-04-27T17:00:02.869Z,1619542802.869 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:03.197Z,1619542803.197 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:03.601Z,1619542803.601 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:03.637Z,1619542803.637 [CBIT](INFO): Clearing failed state for component DVL_micro 2021-04-27T17:00:03.637Z,1619542803.637 [DVL_micro] No Fault, FailCount= 1 2021-04-27T17:00:04.010Z,1619542804.010 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:04.427Z,1619542804.427 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:04.818Z,1619542804.818 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:04.839Z,1619542804.839 [SBIT](IMPORTANT): Beginning Startup BIT 2021-04-27T17:00:04.850Z,1619542804.850 [CBIT](IMPORTANT): Beginning ground fault scan 2021-04-27T17:00:05.217Z,1619542805.217 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:05.621Z,1619542805.621 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:06.025Z,1619542806.025 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:06.433Z,1619542806.433 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:06.837Z,1619542806.837 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:07.241Z,1619542807.241 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:07.241Z,1619542807.241 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2021-04-27T17:00:07.241Z,1619542807.241 [Aanderaa_O2] Communications Fault, FailCount= 2 2021-04-27T17:00:07.241Z,1619542807.241 [Aanderaa_O2](ERROR): Communications Fault 2021-04-27T17:00:07.408Z,1619542807.408 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2021-04-27T17:00:07.641Z,1619542807.641 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:07.759Z,1619542807.759 [Aanderaa_O2](INFO): Powering down 2021-04-27T17:00:08.053Z,1619542808.053 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:08.458Z,1619542808.458 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:08.656Z,1619542808.656 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2021-04-27T17:00:08.656Z,1619542808.656 [Aanderaa_O2] No Fault, FailCount= 2 2021-04-27T17:00:08.865Z,1619542808.865 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:09.257Z,1619542809.257 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:09.669Z,1619542809.669 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:10.081Z,1619542810.081 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:10.473Z,1619542810.473 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:10.873Z,1619542810.873 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:11.281Z,1619542811.281 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:11.681Z,1619542811.681 [NAL9602](INFO): Powering up NAL9602 2021-04-27T17:00:11.682Z,1619542811.682 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:12.085Z,1619542812.085 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:12.493Z,1619542812.493 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:12.893Z,1619542812.893 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:13.301Z,1619542813.301 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:13.705Z,1619542813.705 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:14.105Z,1619542814.105 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:14.509Z,1619542814.509 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:14.917Z,1619542814.917 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:15.317Z,1619542815.317 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-27T17:00:15.317Z,1619542815.317 [DAT](FAULT): failed to enter command mode 2021-04-27T17:00:15.721Z,1619542815.721 [DAT](INFO): entering command mode 2021-04-27T17:00:16.130Z,1619542816.130 [DAT](INFO): setting verbose to 3 2021-04-27T17:00:16.194Z,1619542816.194 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005973 CHAN A1 (24V): -0.007889 CHAN A2 (12V): -0.004083 CHAN A3 (5V): -0.002736 CHAN B0 (3.3V): -0.000869 CHAN B1 (3.15aV): -0.001849 CHAN B2 (3.15bV): -0.000620 CHAN B3 (GND): 0.000088 OPEN: 0.006725 Full Scale Calc: 4.765 mA, -1.589 mA 2021-04-27T17:00:16.534Z,1619542816.534 [DAT](INFO): set verbose to 3 2021-04-27T17:00:16.535Z,1619542816.535 [DAT](INFO): setting DatVerbose to 27440 2021-04-27T17:00:16.934Z,1619542816.934 [DAT](INFO): set DatVerbose to 27440 2021-04-27T17:00:16.934Z,1619542816.934 [DAT](INFO): setting transmit power to 8 2021-04-27T17:00:17.342Z,1619542817.342 [DAT](INFO): set transmit power to 8 2021-04-27T17:00:17.342Z,1619542817.342 [DAT](INFO): setting local address to 5 2021-04-27T17:00:17.746Z,1619542817.746 [DAT](INFO): set local address to 5 2021-04-27T17:00:18.954Z,1619542818.954 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2021-04-27T17:00:18.954Z,1619542818.954 [Aanderaa_O2] Communications Fault, FailCount= 3 2021-04-27T17:00:18.954Z,1619542818.954 [Aanderaa_O2](ERROR): Communications Fault 2021-04-27T17:00:19.013Z,1619542819.013 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2021-04-27T17:00:19.013Z,1619542819.013 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2021-04-27T17:00:19.483Z,1619542819.483 [Aanderaa_O2](INFO): Powering down 2021-04-27T17:00:21.389Z,1619542821.388 [DVL_micro](FAULT): Failed to initialize within timeout. 2021-04-27T17:00:21.389Z,1619542821.389 [DVL_micro] Communications Fault, FailCount= 2 2021-04-27T17:00:21.389Z,1619542821.389 [DVL_micro](ERROR): Communications Fault 2021-04-27T17:00:21.524Z,1619542821.524 [CBIT](ERROR): Communications Fault in component: DVL_micro 2021-04-27T17:00:21.863Z,1619542821.863 [DVL_micro](INFO): Powering down 2021-04-27T17:00:22.594Z,1619542822.594 [NAL9602](INFO): NAL9602 initialized 2021-04-27T17:00:22.720Z,1619542822.720 [CBIT](INFO): Clearing failed state for component DVL_micro 2021-04-27T17:00:22.720Z,1619542822.720 [DVL_micro] No Fault, FailCount= 2 2021-04-27T17:00:40.374Z,1619542840.374 [DVL_micro](FAULT): Failed to initialize within timeout. 2021-04-27T17:00:40.374Z,1619542840.374 [DVL_micro] Communications Fault, FailCount= 3 2021-04-27T17:00:40.375Z,1619542840.375 [DVL_micro](ERROR): Communications Fault 2021-04-27T17:00:40.398Z,1619542840.398 [CBIT](ERROR): Communications Fault in component: DVL_micro 2021-04-27T17:00:40.398Z,1619542840.398 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2021-04-27T17:00:40.836Z,1619542840.836 [DVL_micro](INFO): Powering down 2021-04-27T17:00:40.856Z,1619542840.856 [CommandLine](FAULT): Scheduling is paused 2021-04-27T17:00:40.856Z,1619542840.856 [CBIT](INFO): Critical error at 20210427T170040 2021-04-27T17:00:40.856Z,1619542840.856 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2021-04-27T17:00:58.979Z,1619542858.979 [SBIT](IMPORTANT): SBIT PASSED 2021-04-27T17:00:58.980Z,1619542858.980 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-04-27T17:00:59.365Z,1619542859.365 [MissionManager](IMPORTANT): Started mission Startup 2021-04-27T17:00:59.366Z,1619542859.366 [Startup] Running Loop=1 2021-04-27T17:00:59.366Z,1619542859.366 [Startup](DEBUG): Aggregate::initialize Startup 2021-04-27T17:00:59.366Z,1619542859.366 [Startup:A.GoToSurface] Running Loop=1 2021-04-27T17:00:59.366Z,1619542859.366 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-04-27T17:00:59.366Z,1619542859.366 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-04-27T17:00:59.367Z,1619542859.367 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-04-27T17:00:59.367Z,1619542859.367 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-04-27T17:00:59.368Z,1619542859.368 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-04-27T17:00:59.368Z,1619542859.368 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-04-27T17:00:59.369Z,1619542859.369 [Startup:StartupSatComms] Running Loop=1 2021-04-27T17:00:59.370Z,1619542859.370 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-04-27T17:00:59.370Z,1619542859.370 [Startup:StartupSatComms:A] Running Loop=1 2021-04-27T17:00:59.778Z,1619542859.778 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-04-27T17:01:59.582Z,1619542919.582 [Startup:StartupSatComms:A](INFO): Timed out from 2021-04-27T17:00:59.4Z 2021-04-27T17:01:59.582Z,1619542919.582 [Startup:StartupSatComms:A] Stopped 2021-04-27T17:01:59.582Z,1619542919.582 [Startup:StartupSatComms:B] Running Loop=1 2021-04-27T17:01:59.993Z,1619542919.993 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-04-27T17:02:44.412Z,1619542964.412 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-04-27T17:02:44.412Z,1619542964.412 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-27T17:02:44.422Z,1619542964.422 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-27T17:02:44.828Z,1619542964.828 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-27T17:02:44.828Z,1619542964.828 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-04-27T17:02:49.270Z,1619542969.270 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2021-04-27T17:02:49.270Z,1619542969.270 [Aanderaa_O2] No Fault, FailCount= 3 2021-04-27T17:02:59.768Z,1619542979.768 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2021-04-27T17:02:59.768Z,1619542979.768 [Aanderaa_O2] Communications Fault, FailCount= 1 2021-04-27T17:02:59.768Z,1619542979.768 [Aanderaa_O2](ERROR): Communications Fault 2021-04-27T17:02:59.782Z,1619542979.782 [Startup:StartupSatComms:B](INFO): Timed out from 2021-04-27T17:01:59.6Z 2021-04-27T17:02:59.782Z,1619542979.782 [Startup:StartupSatComms:B] Stopped 2021-04-27T17:02:59.782Z,1619542979.782 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2021-04-27T17:02:59.782Z,1619542979.782 [Startup:StartupSatComms] Stopped 2021-04-27T17:02:59.782Z,1619542979.782 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2021-04-27T17:02:59.783Z,1619542979.783 [Startup](INFO): Completed Startup 2021-04-27T17:02:59.783Z,1619542979.783 [MissionManager](INFO): Startup is completed. 2021-04-27T17:02:59.783Z,1619542979.783 [MissionManager](INFO): Uninitializing Mission Startup 2021-04-27T17:02:59.783Z,1619542979.783 [Startup] Stopped 2021-04-27T17:02:59.783Z,1619542979.783 [Startup](DEBUG): Aggregate::uninitialize Startup 2021-04-27T17:02:59.783Z,1619542979.783 [Startup:A.GoToSurface] Stopped 2021-04-27T17:02:59.783Z,1619542979.783 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-04-27T17:02:59.794Z,1619542979.794 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2021-04-27T17:03:00.268Z,1619542980.268 [Aanderaa_O2](INFO): Powering down 2021-04-27T17:03:00.289Z,1619542980.289 [MissionManager](IMPORTANT): Started mission Default 2021-04-27T17:03:00.289Z,1619542980.289 [Default] Running Loop=1 2021-04-27T17:03:00.290Z,1619542980.290 [Default](DEBUG): Aggregate::initialize Default 2021-04-27T17:03:00.290Z,1619542980.290 [Default:B.GoToSurface] Running Loop=1 2021-04-27T17:03:00.290Z,1619542980.290 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-04-27T17:03:00.290Z,1619542980.290 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-04-27T17:03:00.290Z,1619542980.290 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-04-27T17:03:00.290Z,1619542980.290 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-04-27T17:03:00.291Z,1619542980.291 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-04-27T17:03:00.295Z,1619542980.295 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-04-27T17:03:00.296Z,1619542980.296 [Default:A.Wait] Running Loop=1 2021-04-27T17:03:00.296Z,1619542980.296 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-04-27T17:03:00.987Z,1619542980.987 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2021-04-27T17:03:00.987Z,1619542980.987 [Aanderaa_O2] No Fault, FailCount= 1 2021-04-27T17:03:11.528Z,1619542991.528 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2021-04-27T17:03:11.528Z,1619542991.528 [Aanderaa_O2] Communications Fault, FailCount= 2 2021-0