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