2020-09-21T19:19:27.940Z,1600715967.940 [Supervisor](DEBUG): Initializing supervisor.
2020-09-21T19:19:27.943Z,1600715967.943 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-21T19:19:27.943Z,1600715967.943 [SyncHandler](INFO): Protected caller Thread ID is 841
2020-09-21T19:19:27.944Z,1600715967.944 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-09-21T19:19:27.945Z,1600715967.945 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-09-21T19:19:27.945Z,1600715967.945 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 842
2020-09-21T19:19:27.948Z,1600715967.948 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-09-21T19:19:27.961Z,1600715967.961 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-09-21T19:19:27.962Z,1600715967.962 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-09-21T19:19:27.962Z,1600715967.962 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 843
2020-09-21T19:19:27.963Z,1600715967.963 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-09-21T19:19:27.964Z,1600715967.964 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-09-21T19:19:27.964Z,1600715967.964 [logger ThreadHandler](INFO): Protected caller Thread ID is 844
2020-09-21T19:19:27.966Z,1600715967.966 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-09-21T19:19:27.967Z,1600715967.967 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-09-21T19:19:27.971Z,1600715967.971 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-09-21T19:19:28.126Z,1600715968.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-09-21T19:19:28.128Z,1600715968.128 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-09-21T19:19:28.716Z,1600715968.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-09-21T19:19:28.716Z,1600715968.716 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-09-21T19:19:28.906Z,1600715968.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-09-21T19:19:28.908Z,1600715968.908 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-09-21T19:19:29.007Z,1600715969.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-09-21T19:19:29.009Z,1600715969.009 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-09-21T19:19:29.110Z,1600715969.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-09-21T19:19:29.111Z,1600715969.111 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-09-21T19:19:29.192Z,1600715969.192 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-09-21T19:19:29.328Z,1600715969.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-09-21T19:19:29.328Z,1600715969.328 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-09-21T19:19:29.612Z,1600715969.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-09-21T19:19:29.614Z,1600715969.614 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-09-21T19:19:30.060Z,1600715970.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-09-21T19:19:30.060Z,1600715970.060 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-09-21T19:19:30.206Z,1600715970.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-09-21T19:19:30.208Z,1600715970.208 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-09-21T19:19:30.399Z,1600715970.399 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-09-21T19:19:30.400Z,1600715970.400 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-09-21T19:19:30.905Z,1600715970.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-09-21T19:19:31.086Z,1600715971.086 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-09-21T19:19:31.586Z,1600715971.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-09-21T19:19:31.588Z,1600715971.588 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-09-21T19:19:31.788Z,1600715971.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-09-21T19:19:31.790Z,1600715971.790 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-09-21T19:19:32.192Z,1600715972.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-09-21T19:19:32.194Z,1600715972.194 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-09-21T19:19:32.561Z,1600715972.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-09-21T19:19:32.563Z,1600715972.563 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-09-21T19:19:32.566Z,1600715972.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-09-21T19:19:32.649Z,1600715972.649 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-09-21T19:19:32.806Z,1600715972.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-09-21T19:19:32.914Z,1600715972.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-09-21T19:19:32.999Z,1600715972.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-09-21T19:19:33.092Z,1600715973.092 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-09-21T19:19:33.290Z,1600715973.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-09-21T19:19:33.519Z,1600715973.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-09-21T19:19:33.520Z,1600715973.520 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-09-21T19:19:33.615Z,1600715973.615 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-09-21T19:19:33.713Z,1600715973.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-09-21T19:19:33.842Z,1600715973.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-09-21T19:19:33.941Z,1600715973.941 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2020-09-21T19:19:33.943Z,1600715973.943 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-09-21T19:19:34.039Z,1600715974.039 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-09-21T19:19:34.040Z,1600715974.040 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-09-21T19:19:34.104Z,1600715974.104 [VerticalControl](DEBUG): Construct VerticalControl.
2020-09-21T19:19:34.214Z,1600715974.214 [VerticalControl] Loaded
2020-09-21T19:19:34.215Z,1600715974.215 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-09-21T19:19:34.215Z,1600715974.215 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-09-21T19:19:34.293Z,1600715974.293 [HorizontalControl] Loaded
2020-09-21T19:19:34.294Z,1600715974.294 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-09-21T19:19:34.294Z,1600715974.294 [SpeedControl](DEBUG): Construct SpeedControl.
2020-09-21T19:19:34.300Z,1600715974.300 [SpeedControl] Loaded
2020-09-21T19:19:34.300Z,1600715974.300 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-09-21T19:19:34.301Z,1600715974.301 [LoopControl](DEBUG): Construct LoopControl.
2020-09-21T19:19:34.301Z,1600715974.301 [LoopControl] Loaded
2020-09-21T19:19:34.301Z,1600715974.301 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-09-21T19:19:34.302Z,1600715974.302 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-09-21T19:19:34.302Z,1600715974.302 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-09-21T19:19:34.357Z,1600715974.357 [DepthRateCalculator] Loaded
2020-09-21T19:19:34.357Z,1600715974.357 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-09-21T19:19:34.362Z,1600715974.362 [PitchRateCalculator] Loaded
2020-09-21T19:19:34.362Z,1600715974.362 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-09-21T19:19:34.377Z,1600715974.377 [SpeedCalculator] Loaded
2020-09-21T19:19:34.378Z,1600715974.378 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-09-21T19:19:34.399Z,1600715974.399 [TempGradientCalculator] Loaded
2020-09-21T19:19:34.400Z,1600715974.400 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-09-21T19:19:34.405Z,1600715974.405 [YawRateCalculator] Loaded
2020-09-21T19:19:34.405Z,1600715974.405 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-09-21T19:19:34.434Z,1600715974.434 [ElevatorOffsetCalculator] Loaded
2020-09-21T19:19:34.434Z,1600715974.434 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-09-21T19:19:34.434Z,1600715974.434 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-09-21T19:19:34.436Z,1600715974.436 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-09-21T19:19:34.477Z,1600715974.477 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-09-21T19:19:34.477Z,1600715974.477 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-09-21T19:19:34.605Z,1600715974.605 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-09-21T19:19:34.606Z,1600715974.606 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-09-21T19:19:34.932Z,1600715974.932 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-09-21T19:19:34.933Z,1600715974.933 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-09-21T19:19:35.055Z,1600715975.055 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-09-21T19:19:35.057Z,1600715975.057 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-09-21T19:19:35.758Z,1600715975.758 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-09-21T19:19:35.763Z,1600715975.763 [AHRS_M2](INFO): created writer for : platform_orientation
2020-09-21T19:19:35.764Z,1600715975.764 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-09-21T19:19:35.769Z,1600715975.769 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-09-21T19:19:35.770Z,1600715975.770 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-09-21T19:19:35.775Z,1600715975.775 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-09-21T19:19:35.775Z,1600715975.775 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-09-21T19:19:35.781Z,1600715975.781 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-09-21T19:19:35.936Z,1600715975.936 [AHRS_M2] Loaded
2020-09-21T19:19:35.936Z,1600715975.936 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-09-21T19:19:36.440Z,1600715976.440 [DataOverHttps] Loaded
2020-09-21T19:19:36.441Z,1600715976.441 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-09-21T19:19:36.442Z,1600715976.442 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0
2020-09-21T19:19:36.442Z,1600715976.442 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 927
2020-09-21T19:19:36.530Z,1600715976.530 [Depth_Keller] Loaded
2020-09-21T19:19:36.530Z,1600715976.530 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-09-21T19:19:36.535Z,1600715976.535 [DropWeight] Loaded
2020-09-21T19:19:36.535Z,1600715976.535 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-09-21T19:19:36.771Z,1600715976.771 [NAL9602] Loaded
2020-09-21T19:19:36.771Z,1600715976.771 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-09-21T19:19:36.883Z,1600715976.883 [Onboard] Loaded
2020-09-21T19:19:36.883Z,1600715976.883 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-09-21T19:19:36.884Z,1600715976.884 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093D4E0
2020-09-21T19:19:36.884Z,1600715976.884 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 928
2020-09-21T19:19:36.960Z,1600715976.960 [Radio_Surface] Loaded
2020-09-21T19:19:36.960Z,1600715976.960 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-09-21T19:19:36.961Z,1600715976.961 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0
2020-09-21T19:19:36.961Z,1600715976.961 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 929
2020-09-21T19:19:37.019Z,1600715977.019 [RDI_Pathfinder] Loaded
2020-09-21T19:19:37.020Z,1600715977.020 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-09-21T19:19:37.168Z,1600715977.168 [DAT] Loaded
2020-09-21T19:19:37.168Z,1600715977.168 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-09-21T19:19:38.631Z,1600715978.631 [BPC1] Loaded
2020-09-21T19:19:38.631Z,1600715978.631 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-09-21T19:19:38.662Z,1600715978.662 [AMEcho] Loaded
2020-09-21T19:19:38.663Z,1600715978.663 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2020-09-21T19:19:38.663Z,1600715978.663 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-09-21T19:19:38.664Z,1600715978.664 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-09-21T19:19:38.796Z,1600715978.796 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-09-21T19:19:38.796Z,1600715978.796 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-09-21T19:19:38.816Z,1600715978.816 [NavChart] Loaded
2020-09-21T19:19:38.817Z,1600715978.817 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-09-21T19:19:38.821Z,1600715978.821 [UniversalFixResidualReporter] Loaded
2020-09-21T19:19:38.821Z,1600715978.821 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-09-21T19:19:38.821Z,1600715978.821 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-09-21T19:19:38.822Z,1600715978.822 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-09-21T19:19:38.979Z,1600715978.979 [SBIT](DEBUG): Construct Startup Built In Test.
2020-09-21T19:19:38.990Z,1600715978.990 [SBIT] Loaded
2020-09-21T19:19:38.990Z,1600715978.990 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-09-21T19:19:38.991Z,1600715978.991 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-09-21T19:19:39.004Z,1600715979.004 [IBIT] Loaded
2020-09-21T19:19:39.004Z,1600715979.004 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-09-21T19:19:39.007Z,1600715979.007 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-09-21T19:19:39.146Z,1600715979.146 [CBIT] Loaded
2020-09-21T19:19:39.146Z,1600715979.146 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-09-21T19:19:39.147Z,1600715979.147 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-09-21T19:19:39.147Z,1600715979.147 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-09-21T19:19:39.289Z,1600715979.289 [BuoyancyServo] Loaded
2020-09-21T19:19:39.289Z,1600715979.289 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-09-21T19:19:39.318Z,1600715979.318 [ElevatorServo] Loaded
2020-09-21T19:19:39.318Z,1600715979.318 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-09-21T19:19:39.345Z,1600715979.345 [MassServo] Loaded
2020-09-21T19:19:39.346Z,1600715979.346 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-09-21T19:19:39.373Z,1600715979.373 [RudderServo] Loaded
2020-09-21T19:19:39.373Z,1600715979.373 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-09-21T19:19:39.400Z,1600715979.400 [ThrusterServo] Loaded
2020-09-21T19:19:39.400Z,1600715979.400 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-09-21T19:19:39.400Z,1600715979.400 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-09-21T19:19:39.401Z,1600715979.401 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-09-21T19:19:39.431Z,1600715979.431 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-09-21T19:19:39.431Z,1600715979.431 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-09-21T19:19:39.744Z,1600715979.744 [BackSeatDriver] Loaded
2020-09-21T19:19:39.744Z,1600715979.744 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-09-21T19:19:39.745Z,1600715979.745 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0
2020-09-21T19:19:39.745Z,1600715979.745 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 930
2020-09-21T19:19:39.774Z,1600715979.774 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-09-21T19:19:39.779Z,1600715979.779 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-09-21T19:19:39.780Z,1600715979.780 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-09-21T19:19:39.784Z,1600715979.784 [CTD_Seabird](INFO): created writer for : depth
2020-09-21T19:19:39.785Z,1600715979.785 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-09-21T19:19:39.790Z,1600715979.790 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-09-21T19:19:39.790Z,1600715979.790 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-09-21T19:19:39.795Z,1600715979.795 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-09-21T19:19:39.796Z,1600715979.796 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-09-21T19:19:39.801Z,1600715979.801 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-09-21T19:19:39.802Z,1600715979.802 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-09-21T19:19:39.807Z,1600715979.807 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-09-21T19:19:39.807Z,1600715979.807 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-09-21T19:19:39.813Z,1600715979.813 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-09-21T19:19:39.839Z,1600715979.839 [CTD_Seabird] Loaded
2020-09-21T19:19:39.839Z,1600715979.839 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-09-21T19:19:39.840Z,1600715979.840 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B334E0
2020-09-21T19:19:39.841Z,1600715979.841 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 931
2020-09-21T19:19:39.862Z,1600715979.862 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-09-21T19:19:39.863Z,1600715979.863 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-09-21T19:19:39.867Z,1600715979.867 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-09-21T19:19:39.867Z,1600715979.867 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-09-21T19:19:39.871Z,1600715979.871 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-09-21T19:19:39.871Z,1600715979.871 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-09-21T19:19:39.876Z,1600715979.876 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-09-21T19:19:39.876Z,1600715979.876 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-09-21T19:19:39.880Z,1600715979.880 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-09-21T19:19:39.880Z,1600715979.880 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-09-21T19:19:39.884Z,1600715979.884 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-09-21T19:19:39.885Z,1600715979.885 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-09-21T19:19:39.889Z,1600715979.889 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-09-21T19:19:39.889Z,1600715979.889 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-09-21T19:19:39.893Z,1600715979.893 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-09-21T19:19:39.897Z,1600715979.897 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-09-21T19:19:39.898Z,1600715979.898 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-21T19:19:39.898Z,1600715979.898 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-21T19:19:39.902Z,1600715979.902 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-21T19:19:39.902Z,1600715979.902 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-21T19:19:39.906Z,1600715979.906 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-21T19:19:39.906Z,1600715979.906 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-21T19:19:39.911Z,1600715979.911 [WetLabsBB2FL] Loaded
2020-09-21T19:19:39.911Z,1600715979.911 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-09-21T19:19:39.912Z,1600715979.912 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2020-09-21T19:19:39.912Z,1600715979.912 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 932
2020-09-21T19:19:39.913Z,1600715979.913 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-09-21T19:19:39.917Z,1600715979.917 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-09-21T19:19:39.918Z,1600715979.918 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-09-21T19:19:39.925Z,1600715979.925 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-09-21T19:19:39.926Z,1600715979.926 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0
2020-09-21T19:19:39.926Z,1600715979.926 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 933
2020-09-21T19:19:39.930Z,1600715979.930 [Supervisor](INFO): Main Thread ID is 835
2020-09-21T19:19:39.931Z,1600715979.931 [Supervisor](DEBUG): Running supervisor.
2020-09-21T19:19:39.931Z,1600715979.931 [CommandLine ThreadHandler](INFO): Handler Thread ID is 934
2020-09-21T19:19:39.934Z,1600715979.934 [controlThread ThreadHandler](INFO): Handler Thread ID is 935
2020-09-21T19:19:39.934Z,1600715979.934 [controlThread](DEBUG): Initializing ControlThread
2020-09-21T19:19:39.935Z,1600715979.935 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-09-21T19:19:39.937Z,1600715979.937 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-09-21T19:19:39.937Z,1600715979.937 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-09-21T19:19:39.938Z,1600715979.938 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-09-21T19:19:39.938Z,1600715979.938 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-09-21T19:19:39.938Z,1600715979.938 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-09-21T19:19:39.939Z,1600715979.939 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-09-21T19:19:39.939Z,1600715979.939 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-09-21T19:19:39.940Z,1600715979.940 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-09-21T19:19:39.940Z,1600715979.940 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-09-21T19:19:39.947Z,1600715979.947 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-09-21T19:19:39.947Z,1600715979.947 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-09-21T19:19:39.947Z,1600715979.947 [SBIT](INFO): Initialize SBIT Component.
2020-09-21T19:19:39.948Z,1600715979.948 [SBIT](IMPORTANT): git: 2020-08-31
2020-09-21T19:19:39.948Z,1600715979.948 [SBIT](INFO): git hash: b6c0ec5a69f4280de3f7606c4f2437bb78ebd873
2020-09-21T19:19:39.949Z,1600715979.949 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-09-21T19:19:39.950Z,1600715979.950 [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-21T19:19:39.951Z,1600715979.951 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-09-21T19:19:39.951Z,1600715979.951 [IBIT](INFO): Initialize IBIT Component.
2020-09-21T19:19:39.952Z,1600715979.952 [CBIT](DEBUG): Initialize CBIT Component.
2020-09-21T19:19:39.953Z,1600715979.953 [logger ThreadHandler](INFO): Handler Thread ID is 936
2020-09-21T19:19:39.964Z,1600715979.964 [CBIT](DEBUG): Initialized mux pins.
2020-09-21T19:19:39.964Z,1600715979.964 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2020-09-21T19:19:39.964Z,1600715979.964 [CBIT](DEBUG): Initializing the watchdog timer.
2020-09-21T19:19:39.972Z,1600715979.972 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937
2020-09-21T19:19:39.973Z,1600715979.973 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-09-21T19:19:39.984Z,1600715979.984 [Onboard ThreadHandler](INFO): Handler Thread ID is 938
2020-09-21T19:19:39.989Z,1600715979.989 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-09-21T19:19:39.989Z,1600715979.989 [CBIT](DEBUG): Initializing heartbeat.
2020-09-21T19:19:40.002Z,1600715980.002 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939
2020-09-21T19:19:40.024Z,1600715980.024 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 940
2020-09-21T19:19:40.044Z,1600715980.044 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941
2020-09-21T19:19:40.045Z,1600715980.045 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-09-21T19:19:40.050Z,1600715980.050 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943
2020-09-21T19:19:40.050Z,1600715980.050 [WetLabsBB2FL](INFO): Powering down
2020-09-21T19:19:40.060Z,1600715980.060 [CBIT](DEBUG): Deactivating GF circuits.
2020-09-21T19:19:40.060Z,1600715980.060 [CBIT](DEBUG): Deactivating emergency mode.
2020-09-21T19:19:40.096Z,1600715980.096 [CBIT](DEBUG): Backplane powered.
2020-09-21T19:19:40.098Z,1600715980.098 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-09-21T19:19:40.163Z,1600715980.163 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2020-09-21T19:19:40.272Z,1600715980.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-09-21T19:19:40.272Z,1600715980.272 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-09-21T19:19:40.272Z,1600715980.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-09-21T19:19:40.272Z,1600715980.272 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-09-21T19:19:40.272Z,1600715980.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-09-21T19:19:40.273Z,1600715980.273 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-09-21T19:19:40.273Z,1600715980.273 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-09-21T19:19:40.273Z,1600715980.273 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-09-21T19:19:40.273Z,1600715980.273 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-09-21T19:19:40.273Z,1600715980.273 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-09-21T19:19:40.273Z,1600715980.273 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-09-21T19:19:40.274Z,1600715980.274 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-09-21T19:19:40.274Z,1600715980.274 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-09-21T19:19:40.274Z,1600715980.274 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-09-21T19:19:40.274Z,1600715980.274 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-09-21T19:19:40.274Z,1600715980.274 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-09-21T19:19:40.287Z,1600715980.287 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-21T19:19:40.378Z,1600715980.378 [MissionManager](DEBUG):
2020-09-21T19:19:40.379Z,1600715980.379 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-09-21T19:19:40.408Z,1600715980.408 [Radio_Surface](INFO): Powering up
2020-09-21T19:19:40.491Z,1600715980.491 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-09-21T19:19:40.492Z,1600715980.492 [Default:A.Wait](DEBUG): Construct Wait.
2020-09-21T19:19:40.515Z,1600715980.515 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-21T19:19:40.542Z,1600715980.542 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-09-21T19:19:40.544Z,1600715980.544 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-09-21T19:19:40.570Z,1600715980.570 [Default:E.Execute](DEBUG): Construct Execute.
2020-09-21T19:19:40.581Z,1600715980.581 [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
2020-09-21T19:19:40.585Z,1600715980.585 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,AMEcho,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-21T19:19:40.596Z,1600715980.596 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-21T19:19:40.641Z,1600715980.641 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-09-21T19:19:40.654Z,1600715980.654 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-09-21T19:19:40.655Z,1600715980.655 [DAT](INFO): Powering up
2020-09-21T19:19:40.655Z,1600715980.655 [DAT](DEBUG): Initializing DAT.
2020-09-21T19:19:40.700Z,1600715980.700 [AMEcho](INFO): Powering up
2020-09-21T19:19:40.716Z,1600715980.716 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-09-21T19:19:40.732Z,1600715980.732 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-09-21T19:19:40.804Z,1600715980.804 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-09-21T19:19:40.816Z,1600715980.816 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-09-21T19:19:40.818Z,1600715980.818 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-21T19:19:40.828Z,1600715980.828 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-21T19:19:40.829Z,1600715980.829 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-21T19:19:40.840Z,1600715980.840 [MassServo](DEBUG): Initializing MassServo.
2020-09-21T19:19:40.841Z,1600715980.841 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-21T19:19:40.852Z,1600715980.852 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-21T19:19:40.853Z,1600715980.853 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-09-21T19:19:40.862Z,1600715980.862 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-09-21T19:19:41.300Z,1600715981.300 [AMEcho](INFO): Powering down
2020-09-21T19:19:41.736Z,1600715981.736 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-09-21T19:19:41.736Z,1600715981.736 [RudderServo](FAULT): Rudder failed to initialize
2020-09-21T19:19:41.736Z,1600715981.736 [RudderServo] Communications Fault, FailCount= 1
2020-09-21T19:19:41.736Z,1600715981.736 [RudderServo](ERROR): Communications Fault
2020-09-21T19:19:41.883Z,1600715981.883 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-09-21T19:19:42.051Z,1600715982.051 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-21T19:19:42.054Z,1600715982.054 [RudderServo](INFO): Powering down
2020-09-21T19:19:42.711Z,1600715982.711 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-21T19:19:42.829Z,1600715982.829 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-21T19:19:42.833Z,1600715982.833 [CBIT](INFO): Clearing failed state for component RudderServo
2020-09-21T19:19:42.833Z,1600715982.833 [RudderServo] No Fault, FailCount= 1
2020-09-21T19:19:47.994Z,1600715987.994 [DAT](INFO): unknown deviceResponse_: ERROR - no bootable image. Entering boot loader to update
2020-09-21T19:19:48.403Z,1600715988.403 [DAT](INFO): unknown deviceResponse_: NOTE: Changing baud rate to 115200.
2020-09-21T19:20:02.906Z,1600716002.906 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-21T19:20:02.906Z,1600716002.906 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-09-21T19:20:02.906Z,1600716002.906 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-21T19:20:02.976Z,1600716002.976 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-21T19:20:03.393Z,1600716003.393 [RDI_Pathfinder](INFO): Powering down
2020-09-21T19:20:04.176Z,1600716004.176 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-21T19:20:04.176Z,1600716004.176 [RDI_Pathfinder] No Fault, FailCount= 1
2020-09-21T19:20:06.948Z,1600716006.948 [NAL9602](INFO): Powering up NAL9602
2020-09-21T19:20:17.854Z,1600716017.854 [NAL9602](INFO): NAL9602 initialized
2020-09-21T19:20:19.533Z,1600716019.533 [SBIT](IMPORTANT): Beginning Startup BIT
2020-09-21T19:20:19.537Z,1600716019.537 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-21T19:20:26.753Z,1600716026.753 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-21T19:20:26.753Z,1600716026.753 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-09-21T19:20:26.753Z,1600716026.753 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-21T19:20:26.881Z,1600716026.881 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-21T19:20:27.216Z,1600716027.216 [RDI_Pathfinder](INFO): Powering down
2020-09-21T19:20:28.080Z,1600716028.080 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-21T19:20:28.080Z,1600716028.080 [RDI_Pathfinder] No Fault, FailCount= 2
2020-09-21T19:20:30.483Z,1600716030.483 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.003802
CHAN A1 (24V): 0.036710
CHAN A2 (12V): -0.006720
CHAN A3 (5V): -0.001442
CHAN B0 (3.3V): -0.000231
CHAN B1 (3.15aV): -0.000122
CHAN B2 (3.15bV): -0.000181
CHAN B3 (GND): 0.001959
OPEN: -0.002125
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-21T19:20:32.914Z,1600716032.914 [SBIT](FAULT): Mass: EXPECTED:0.013000 ACTUAL:0.008034
2020-09-21T19:20:40.887Z,1600716040.887 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: NOTE: Changing baud rate to 115200.
, available: UWu_ÝUW]]Ýÿ@
v‰¾ E@/
2020-09-21T19:20:40.887Z,1600716040.887 [DAT] Communications Fault, FailCou