2021-04-27T03:50:30.319Z,1619495430.319 [Supervisor](DEBUG): Initializing supervisor. 2021-04-27T03:50:30.324Z,1619495430.324 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-04-27T03:50:30.325Z,1619495430.325 [SyncHandler](INFO): Protected caller Thread ID is 2413 2021-04-27T03:50:30.325Z,1619495430.325 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-04-27T03:50:30.327Z,1619495430.327 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-04-27T03:50:30.327Z,1619495430.327 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2414 2021-04-27T03:50:30.332Z,1619495430.332 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-04-27T03:50:30.350Z,1619495430.350 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-04-27T03:50:30.351Z,1619495430.351 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-04-27T03:50:30.352Z,1619495430.352 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2415 2021-04-27T03:50:30.354Z,1619495430.354 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-04-27T03:50:30.355Z,1619495430.355 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-04-27T03:50:30.355Z,1619495430.355 [logger ThreadHandler](INFO): Protected caller Thread ID is 2416 2021-04-27T03:50:30.359Z,1619495430.359 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-04-27T03:50:30.359Z,1619495430.359 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-04-27T03:50:30.361Z,1619495430.361 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-04-27T03:50:30.595Z,1619495430.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-04-27T03:50:30.596Z,1619495430.596 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-04-27T03:50:30.675Z,1619495430.675 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-04-27T03:50:31.179Z,1619495431.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-04-27T03:50:31.180Z,1619495431.180 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-04-27T03:50:31.512Z,1619495431.512 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-04-27T03:50:31.512Z,1619495431.512 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-04-27T03:50:31.608Z,1619495431.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-04-27T03:50:31.609Z,1619495431.609 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-04-27T03:50:31.969Z,1619495431.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-04-27T03:50:31.969Z,1619495431.969 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-04-27T03:50:32.167Z,1619495432.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-04-27T03:50:32.168Z,1619495432.168 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-04-27T03:50:32.606Z,1619495432.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-04-27T03:50:32.606Z,1619495432.606 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-04-27T03:50:32.787Z,1619495432.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-04-27T03:50:32.787Z,1619495432.787 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-04-27T03:50:32.998Z,1619495432.998 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-04-27T03:50:33.701Z,1619495433.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-04-27T03:50:33.702Z,1619495433.702 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-04-27T03:50:34.063Z,1619495434.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-04-27T03:50:34.063Z,1619495434.063 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-04-27T03:50:34.255Z,1619495434.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-04-27T03:50:34.255Z,1619495434.255 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-04-27T03:50:34.357Z,1619495434.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-04-27T03:50:34.358Z,1619495434.358 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-04-27T03:50:34.509Z,1619495434.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-04-27T03:50:34.509Z,1619495434.509 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-04-27T03:50:34.737Z,1619495434.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-04-27T03:50:34.739Z,1619495434.739 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2021-04-27T03:50:34.740Z,1619495434.740 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2021-04-27T03:50:34.830Z,1619495434.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2021-04-27T03:50:34.916Z,1619495434.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2021-04-27T03:50:35.018Z,1619495435.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2021-04-27T03:50:35.101Z,1619495435.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2021-04-27T03:50:35.197Z,1619495435.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2021-04-27T03:50:35.299Z,1619495435.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2021-04-27T03:50:35.497Z,1619495435.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2021-04-27T03:50:35.573Z,1619495435.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2021-04-27T03:50:35.731Z,1619495435.731 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2021-04-27T03:50:35.872Z,1619495435.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2021-04-27T03:50:35.993Z,1619495435.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2021-04-27T03:50:36.255Z,1619495436.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-04-27T03:50:36.256Z,1619495436.256 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2021-04-27T03:50:36.257Z,1619495436.257 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2021-04-27T03:50:36.264Z,1619495436.264 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-04-27T03:50:36.318Z,1619495436.318 [VerticalControl](DEBUG): Construct VerticalControl. 2021-04-27T03:50:36.379Z,1619495436.379 [VerticalControl] Loaded 2021-04-27T03:50:36.379Z,1619495436.379 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-04-27T03:50:36.382Z,1619495436.382 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-04-27T03:50:36.420Z,1619495436.420 [HorizontalControl] Loaded 2021-04-27T03:50:36.420Z,1619495436.420 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-04-27T03:50:36.423Z,1619495436.423 [SpeedControl](DEBUG): Construct SpeedControl. 2021-04-27T03:50:36.426Z,1619495436.426 [SpeedControl] Loaded 2021-04-27T03:50:36.426Z,1619495436.426 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-04-27T03:50:36.429Z,1619495436.429 [LoopControl](DEBUG): Construct LoopControl. 2021-04-27T03:50:36.429Z,1619495436.429 [LoopControl] Loaded 2021-04-27T03:50:36.429Z,1619495436.429 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-04-27T03:50:36.430Z,1619495436.430 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-04-27T03:50:36.430Z,1619495436.430 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-04-27T03:50:36.441Z,1619495436.441 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-04-27T03:50:36.442Z,1619495436.442 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-04-27T03:50:36.544Z,1619495436.544 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-04-27T03:50:36.544Z,1619495436.544 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-04-27T03:50:36.679Z,1619495436.679 [BuoyancyServo] Loaded 2021-04-27T03:50:36.679Z,1619495436.679 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-04-27T03:50:36.698Z,1619495436.698 [ElevatorServo] Loaded 2021-04-27T03:50:36.698Z,1619495436.698 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-04-27T03:50:36.716Z,1619495436.716 [MassServo] Loaded 2021-04-27T03:50:36.716Z,1619495436.716 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-04-27T03:50:36.734Z,1619495436.734 [RudderServo] Loaded 2021-04-27T03:50:36.734Z,1619495436.734 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-04-27T03:50:36.751Z,1619495436.751 [ThrusterServo] Loaded 2021-04-27T03:50:36.751Z,1619495436.751 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-04-27T03:50:36.752Z,1619495436.752 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-04-27T03:50:36.752Z,1619495436.752 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-04-27T03:50:36.832Z,1619495436.832 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-04-27T03:50:36.832Z,1619495436.832 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-04-27T03:50:36.845Z,1619495436.845 [NavChart] Loaded 2021-04-27T03:50:36.845Z,1619495436.845 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-04-27T03:50:36.850Z,1619495436.850 [UniversalFixResidualReporter] Loaded 2021-04-27T03:50:36.851Z,1619495436.851 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-04-27T03:50:36.851Z,1619495436.851 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-04-27T03:50:36.852Z,1619495436.852 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-04-27T03:50:36.908Z,1619495436.908 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-04-27T03:50:36.908Z,1619495436.908 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-04-27T03:50:37.247Z,1619495437.247 [AHRS_M2] Loaded 2021-04-27T03:50:37.247Z,1619495437.247 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-04-27T03:50:38.042Z,1619495438.042 [BPC1] Loaded 2021-04-27T03:50:38.042Z,1619495438.042 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-04-27T03:50:38.111Z,1619495438.111 [DataOverHttps] Loaded 2021-04-27T03:50:38.112Z,1619495438.112 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-04-27T03:50:38.113Z,1619495438.113 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408784E0 2021-04-27T03:50:38.113Z,1619495438.113 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2501 2021-04-27T03:50:38.133Z,1619495438.133 [Depth_Keller] Loaded 2021-04-27T03:50:38.133Z,1619495438.133 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-04-27T03:50:38.137Z,1619495438.137 [DropWeight] Loaded 2021-04-27T03:50:38.138Z,1619495438.138 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-04-27T03:50:38.195Z,1619495438.195 [NAL9602] Loaded 2021-04-27T03:50:38.196Z,1619495438.196 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-04-27T03:50:38.270Z,1619495438.270 [Onboard] Loaded 2021-04-27T03:50:38.270Z,1619495438.270 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-04-27T03:50:38.283Z,1619495438.283 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408A84E0 2021-04-27T03:50:38.284Z,1619495438.284 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2502 2021-04-27T03:50:38.309Z,1619495438.309 [Radio_Surface] Loaded 2021-04-27T03:50:38.309Z,1619495438.309 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-04-27T03:50:38.315Z,1619495438.315 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408D84E0 2021-04-27T03:50:38.315Z,1619495438.315 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2503 2021-04-27T03:50:38.370Z,1619495438.370 [AMEcho] Loaded 2021-04-27T03:50:38.370Z,1619495438.370 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread. 2021-04-27T03:50:38.519Z,1619495438.519 [DAT] Loaded 2021-04-27T03:50:38.519Z,1619495438.519 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-04-27T03:50:38.519Z,1619495438.519 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-04-27T03:50:38.520Z,1619495438.520 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-04-27T03:50:38.682Z,1619495438.682 [DepthRateCalculator] Loaded 2021-04-27T03:50:38.682Z,1619495438.682 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-04-27T03:50:38.687Z,1619495438.687 [PitchRateCalculator] Loaded 2021-04-27T03:50:38.687Z,1619495438.687 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-04-27T03:50:38.696Z,1619495438.696 [SpeedCalculator] Loaded 2021-04-27T03:50:38.696Z,1619495438.696 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-04-27T03:50:38.710Z,1619495438.710 [TempGradientCalculator] Loaded 2021-04-27T03:50:38.710Z,1619495438.710 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-04-27T03:50:38.714Z,1619495438.714 [YawRateCalculator] Loaded 2021-04-27T03:50:38.714Z,1619495438.714 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-04-27T03:50:38.732Z,1619495438.732 [ElevatorOffsetCalculator] Loaded 2021-04-27T03:50:38.732Z,1619495438.732 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-04-27T03:50:38.733Z,1619495438.733 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-04-27T03:50:38.733Z,1619495438.733 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-04-27T03:50:38.995Z,1619495438.995 [CTD_Seabird] Loaded 2021-04-27T03:50:38.995Z,1619495438.995 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-04-27T03:50:38.996Z,1619495438.996 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0 2021-04-27T03:50:38.996Z,1619495438.996 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2504 2021-04-27T03:50:39.016Z,1619495439.016 [PAR_Licor] Loaded 2021-04-27T03:50:39.016Z,1619495439.016 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-04-27T03:50:39.047Z,1619495439.047 [WetLabsBB2FL] Loaded 2021-04-27T03:50:39.048Z,1619495439.048 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-04-27T03:50:39.049Z,1619495439.049 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409BA4E0 2021-04-27T03:50:39.049Z,1619495439.049 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2505 2021-04-27T03:50:39.050Z,1619495439.050 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-04-27T03:50:39.050Z,1619495439.050 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-04-27T03:50:39.079Z,1619495439.079 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-04-27T03:50:39.079Z,1619495439.079 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-04-27T03:50:39.399Z,1619495439.399 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-04-27T03:50:39.399Z,1619495439.399 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-04-27T03:50:39.544Z,1619495439.544 [SBIT](DEBUG): Construct Startup Built In Test. 2021-04-27T03:50:39.553Z,1619495439.553 [SBIT] Loaded 2021-04-27T03:50:39.553Z,1619495439.553 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-04-27T03:50:39.556Z,1619495439.556 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-04-27T03:50:39.569Z,1619495439.569 [IBIT] Loaded 2021-04-27T03:50:39.569Z,1619495439.569 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-04-27T03:50:39.575Z,1619495439.575 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-04-27T03:50:39.673Z,1619495439.673 [CBIT] Loaded 2021-04-27T03:50:39.673Z,1619495439.673 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-04-27T03:50:39.674Z,1619495439.674 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-04-27T03:50:39.674Z,1619495439.674 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-04-27T03:50:39.763Z,1619495439.763 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-04-27T03:50:39.769Z,1619495439.769 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-04-27T03:50:39.772Z,1619495439.772 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-04-27T03:50:39.782Z,1619495439.782 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-04-27T03:50:39.783Z,1619495439.783 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0 2021-04-27T03:50:39.784Z,1619495439.784 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2506 2021-04-27T03:50:39.788Z,1619495439.788 [Supervisor](INFO): Main Thread ID is 2146 2021-04-27T03:50:39.788Z,1619495439.788 [Supervisor](DEBUG): Running supervisor. 2021-04-27T03:50:39.789Z,1619495439.789 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2507 2021-04-27T03:50:39.791Z,1619495439.791 [controlThread ThreadHandler](INFO): Handler Thread ID is 2508 2021-04-27T03:50:39.792Z,1619495439.792 [controlThread](DEBUG): Initializing ControlThread 2021-04-27T03:50:39.793Z,1619495439.793 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-04-27T03:50:39.794Z,1619495439.794 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-04-27T03:50:39.795Z,1619495439.795 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-04-27T03:50:39.796Z,1619495439.796 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-04-27T03:50:39.798Z,1619495439.798 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-04-27T03:50:39.798Z,1619495439.798 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-04-27T03:50:39.805Z,1619495439.805 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-04-27T03:50:39.806Z,1619495439.806 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-04-27T03:50:39.806Z,1619495439.806 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-04-27T03:50:39.806Z,1619495439.806 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-04-27T03:50:39.807Z,1619495439.807 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-04-27T03:50:39.807Z,1619495439.807 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-04-27T03:50:39.812Z,1619495439.812 [SBIT](INFO): Initialize SBIT Component. 2021-04-27T03:50:39.812Z,1619495439.812 [SBIT](IMPORTANT): git: 2021-04-19 2021-04-27T03:50:39.812Z,1619495439.812 [SBIT](INFO): git hash: f68c130b0735c80abc7d2defc1d1ad4282ec925d 2021-04-27T03:50:39.813Z,1619495439.813 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-04-27T03:50:39.814Z,1619495439.814 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019 2021-04-27T03:50:39.815Z,1619495439.815 [SBIT](INFO): Beginning SBIT in 56.000000 seconds. 2021-04-27T03:50:39.816Z,1619495439.816 [IBIT](INFO): Initialize IBIT Component. 2021-04-27T03:50:39.816Z,1619495439.816 [CBIT](DEBUG): Initialize CBIT Component. 2021-04-27T03:50:39.817Z,1619495439.817 [logger ThreadHandler](INFO): Handler Thread ID is 2509 2021-04-27T03:50:39.827Z,1619495439.827 [CBIT](DEBUG): Initialized mux pins. 2021-04-27T03:50:39.827Z,1619495439.827 [CBIT](DEBUG): Initializing the watchdog timer. 2021-04-27T03:50:39.836Z,1619495439.836 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2510 2021-04-27T03:50:39.837Z,1619495439.837 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-04-27T03:50:39.848Z,1619495439.848 [Onboard ThreadHandler](INFO): Handler Thread ID is 2511 2021-04-27T03:50:39.852Z,1619495439.852 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-04-27T03:50:39.852Z,1619495439.852 [CBIT](DEBUG): Initializing heartbeat. 2021-04-27T03:50:39.868Z,1619495439.868 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2512 2021-04-27T03:50:39.884Z,1619495439.884 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2513 2021-04-27T03:50:39.884Z,1619495439.884 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-04-27T03:50:39.889Z,1619495439.889 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2515 2021-04-27T03:50:39.892Z,1619495439.892 [WetLabsBB2FL](INFO): Powering up 2021-04-27T03:50:39.893Z,1619495439.893 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2517 2021-04-27T03:50:39.896Z,1619495439.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-04-27T03:50:39.896Z,1619495439.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-04-27T03:50:39.896Z,1619495439.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-04-27T03:50:39.896Z,1619495439.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-04-27T03:50:39.897Z,1619495439.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-04-27T03:50:39.897Z,1619495439.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-04-27T03:50:39.897Z,1619495439.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-04-27T03:50:39.897Z,1619495439.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-04-27T03:50:39.897Z,1619495439.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-04-27T03:50:39.897Z,1619495439.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-04-27T03:50:39.898Z,1619495439.898 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-04-27T03:50:39.898Z,1619495439.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-04-27T03:50:39.898Z,1619495439.898 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-04-27T03:50:39.898Z,1619495439.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-04-27T03:50:39.898Z,1619495439.898 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-04-27T03:50:39.898Z,1619495439.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-04-27T03:50:39.923Z,1619495439.923 [CBIT](DEBUG): Deactivating GF circuits. 2021-04-27T03:50:39.923Z,1619495439.923 [CBIT](DEBUG): Deactivating emergency mode. 2021-04-27T03:50:39.959Z,1619495439.959 [CBIT](DEBUG): Backplane powered. 2021-04-27T03:50:39.960Z,1619495439.960 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-04-27T03:50:39.973Z,1619495439.973 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-04-27T03:50:39.996Z,1619495439.996 [MissionManager](DEBUG): 2021-04-27T03:50:39.997Z,1619495439.997 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-04-27T03:50:40.071Z,1619495440.071 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-04-27T03:50:40.072Z,1619495440.072 [Default:A.Wait](DEBUG): Construct Wait. 2021-04-27T03:50:40.074Z,1619495440.074 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-04-27T03:50:40.132Z,1619495440.132 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-04-27T03:50:40.135Z,1619495440.135 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-04-27T03:50:40.157Z,1619495440.157 [Default:E.Execute](DEBUG): Construct Execute. 2021-04-27T03:50:40.160Z,1619495440.160 [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-27T03:50:40.165Z,1619495440.165 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,AMEcho,DAT,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-27T03:50:40.183Z,1619495440.183 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-27T03:50:40.275Z,1619495440.275 [Radio_Surface](INFO): Powering up 2021-04-27T03:50:40.366Z,1619495440.366 [AMEcho](INFO): Powering up 2021-04-27T03:50:40.368Z,1619495440.368 [AMEcho](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2021-04-27T03:50:40.395Z,1619495440.395 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2021-04-27T03:50:40.395Z,1619495440.395 [DAT](INFO): Powering up 2021-04-27T03:50:40.395Z,1619495440.395 [DAT](DEBUG): Initializing DAT. 2021-04-27T03:50:40.412Z,1619495440.411 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2021-04-27T03:50:40.560Z,1619495440.560 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-04-27T03:50:40.567Z,1619495440.567 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-04-27T03:50:40.569Z,1619495440.569 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-04-27T03:50:40.575Z,1619495440.575 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-04-27T03:50:40.577Z,1619495440.577 [MassServo](DEBUG): Initializing EZServoServo. 2021-04-27T03:50:40.583Z,1619495440.583 [MassServo](DEBUG): Initializing MassServo. 2021-04-27T03:50:40.584Z,1619495440.584 [RudderServo](DEBUG): Initializing EZServoServo. 2021-04-27T03:50:40.591Z,1619495440.591 [RudderServo](DEBUG): Initializing RudderServo. 2021-04-27T03:50:40.592Z,1619495440.592 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-04-27T03:50:40.599Z,1619495440.599 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-04-27T03:50:40.688Z,1619495440.688 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2021-04-27T03:50:40.688Z,1619495440.688 [DropWeight] Hardware Fault, FailCount= 1 2021-04-27T03:50:40.688Z,1619495440.688 [DropWeight](ERROR): Hardware Fault 2021-04-27T03:50:40.855Z,1619495440.855 [AMEcho](INFO): Powering down 2021-04-27T03:50:40.878Z,1619495440.878 [CommandLine](FAULT): Scheduling is paused 2021-04-27T03:50:40.878Z,1619495440.878 [CBIT](INFO): Critical error at 20210427T035040 2021-04-27T03:50:40.878Z,1619495440.878 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2021-04-27T03:50:40.881Z,1619495440.881 [CBIT](ERROR): Hardware Fault in component: DropWeight 2021-04-27T03:50:40.881Z,1619495440.881 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2021-04-27T03:50:41.050Z,1619495441.050 [CBIT](INFO): Critical error at 20210427T035040 2021-04-27T03:50:41.425Z,1619495441.425 [AMEcho](IMPORTANT): Setting 'depthThreshold' to 20.000000 2021-04-27T03:50:41.999Z,1619495441.999 [WetLabsBB2FL](INFO): Powering down 2021-04-27T03:50:45.148Z,1619495445.148 [CommandLine](IMPORTANT): got command quit 2021-04-27T03:50:46.151Z,1619495446.151 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-27T03:50:46.152Z,1619495446.152 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:46.243Z,1619495446.243 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-04-27T03:50:46.243Z,1619495446.243 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:46.244Z,1619495446.244 [CommandLine](INFO): Join timeout helper Thread ID is 2527 2021-04-27T03:50:46.259Z,1619495446.259 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-04-27T03:50:46.259Z,1619495446.259 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:46.260Z,1619495446.260 [NavChartDb](INFO): Join timeout helper Thread ID is 2528 2021-04-27T03:50:46.916Z,1619495446.916 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-04-27T03:50:54.466Z,1619495454.466 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-27T03:50:54.466Z,1619495454.466 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:54.467Z,1619495454.467 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-04-27T03:50:54.467Z,1619495454.467 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:54.468Z,1619495454.468 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2529 2021-04-27T03:50:54.503Z,1619495454.503 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-27T03:50:54.504Z,1619495454.504 [WetLabsBB2FL](INFO): Powering down 2021-04-27T03:50:54.504Z,1619495454.504 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:54.515Z,1619495454.515 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-04-27T03:50:54.515Z,1619495454.515 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:54.516Z,1619495454.516 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2530 2021-04-27T03:50:54.971Z,1619495454.971 [CTD_Seabird](INFO): Powering down 2021-04-27T03:50:54.983Z,1619495454.983 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-27T03:50:54.984Z,1619495454.984 [CTD_Seabird](INFO): Powering down 2021-04-27T03:50:54.995Z,1619495454.995 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:55.003Z,1619495455.003 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-04-27T03:50:55.003Z,1619495455.003 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:55.004Z,1619495455.004 [Radio_Surface](INFO): Join timeout helper Thread ID is 2531 2021-04-27T03:50:55.224Z,1619495455.224 [Radio_Surface](INFO): Powering down 2021-04-27T03:50:55.224Z,1619495455.224 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-27T03:50:55.225Z,1619495455.225 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:55.236Z,1619495455.236 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-04-27T03:50:55.237Z,1619495455.237 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:55.237Z,1619495455.237 [Onboard](INFO): Join timeout helper Thread ID is 2532 2021-04-27T03:50:59.863Z,1619495459.863 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-27T03:50:59.864Z,1619495459.864 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:59.880Z,1619495459.880 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-04-27T03:50:59.880Z,1619495459.880 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:59.880Z,1619495459.880 [DataOverHttps](INFO): Join timeout helper Thread ID is 2533 2021-04-27T03:50:59.915Z,1619495459.915 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-27T03:50:59.916Z,1619495459.916 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:59.920Z,1619495459.920 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-04-27T03:50:59.920Z,1619495459.920 [logger ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:59.920Z,1619495459.920 [logger](INFO): Join timeout helper Thread ID is 2534 2021-04-27T03:50:59.951Z,1619495459.951 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-27T03:50:59.952Z,1619495459.952 [logger ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:59.960Z,1619495459.960 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-04-27T03:50:59.960Z,1619495459.960 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:59.960Z,1619495459.960 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-04-27T03:50:59.960Z,1619495459.960 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:50:59.961Z,1619495459.961 [controlThread](INFO): Join timeout helper Thread ID is 2535 2021-04-27T03:50:59.999Z,1619495459.999 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-27T03:50:59.000Z,1619495460.000 [controlThread](DEBUG): Uninitializing ControlThread 2021-04-27T03:51:00.000Z,1619495460.000 [AHRS_M2](INFO): Powering down 2021-04-27T03:51:00.072Z,1619495460.072 [NAL9602](INFO): Powering down 2021-04-27T03:51:00.073Z,1619495460.073 [AMEcho](INFO): Powering down 2021-04-27T03:51:00.074Z,1619495460.074 [DAT](INFO): Powering down 2021-04-27T03:51:00.192Z,1619495460.192 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-04-27T03:51:00.193Z,1619495460.193 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-04-27T03:51:00.193Z,1619495460.193 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-04-27T03:51:00.194Z,1619495460.194 [MissionManager](INFO): Uninitializing Mission Default 2021-04-27T03:51:00.197Z,1619495460.197 [MissionManager](INFO): Uninitializing Mission Startup 2021-04-27T03:51:00.198Z,1619495460.198 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-04-27T03:51:00.198Z,1619495460.198 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-04-27T03:51:00.199Z,1619495460.199 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-04-27T03:51:00.199Z,1619495460.199 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-04-27T03:51:00.199Z,1619495460.199 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-04-27T03:51:00.199Z,1619495460.199 [BuoyancyServo](INFO): Powering down 2021-04-27T03:51:00.211Z,1619495460.211 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-04-27T03:51:00.212Z,1619495460.212 [ElevatorServo](INFO): Powering down 2021-04-27T03:51:00.212Z,1619495460.212 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-04-27T03:51:00.212Z,1619495460.212 [MassServo](INFO): Powering down 2021-04-27T03:51:00.213Z,1619495460.213 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-04-27T03:51:00.213Z,1619495460.213 [RudderServo](INFO): Powering down 2021-04-27T03:51:00.214Z,1619495460.214 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-04-27T03:51:00.214Z,1619495460.214 [ThrusterServo](INFO): Powering down 2021-04-27T03:51:00.215Z,1619495460.215 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-04-27T03:51:00.215Z,1619495460.215 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-04-27T03:51:00.216Z,1619495460.216 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-04-27T03:51:00.216Z,1619495460.216 [CBIT](DEBUG): Powering off loads. 2021-04-27T03:51:00.227Z,1619495460.227 [CBIT](DEBUG): Disabling WDT. 2021-04-27T03:51:00.239Z,1619495460.239 [CBIT](DEBUG): Opening all GF detection circuits. 2021-04-27T03:51:00.240Z,1619495460.240 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:51:00.535Z,1619495460.535 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:51:00.537Z,1619495460.537 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:51:00.543Z,1619495460.543 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:51:00.611Z,1619495460.611 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:51:00.615Z,1619495460.615 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:51:00.655Z,1619495460.655 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-27T03:51:00.712Z,1619495460.712 [logger ThreadHandler](INFO): Thread cancelled.