2021-11-17T20:53:17.007Z,1637182397.007 [Supervisor](DEBUG): Initializing supervisor.
2021-11-17T20:53:17.012Z,1637182397.012 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-11-17T20:53:17.012Z,1637182397.012 [SyncHandler](INFO): Protected caller Thread ID is 1057
2021-11-17T20:53:17.013Z,1637182397.013 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-11-17T20:53:17.014Z,1637182397.014 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-11-17T20:53:17.014Z,1637182397.014 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1058
2021-11-17T20:53:17.019Z,1637182397.019 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-11-17T20:53:17.040Z,1637182397.040 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-11-17T20:53:17.041Z,1637182397.041 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-11-17T20:53:17.041Z,1637182397.041 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1059
2021-11-17T20:53:17.044Z,1637182397.044 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-11-17T20:53:17.045Z,1637182397.045 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-11-17T20:53:17.045Z,1637182397.045 [logger ThreadHandler](INFO): Protected caller Thread ID is 1060
2021-11-17T20:53:17.049Z,1637182397.049 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-11-17T20:53:17.049Z,1637182397.049 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-11-17T20:53:17.051Z,1637182397.051 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-11-17T20:53:17.359Z,1637182397.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-11-17T20:53:17.360Z,1637182397.360 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-11-17T20:53:17.444Z,1637182397.444 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-11-17T20:53:18.011Z,1637182398.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-11-17T20:53:18.012Z,1637182398.012 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-11-17T20:53:18.393Z,1637182398.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-11-17T20:53:18.394Z,1637182398.394 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-11-17T20:53:18.497Z,1637182398.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-11-17T20:53:18.497Z,1637182398.497 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-11-17T20:53:18.922Z,1637182398.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-11-17T20:53:18.923Z,1637182398.923 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-11-17T20:53:19.145Z,1637182399.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-11-17T20:53:19.146Z,1637182399.146 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-11-17T20:53:19.689Z,1637182399.689 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-11-17T20:53:19.689Z,1637182399.689 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-11-17T20:53:19.825Z,1637182399.825 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-11-17T20:53:19.826Z,1637182399.826 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-11-17T20:53:19.908Z,1637182399.908 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-11-17T20:53:20.702Z,1637182400.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-11-17T20:53:20.703Z,1637182400.703 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-11-17T20:53:21.115Z,1637182401.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-11-17T20:53:21.116Z,1637182401.116 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-11-17T20:53:21.334Z,1637182401.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-11-17T20:53:21.335Z,1637182401.335 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-11-17T20:53:21.454Z,1637182401.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-11-17T20:53:21.454Z,1637182401.454 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-11-17T20:53:21.623Z,1637182401.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-11-17T20:53:21.624Z,1637182401.624 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-11-17T20:53:21.901Z,1637182401.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-11-17T20:53:21.903Z,1637182401.903 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-11-17T20:53:21.904Z,1637182401.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-11-17T20:53:22.552Z,1637182402.552 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-11-17T20:53:22.944Z,1637182402.944 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-11-17T20:53:23.052Z,1637182403.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-11-17T20:53:23.139Z,1637182403.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-11-17T20:53:23.240Z,1637182403.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-11-17T20:53:23.347Z,1637182403.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-11-17T20:53:23.557Z,1637182403.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-11-17T20:53:23.635Z,1637182403.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-11-17T20:53:23.800Z,1637182403.800 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-11-17T20:53:23.953Z,1637182403.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-11-17T20:53:24.080Z,1637182404.080 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-11-17T20:53:24.364Z,1637182404.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-11-17T20:53:24.365Z,1637182404.365 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2021-11-17T20:53:24.366Z,1637182404.366 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-11-17T20:53:24.379Z,1637182404.379 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-11-17T20:53:24.455Z,1637182404.455 [VerticalControl](DEBUG): Construct VerticalControl.
2021-11-17T20:53:24.524Z,1637182404.524 [VerticalControl] Loaded
2021-11-17T20:53:24.524Z,1637182404.524 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-11-17T20:53:24.527Z,1637182404.527 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-11-17T20:53:24.569Z,1637182404.569 [HorizontalControl] Loaded
2021-11-17T20:53:24.569Z,1637182404.569 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-11-17T20:53:24.572Z,1637182404.572 [SpeedControl](DEBUG): Construct SpeedControl.
2021-11-17T20:53:24.576Z,1637182404.576 [SpeedControl] Loaded
2021-11-17T20:53:24.576Z,1637182404.576 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-11-17T20:53:24.579Z,1637182404.579 [LoopControl](DEBUG): Construct LoopControl.
2021-11-17T20:53:24.579Z,1637182404.579 [LoopControl] Loaded
2021-11-17T20:53:24.580Z,1637182404.580 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-11-17T20:53:24.580Z,1637182404.580 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-11-17T20:53:24.581Z,1637182404.581 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-11-17T20:53:24.597Z,1637182404.597 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-11-17T20:53:24.598Z,1637182404.598 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-11-17T20:53:24.740Z,1637182404.740 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-11-17T20:53:24.741Z,1637182404.741 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-11-17T20:53:24.932Z,1637182404.932 [BuoyancyServo] Loaded
2021-11-17T20:53:24.932Z,1637182404.932 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-11-17T20:53:24.953Z,1637182404.953 [ElevatorServo] Loaded
2021-11-17T20:53:24.954Z,1637182404.954 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-11-17T20:53:24.974Z,1637182404.974 [MassServo] Loaded
2021-11-17T20:53:24.974Z,1637182404.974 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-11-17T20:53:24.993Z,1637182404.993 [RudderServo] Loaded
2021-11-17T20:53:24.994Z,1637182404.994 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-11-17T20:53:25.013Z,1637182405.013 [ThrusterServo] Loaded
2021-11-17T20:53:25.013Z,1637182405.013 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-11-17T20:53:25.014Z,1637182405.014 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-11-17T20:53:25.014Z,1637182405.014 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-11-17T20:53:25.121Z,1637182405.121 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-11-17T20:53:25.121Z,1637182405.121 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-11-17T20:53:25.136Z,1637182405.136 [NavChart] Loaded
2021-11-17T20:53:25.136Z,1637182405.136 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-11-17T20:53:25.142Z,1637182405.142 [UniversalFixResidualReporter] Loaded
2021-11-17T20:53:25.142Z,1637182405.142 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-11-17T20:53:25.143Z,1637182405.143 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-11-17T20:53:25.144Z,1637182405.144 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-11-17T20:53:25.229Z,1637182405.229 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-11-17T20:53:25.230Z,1637182405.230 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-11-17T20:53:25.746Z,1637182405.746 [AHRS_M2] Loaded
2021-11-17T20:53:25.747Z,1637182405.747 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-11-17T20:53:26.613Z,1637182406.613 [BPC1] Loaded
2021-11-17T20:53:26.614Z,1637182406.614 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-11-17T20:53:26.635Z,1637182406.635 [Depth_Keller] Loaded
2021-11-17T20:53:26.636Z,1637182406.636 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-11-17T20:53:26.641Z,1637182406.641 [DropWeight] Loaded
2021-11-17T20:53:26.641Z,1637182406.641 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-11-17T20:53:26.706Z,1637182406.706 [NAL9602] Loaded
2021-11-17T20:53:26.707Z,1637182406.707 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-11-17T20:53:26.738Z,1637182406.738 [Onboard] Loaded
2021-11-17T20:53:26.739Z,1637182406.739 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-11-17T20:53:26.740Z,1637182406.740 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4088B4E0
2021-11-17T20:53:26.740Z,1637182406.740 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1145
2021-11-17T20:53:26.754Z,1637182406.754 [Power24vConverter] Loaded
2021-11-17T20:53:26.755Z,1637182406.755 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-11-17T20:53:26.770Z,1637182406.770 [Radio_Surface] Loaded
2021-11-17T20:53:26.771Z,1637182406.771 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-11-17T20:53:26.772Z,1637182406.772 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408BB4E0
2021-11-17T20:53:26.772Z,1637182406.772 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1146
2021-11-17T20:53:26.862Z,1637182406.862 [DAT] Loaded
2021-11-17T20:53:26.862Z,1637182406.862 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-11-17T20:53:26.863Z,1637182406.863 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-11-17T20:53:26.863Z,1637182406.863 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-11-17T20:53:26.928Z,1637182406.928 [DepthRateCalculator] Loaded
2021-11-17T20:53:26.928Z,1637182406.928 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-11-17T20:53:26.933Z,1637182406.933 [PitchRateCalculator] Loaded
2021-11-17T20:53:26.934Z,1637182406.934 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-11-17T20:53:26.944Z,1637182406.944 [SpeedCalculator] Loaded
2021-11-17T20:53:26.944Z,1637182406.944 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-11-17T20:53:26.959Z,1637182406.959 [TempGradientCalculator] Loaded
2021-11-17T20:53:26.959Z,1637182406.959 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-11-17T20:53:26.964Z,1637182406.964 [YawRateCalculator] Loaded
2021-11-17T20:53:26.964Z,1637182406.964 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-11-17T20:53:26.984Z,1637182406.984 [ElevatorOffsetCalculator] Loaded
2021-11-17T20:53:26.984Z,1637182406.984 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-11-17T20:53:26.985Z,1637182406.985 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-11-17T20:53:26.985Z,1637182406.985 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-11-17T20:53:27.635Z,1637182407.635 [CANONSampler] Loaded
2021-11-17T20:53:27.636Z,1637182407.636 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2021-11-17T20:53:27.705Z,1637182407.705 [CTD_Seabird] Loaded
2021-11-17T20:53:27.706Z,1637182407.706 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-11-17T20:53:27.707Z,1637182407.707 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409704E0
2021-11-17T20:53:27.707Z,1637182407.707 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1147
2021-11-17T20:53:27.729Z,1637182407.729 [PAR_Licor] Loaded
2021-11-17T20:53:27.729Z,1637182407.729 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-11-17T20:53:27.765Z,1637182407.765 [WetLabsBB2FL] Loaded
2021-11-17T20:53:27.765Z,1637182407.765 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-11-17T20:53:27.766Z,1637182407.766 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0
2021-11-17T20:53:27.767Z,1637182407.767 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1148
2021-11-17T20:53:27.767Z,1637182407.767 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-11-17T20:53:27.768Z,1637182407.768 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-11-17T20:53:27.813Z,1637182407.813 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-11-17T20:53:27.814Z,1637182407.814 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-11-17T20:53:28.223Z,1637182408.223 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-11-17T20:53:28.223Z,1637182408.223 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-11-17T20:53:28.413Z,1637182408.413 [SBIT](DEBUG): Construct Startup Built In Test.
2021-11-17T20:53:28.423Z,1637182408.423 [SBIT] Loaded
2021-11-17T20:53:28.423Z,1637182408.423 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-11-17T20:53:28.426Z,1637182408.426 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-11-17T20:53:28.440Z,1637182408.440 [IBIT] Loaded
2021-11-17T20:53:28.441Z,1637182408.441 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-11-17T20:53:28.447Z,1637182408.447 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-11-17T20:53:28.562Z,1637182408.562 [CBIT] Loaded
2021-11-17T20:53:28.562Z,1637182408.562 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-11-17T20:53:28.563Z,1637182408.563 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-11-17T20:53:28.563Z,1637182408.563 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-11-17T20:53:28.692Z,1637182408.692 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-11-17T20:53:28.698Z,1637182408.698 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-11-17T20:53:28.701Z,1637182408.701 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-11-17T20:53:28.712Z,1637182408.712 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-11-17T20:53:28.713Z,1637182408.713 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD54E0
2021-11-17T20:53:28.714Z,1637182408.714 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1149
2021-11-17T20:53:28.719Z,1637182408.719 [Supervisor](INFO): Main Thread ID is 829
2021-11-17T20:53:28.719Z,1637182408.719 [Supervisor](DEBUG): Running supervisor.
2021-11-17T20:53:28.720Z,1637182408.720 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1150
2021-11-17T20:53:28.722Z,1637182408.722 [controlThread ThreadHandler](INFO): Handler Thread ID is 1151
2021-11-17T20:53:28.723Z,1637182408.723 [controlThread](DEBUG): Initializing ControlThread
2021-11-17T20:53:28.724Z,1637182408.724 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-11-17T20:53:28.725Z,1637182408.725 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-11-17T20:53:28.726Z,1637182408.726 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-11-17T20:53:28.727Z,1637182408.727 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-11-17T20:53:28.729Z,1637182408.729 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-11-17T20:53:28.729Z,1637182408.729 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-11-17T20:53:28.733Z,1637182408.733 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-11-17T20:53:28.734Z,1637182408.734 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-11-17T20:53:28.734Z,1637182408.734 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-11-17T20:53:28.734Z,1637182408.734 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-11-17T20:53:28.735Z,1637182408.735 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-11-17T20:53:28.735Z,1637182408.735 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-11-17T20:53:28.739Z,1637182408.739 [SBIT](INFO): Initialize SBIT Component.
2021-11-17T20:53:28.739Z,1637182408.739 [SBIT](IMPORTANT): git: 2021-09-29
2021-11-17T20:53:28.740Z,1637182408.740 [SBIT](INFO): git hash: d0bedea221735b48726a30f35f8acb1d414c6dbf
2021-11-17T20:53:28.740Z,1637182408.740 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-11-17T20:53:28.741Z,1637182408.741 [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 Mar 17 08:23:48 PDT 2021
2021-11-17T20:53:28.743Z,1637182408.743 [SBIT](INFO): Beginning SBIT in 84.000000 seconds.
2021-11-17T20:53:28.743Z,1637182408.743 [IBIT](INFO): Initialize IBIT Component.
2021-11-17T20:53:28.744Z,1637182408.744 [CBIT](DEBUG): Initialize CBIT Component.
2021-11-17T20:53:28.745Z,1637182408.745 [logger ThreadHandler](INFO): Handler Thread ID is 1152
2021-11-17T20:53:28.759Z,1637182408.759 [CBIT](DEBUG): Initialized mux pins.
2021-11-17T20:53:28.759Z,1637182408.759 [CBIT](DEBUG): Initializing the watchdog timer.
2021-11-17T20:53:28.763Z,1637182408.763 [Onboard ThreadHandler](INFO): Handler Thread ID is 1153
2021-11-17T20:53:28.783Z,1637182408.783 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-11-17T20:53:28.783Z,1637182408.783 [CBIT](DEBUG): Initializing heartbeat.
2021-11-17T20:53:28.781Z,1637182408.781 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1154
2021-11-17T20:53:28.803Z,1637182408.803 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1155
2021-11-17T20:53:28.804Z,1637182408.804 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-11-17T20:53:28.808Z,1637182408.808 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1157
2021-11-17T20:53:28.811Z,1637182408.811 [WetLabsBB2FL](INFO): Powering up
2021-11-17T20:53:28.812Z,1637182408.812 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1159
2021-11-17T20:53:28.817Z,1637182408.817 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-11-17T20:53:28.817Z,1637182408.817 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-11-17T20:53:28.818Z,1637182408.818 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-11-17T20:53:28.818Z,1637182408.818 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-11-17T20:53:28.818Z,1637182408.818 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-11-17T20:53:28.818Z,1637182408.818 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-11-17T20:53:28.818Z,1637182408.818 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-11-17T20:53:28.819Z,1637182408.819 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-11-17T20:53:28.819Z,1637182408.819 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-11-17T20:53:28.820Z,1637182408.820 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-11-17T20:53:28.820Z,1637182408.820 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-11-17T20:53:28.820Z,1637182408.820 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-11-17T20:53:28.820Z,1637182408.820 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-11-17T20:53:28.820Z,1637182408.820 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-11-17T20:53:28.821Z,1637182408.821 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-11-17T20:53:28.821Z,1637182408.821 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-11-17T20:53:28.855Z,1637182408.855 [CBIT](DEBUG): Deactivating GF circuits.
2021-11-17T20:53:28.855Z,1637182408.855 [CBIT](DEBUG): Deactivating emergency mode.
2021-11-17T20:53:28.891Z,1637182408.891 [CBIT](DEBUG): Backplane powered.
2021-11-17T20:53:28.892Z,1637182408.892 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-11-17T20:53:28.908Z,1637182408.908 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-17T20:53:28.936Z,1637182408.936 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2021-11-17T20:53:28.936Z,1637182408.936 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-11-17T20:53:29.021Z,1637182409.021 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-11-17T20:53:29.022Z,1637182409.022 [Default:A.Wait](DEBUG): Construct Wait.
2021-11-17T20:53:29.025Z,1637182409.025 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-17T20:53:29.073Z,1637182409.073 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-11-17T20:53:29.075Z,1637182409.075 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-11-17T20:53:29.098Z,1637182409.098 [Default:E.Execute](DEBUG): Construct Execute.
2021-11-17T20:53:29.102Z,1637182409.102 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2021-11-17T20:53:29.107Z,1637182409.107 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,DAT,CANONSampler,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-11-17T20:53:29.127Z,1637182409.127 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-11-17T20:53:29.191Z,1637182409.191 [Radio_Surface](INFO): Powering up
2021-11-17T20:53:29.217Z,1637182409.217 [Power24vConverter](INFO): Powering up.
2021-11-17T20:53:29.218Z,1637182409.218 [DAT](INFO): Powering up
2021-11-17T20:53:29.218Z,1637182409.218 [DAT](DEBUG): Initializing DAT.
2021-11-17T20:53:29.235Z,1637182409.235 [CANONSampler](INFO): Powering down
2021-11-17T20:53:29.293Z,1637182409.293 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-11-17T20:53:29.299Z,1637182409.299 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-11-17T20:53:29.300Z,1637182409.300 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-11-17T20:53:29.307Z,1637182409.307 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-11-17T20:53:29.308Z,1637182409.308 [MassServo](DEBUG): Initializing EZServoServo.
2021-11-17T20:53:29.319Z,1637182409.319 [MassServo](DEBUG): Initializing MassServo.
2021-11-17T20:53:29.320Z,1637182409.320 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-17T20:53:29.327Z,1637182409.327 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-17T20:53:29.328Z,1637182409.328 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-11-17T20:53:29.335Z,1637182409.335 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-11-17T20:53:29.540Z,1637182409.540 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2021-11-17T20:53:29.541Z,1637182409.541 [DropWeight] Hardware Fault, FailCount= 1
2021-11-17T20:53:29.541Z,1637182409.541 [DropWeight](ERROR): Hardware Fault
2021-11-17T20:53:29.581Z,1637182409.581 [CommandLine](FAULT): Scheduling is paused
2021-11-17T20:53:29.582Z,1637182409.582 [CBIT](INFO): Critical error at 20211117T205329
2021-11-17T20:53:29.582Z,1637182409.582 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-11-17T20:53:29.584Z,1637182409.584 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-11-17T20:53:29.585Z,1637182409.585 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-11-17T20:53:30.243Z,1637182410.243 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-11-17T20:53:30.243Z,1637182410.243 [RudderServo](FAULT): Rudder failed to initialize
2021-11-17T20:53:30.243Z,1637182410.243 [RudderServo] Communications Fault, FailCount= 1
2021-11-17T20:53:30.243Z,1637182410.243 [RudderServo](ERROR): Communications Fault
2021-11-17T20:53:30.341Z,1637182410.341 [CBIT](INFO): Critical error at 20211117T205329
2021-11-17T20:53:30.344Z,1637182410.344 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-11-17T20:53:30.556Z,1637182410.556 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-11-17T20:53:30.556Z,1637182410.556 [RudderServo](INFO): Powering down
2021-11-17T20:53:30.919Z,1637182410.919 [WetLabsBB2FL](INFO): Powering down
2021-11-17T20:53:31.180Z,1637182411.180 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-17T20:53:31.300Z,1637182411.300 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-17T20:53:31.304Z,1637182411.304 [CBIT](INFO): Clearing failed state for component RudderServo
2021-11-17T20:53:31.304Z,1637182411.304 [RudderServo] No Fault, FailCount= 1
2021-11-17T20:53:57.020Z,1637182437.020 [NAL9602](INFO): Powering up NAL9602
2021-11-17T20:54:07.932Z,1637182447.932 [NAL9602](INFO): NAL9602 initialized
2021-11-17T20:54:29.341Z,1637182469.341 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T20:54:29.341Z,1637182469.341 [DAT] Communications Fault, FailCount= 1
2021-11-17T20:54:29.341Z,1637182469.341 [DAT](ERROR): Communications Fault
2021-11-17T20:54:29.357Z,1637182469.357 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T20:54:29.773Z,1637182469.773 [DAT](INFO): Powering down
2021-11-17T20:54:30.587Z,1637182470.587 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T20:54:30.588Z,1637182470.588 [DAT] No Fault, FailCount= 1
2021-11-17T20:54:32.993Z,1637182472.993 [DAT](INFO): Powering up
2021-11-17T20:54:32.994Z,1637182472.994 [DAT](DEBUG): Initializing DAT.
2021-11-17T20:54:53.214Z,1637182493.214 [SBIT](IMPORTANT): Beginning Startup BIT
2021-11-17T20:54:53.218Z,1637182493.218 [CBIT](IMPORTANT): Beginning ground fault scan
2021-11-17T20:55:04.533Z,1637182504.533 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002144
CHAN A1 (24V): 0.000490
CHAN A2 (12V): -0.002150
CHAN A3 (5V): -0.001789
CHAN B0 (3.3V): -0.000236
CHAN B1 (3.15aV): -0.000323
CHAN B2 (3.15bV): -0.000381
CHAN B3 (GND): 0.000159
OPEN: -0.000356
Full Scale: +/- 1 mA
2021-11-17T20:55:33.177Z,1637182533.177 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T20:55:33.177Z,1637182533.177 [DAT] Communications Fault, FailCount= 2
2021-11-17T20:55:33.178Z,1637182533.178 [DAT](ERROR): Communications Fault
2021-11-17T20:55:33.196Z,1637182533.196 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T20:55:33.577Z,1637182533.577 [DAT](INFO): Powering down
2021-11-17T20:55:34.409Z,1637182534.409 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T20:55:34.410Z,1637182534.410 [DAT] No Fault, FailCount= 2
2021-11-17T20:55:36.817Z,1637182536.817 [DAT](INFO): Powering up
2021-11-17T20:55:36.817Z,1637182536.817 [DAT](DEBUG): Initializing DAT.
2021-11-17T20:55:47.341Z,1637182547.341 [SBIT](IMPORTANT): SBIT PASSED
2021-11-17T20:55:47.341Z,1637182547.341 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-11-17T20:55:47.342Z,1637182547.342 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2021-11-17T20:55:47.342Z,1637182547.342 [SBIT](IMPORTANT): BuoyancyServo.fastPumpDepth=6 meter;

2021-11-17T20:55:47.342Z,1637182547.342 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2021-11-17T20:55:47.342Z,1637182547.342 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2021-11-17T20:55:47.342Z,1637182547.342 [SBIT](IMPORTANT): DAT.sbdAddress=1 enum;

2021-11-17T20:55:47.343Z,1637182547.343 [SBIT](IMPORTANT): DAT.surfaceThreshold=0 meter;

2021-11-17T20:55:47.343Z,1637182547.343 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2021-11-17T20:55:47.343Z,1637182547.343 [SBIT](IMPORTANT): DataOverHttps.loadAtStartup=0 bool;

2021-11-17T20:55:47.343Z,1637182547.343 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2021-11-17T20:55:47.343Z,1637182547.343 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2021-11-17T20:55:47.343Z,1637182547.343 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=296 cubic_centimeter;

2021-11-17T20:55:47.344Z,1637182547.344 [SBIT](IMPORTANT): VerticalControl.massDefault=-16 millimeter;

2021-11-17T20:55:47.344Z,1637182547.344 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=2 meter;

2021-11-17T20:55:47.729Z,1637182547.729 [MissionManager](IMPORTANT): Started mission Startup
2021-11-17T20:55:47.730Z,1637182547.730 [Startup] Running Loop=1
2021-11-17T20:55:47.730Z,1637182547.730 [Startup](DEBUG): Aggregate::initialize Startup
2021-11-17T20:55:47.730Z,1637182547.730 [Startup:A.GoToSurface] Running Loop=1
2021-11-17T20:55:47.730Z,1637182547.730 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-17T20:55:47.731Z,1637182547.731 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-11-17T20:55:47.731Z,1637182547.731 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-11-17T20:55:47.732Z,1637182547.732 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-11-17T20:55:47.732Z,1637182547.732 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-17T20:55:47.733Z,1637182547.733 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-17T20:55:47.734Z,1637182547.734 [Startup:StartupSatComms] Running Loop=1
2021-11-17T20:55:47.734Z,1637182547.734 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-11-17T20:55:47.734Z,1637182547.734 [Startup:StartupSatComms:A] Running Loop=1
2021-11-17T20:55:48.134Z,1637182548.134 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-11-17T20:56:28.962Z,1637182588.962 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-11-17T20:56:28.962Z,1637182588.962 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-17T20:56:28.973Z,1637182588.973 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-17T20:56:29.346Z,1637182589.346 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-17T20:56:29.347Z,1637182589.347 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-11-17T20:56:37.034Z,1637182597.034 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T20:56:37.034Z,1637182597.034 [DAT] Communications Fault, FailCount= 3
2021-11-17T20:56:37.039Z,1637182597.039 [DAT](ERROR): Communications Fault
2021-11-17T20:56:37.069Z,1637182597.069 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T20:56:37.455Z,1637182597.455 [DAT](INFO): Powering down
2021-11-17T20:56:38.255Z,1637182598.255 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T20:56:38.255Z,1637182598.255 [DAT] No Fault, FailCount= 3
2021-11-17T20:56:40.641Z,1637182600.641 [DAT](INFO): Powering up
2021-11-17T20:56:40.641Z,1637182600.641 [DAT](DEBUG): Initializing DAT.
2021-11-17T20:56:47.950Z,1637182607.950 [Startup:StartupSatComms:A](INFO): Timed out from 2021-11-17T20:55:47.7Z
2021-11-17T20:56:47.950Z,1637182607.950 [Startup:StartupSatComms:A] Stopped
2021-11-17T20:56:47.950Z,1637182607.950 [Startup:StartupSatComms:B] Running Loop=1
2021-11-17T20:56:48.357Z,1637182608.357 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-11-17T20:57:40.860Z,1637182660.860 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T20:57:40.860Z,1637182660.860 [DAT] Communications Fault, FailCount= 4
2021-11-17T20:57:40.860Z,1637182660.860 [DAT](ERROR): Communications Fault
2021-11-17T20:57:40.906Z,1637182660.906 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T20:57:41.259Z,1637182661.259 [DAT](INFO): Powering down
2021-11-17T20:57:42.070Z,1637182662.070 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T20:57:42.070Z,1637182662.070 [DAT] No Fault, FailCount= 4
2021-11-17T20:57:44.521Z,1637182664.521 [DAT](INFO): Powering up
2021-11-17T20:57:44.521Z,1637182664.521 [DAT](DEBUG): Initializing DAT.
2021-11-17T20:57:48.136Z,1637182668.136 [Startup:StartupSatComms:B](INFO): Timed out from 2021-11-17T20:56:47.0Z
2021-11-17T20:57:48.137Z,1637182668.137 [Startup:StartupSatComms:B] Stopped
2021-11-17T20:57:48.137Z,1637182668.137 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-11-17T20:57:48.137Z,1637182668.137 [Startup:StartupSatComms] Stopped
2021-11-17T20:57:48.137Z,1637182668.137 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-11-17T20:57:48.138Z,1637182668.138 [Startup](INFO): Completed Startup
2021-11-17T20:57:48.138Z,1637182668.138 [MissionManager](INFO): Startup is completed.
2021-11-17T20:57:48.138Z,1637182668.138 [MissionManager](INFO): Uninitializing Mission Startup
2021-11-17T20:57:48.138Z,1637182668.138 [Startup] Stopped
2021-11-17T20:57:48.138Z,1637182668.138 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-11-17T20:57:48.139Z,1637182668.139 [Startup:A.GoToSurface] Stopped
2021-11-17T20:57:48.139Z,1637182668.139 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-17T20:57:48.525Z,1637182668.525 [MissionManager](IMPORTANT): Started mission Default
2021-11-17T20:57:48.526Z,1637182668.526 [Default] Running Loop=1
2021-11-17T20:57:48.526Z,1637182668.526 [Default](DEBUG): Aggregate::initialize Default
2021-11-17T20:57:48.526Z,1637182668.526 [Default:B.GoToSurface] Running Loop=1
2021-11-17T20:57:48.526Z,1637182668.526 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-17T20:57:48.526Z,1637182668.526 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-11-17T20:57:48.527Z,1637182668.527 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-11-17T20:57:48.527Z,1637182668.527 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-11-17T20:57:48.528Z,1637182668.528 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-17T20:57:48.528Z,1637182668.528 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-17T20:57:48.528Z,1637182668.528 [Default:A.Wait] Running Loop=1
2021-11-17T20:57:48.528Z,1637182668.528 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-11-17T20:58:01.878Z,1637182681.878 [Default:A.Wait](INFO): Done Waiting.
2021-11-17T20:58:01.879Z,1637182681.879 [Default:A.Wait] Stopped
2021-11-17T20:58:01.879Z,1637182681.879 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-11-17T20:58:02.281Z,1637182682.281 [Default:CheckIn] Running Loop=1
2021-11-17T20:58:02.281Z,1637182682.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-17T20:58:02.281Z,1637182682.281 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-17T20:58:02.660Z,1637182682.660 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-11-17T20:58:44.673Z,1637182724.673 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T20:58:44.673Z,1637182724.673 [DAT] Communications Fault, FailCount= 5
2021-11-17T20:58:44.674Z,1637182724.674 [DAT](ERROR): Communications Fault
2021-11-17T20:58:44.732Z,1637182724.732 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T20:58:45.077Z,1637182725.077 [DAT](INFO): Powering down
2021-11-17T20:58:45.904Z,1637182725.904 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T20:58:45.905Z,1637182725.905 [DAT] No Fault, FailCount= 5
2021-11-17T20:58:48.319Z,1637182728.319 [DAT](INFO): Powering up
2021-11-17T20:58:48.319Z,1637182728.319 [DAT](DEBUG): Initializing DAT.
2021-11-17T20:59:11.332Z,1637182751.332 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-11-17T20:59:29.956Z,1637182769.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-11-17T20:59:29.956Z,1637182769.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-17T20:59:29.972Z,1637182769.972 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-17T20:59:30.370Z,1637182770.370 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-17T20:59:30.370Z,1637182770.370 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-11-17T20:59:48.505Z,1637182788.505 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T20:59:48.505Z,1637182788.505 [DAT] Communications Fault, FailCount= 6
2021-11-17T20:59:48.505Z,1637182788.505 [DAT](ERROR): Communications Fault
2021-11-17T20:59:48.524Z,1637182788.524 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T20:59:48.911Z,1637182788.911 [DAT](INFO): Powering down
2021-11-17T20:59:49.759Z,1637182789.759 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T20:59:49.759Z,1637182789.759 [DAT] No Fault, FailCount= 6
2021-11-17T20:59:52.141Z,1637182792.141 [DAT](INFO): Powering up
2021-11-17T20:59:52.141Z,1637182792.141 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:00:52.410Z,1637182852.410 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:00:52.410Z,1637182852.410 [DAT] Communications Fault, FailCount= 7
2021-11-17T21:00:52.411Z,1637182852.411 [DAT](ERROR): Communications Fault
2021-11-17T21:00:52.429Z,1637182852.429 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:00:52.740Z,1637182852.740 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-11-17T21:00:52.744Z,1637182852.744 [BPC1](INFO): Received data from all battery sticks.
2021-11-17T21:00:52.747Z,1637182852.747 [DAT](INFO): Powering down
2021-11-17T21:00:53.580Z,1637182853.580 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T21:00:53.580Z,1637182853.580 [DAT] No Fault, FailCount= 7
2021-11-17T21:00:55.973Z,1637182855.973 [DAT](INFO): Powering up
2021-11-17T21:00:55.973Z,1637182855.973 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:01:56.169Z,1637182916.169 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:01:56.169Z,1637182916.169 [DAT] Communications Fault, FailCount= 8
2021-11-17T21:01:56.169Z,1637182916.169 [DAT](ERROR): Communications Fault
2021-11-17T21:01:56.225Z,1637182916.225 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:01:56.225Z,1637182916.225 [CBIT](FAULT): Communications Fault in component: DAT
2021-11-17T21:01:56.581Z,1637182916.581 [DAT](INFO): Powering down
2021-11-17T21:02:30.920Z,1637182950.920 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-11-17T21:02:30.921Z,1637182950.921 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-17T21:02:30.948Z,1637182950.948 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-17T21:02:31.334Z,1637182951.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-17T21:02:31.334Z,1637182951.334 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-11-17T21:03:02.450Z,1637182982.450 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-11-17T20:58:02.3Z
2021-11-17T21:03:02.450Z,1637182982.450 [Default:CheckIn:Read_GPS] Stopped
2021-11-17T21:03:02.450Z,1637182982.450 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-17T21:03:02.855Z,1637182982.855 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-11-17T21:03:33.526Z,1637183013.526 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2021-11-17T21:03:33.603Z,1637183013.603 [NAL9602](ERROR): received: 
+CSQ:0

OK
2021-11-17T21:04:10.984Z,1637183050.984 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-11-17T21:04:10.984Z,1637183050.984 [NAL9602] Data Fault, FailCount= 1
2021-11-17T21:04:10.984Z,1637183050.984 [NAL9602](ERROR): Data Fault
2021-11-17T21:04:11.039Z,1637183051.039 [CBIT](ERROR): Data Fault in component: NAL9602
2021-11-17T21:04:11.388Z,1637183051.388 [NAL9602](INFO): Powering down
2021-11-17T21:04:12.225Z,1637183052.225 [CBIT](INFO): Clearing failed state for component NAL9602
2021-11-17T21:04:12.226Z,1637183052.226 [NAL9602] No Fault, FailCount= 1
2021-11-17T21:04:30.781Z,1637183070.781 [Power24vConverter](INFO): Powering down.
2021-11-17T21:04:41.688Z,1637183081.688 [NAL9602](INFO): Powering up NAL9602
2021-11-17T21:04:52.601Z,1637183092.601 [NAL9602](INFO): NAL9602 initialized
2021-11-17T21:05:31.968Z,1637183131.968 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-11-17T21:05:31.969Z,1637183131.969 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-17T21:05:31.980Z,1637183131.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-17T21:05:32.379Z,1637183132.379 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-17T21:05:32.379Z,1637183132.379 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-11-17T21:06:56.271Z,1637183216.271 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T21:06:56.271Z,1637183216.271 [DAT] No Fault, FailCount= 8
2021-11-17T21:06:56.675Z,1637183216.675 [DAT](INFO): Powering up
2021-11-17T21:06:56.675Z,1637183216.675 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:06:57.067Z,1637183217.067 [Power24vConverter](INFO): Powering up.
2021-11-17T21:07:56.884Z,1637183276.884 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:07:56.884Z,1637183276.884 [DAT] Communications Fault, FailCount= 1
2021-11-17T21:07:56.884Z,1637183276.884 [DAT](ERROR): Communications Fault
2021-11-17T21:07:56.908Z,1637183276.908 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:07:57.275Z,1637183277.275 [DAT](INFO): Powering down
2021-11-17T21:07:58.254Z,1637183278.254 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T21:07:58.255Z,1637183278.255 [DAT] No Fault, FailCount= 1
2021-11-17T21:08:00.622Z,1637183280.622 [DAT](INFO): Powering up
2021-11-17T21:08:00.622Z,1637183280.622 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:08:30.112Z,1637183310.112 [CBIT](INFO): Clearing failed state for component DropWeight
2021-11-17T21:08:30.112Z,1637183310.112 [DropWeight] No Fault, FailCount= 1
2021-11-17T21:08:33.391Z,1637183313.391 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-11-17T21:08:33.392Z,1637183313.392 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-17T21:08:33.403Z,1637183313.403 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-17T21:08:34.273Z,1637183314.273 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-17T21:08:34.273Z,1637183314.273 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-11-17T21:09:00.820Z,1637183340.820 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:09:00.820Z,1637183340.820 [DAT] Communications Fault, FailCount= 2
2021-11-17T21:09:00.820Z,1637183340.820 [DAT](ERROR): Communications Fault
2021-11-17T21:09:00.837Z,1637183340.837 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:09:01.211Z,1637183341.211 [DAT](INFO): Powering down
2021-11-17T21:09:02.500Z,1637183342.500 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T21:09:02.500Z,1637183342.500 [DAT] No Fault, FailCount= 2
2021-11-17T21:09:04.572Z,1637183344.572 [DAT](INFO): Powering up
2021-11-17T21:09:04.572Z,1637183344.572 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:10:04.671Z,1637183404.671 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:10:04.672Z,1637183404.672 [DAT] Communications Fault, FailCount= 3
2021-11-17T21:10:04.672Z,1637183404.672 [DAT](ERROR): Communications Fault
2021-11-17T21:10:04.698Z,1637183404.698 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:10:05.077Z,1637183405.077 [DAT](INFO): Powering down
2021-11-17T21:10:06.031Z,1637183406.031 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T21:10:06.031Z,1637183406.031 [DAT] No Fault, FailCount= 3
2021-11-17T21:10:08.148Z,1637183408.148 [DAT](INFO): Powering up
2021-11-17T21:10:08.149Z,1637183408.149 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:10:44.974Z,1637183444.974 [CommandLine](IMPORTANT): got command failComponent
2021-11-17T21:10:44.974Z,1637183444.974 [CommandLine](IMPORTANT): Failed components:
2021-11-17T21:10:44.975Z,1637183444.975 [CommandLine](IMPORTANT): No failed Components.
2021-11-17T21:11:08.552Z,1637183468.552 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:11:08.552Z,1637183468.552 [DAT] Communications Fault, FailCount= 4
2021-11-17T21:11:08.552Z,1637183468.552 [DAT](ERROR): Communications Fault
2021-11-17T21:11:08.576Z,1637183468.576 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:11:08.937Z,1637183468.937 [DAT](INFO): Powering down
2021-11-17T21:11:09.811Z,1637183469.811 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T21:11:09.811Z,1637183469.811 [DAT] No Fault, FailCount= 4
2021-11-17T21:11:12.294Z,1637183472.294 [DAT](INFO): Powering up
2021-11-17T21:11:12.295Z,1637183472.295 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:11:20.689Z,1637183480.689 [CommandLine](IMPORTANT): got command get DataOverHttps.loadAtStartup
2021-11-17T21:11:20.690Z,1637183480.690 [CommandLine](IMPORTANT): DataOverHttps.loadAtStartup 0 bool
2021-11-17T21:11:23.957Z,1637183483.957 [CommandLine](IMPORTANT): got command failComponent
2021-11-17T21:11:23.957Z,1637183483.957 [CommandLine](IMPORTANT): Failed components:
2021-11-17T21:11:23.957Z,1637183483.957 [CommandLine](IMPORTANT): No failed Components.
2021-11-17T21:11:29.031Z,1637183489.031 [CommandLine](IMPORTANT): got command failComponent none DAT
2021-11-17T21:11:29.031Z,1637183489.031 [CommandLine](IMPORTANT): DAT failureMode is No Fault
2021-11-17T21:11:34.599Z,1637183494.599 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-11-17T21:11:34.600Z,1637183494.600 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-17T21:11:34.610Z,1637183494.610 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-17T21:11:35.460Z,1637183495.460 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-17T21:11:35.460Z,1637183495.460 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-11-17T21:11:39.133Z,1637183499.133 [CommandLine](IMPORTANT): got command failComponent 
2021-11-17T21:11:39.134Z,1637183499.134 [CommandLine](IMPORTANT): Failed components:
2021-11-17T21:11:39.134Z,1637183499.134 [CommandLine](IMPORTANT): No failed Components.
2021-11-17T21:11:41.728Z,1637183501.728 [CommandLine](IMPORTANT): got command failComponent hardware DropWeight
2021-11-17T21:11:41.728Z,1637183501.728 [DropWeight] Hardware Fault, FailCount= 1
2021-11-17T21:11:41.728Z,1637183501.728 [DropWeight](ERROR): Hardware Fault
2021-11-17T21:11:41.728Z,1637183501.728 [CommandLine](IMPORTANT): DropWeight failureMode is Hardware Fault
2021-11-17T21:11:41.804Z,1637183501.804 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-11-17T21:11:41.805Z,1637183501.805 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-11-17T21:11:42.117Z,1637183502.117 [CBIT](INFO): Critical error at 20211117T211141
2021-11-17T21:12:12.476Z,1637183532.476 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:12:12.476Z,1637183532.476 [DAT] Communications Fault, FailCount= 5
2021-11-17T21:12:12.476Z,1637183532.476 [DAT](ERROR): Communications Fault
2021-11-17T21:12:12.516Z,1637183532.516 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:12:12.865Z,1637183532.865 [DAT](INFO): Powering down
2021-11-17T21:12:13.738Z,1637183533.738 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T21:12:13.738Z,1637183533.738 [DAT] No Fault, FailCount= 5
2021-11-17T21:12:16.199Z,1637183536.199 [DAT](INFO): Powering up
2021-11-17T21:12:16.199Z,1637183536.199 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:13:16.452Z,1637183596.452 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:13:16.453Z,1637183596.453 [DAT] Communications Fault, FailCount= 6
2021-11-17T21:13:16.453Z,1637183596.453 [DAT](ERROR): Communications Fault
2021-11-17T21:13:16.500Z,1637183596.500 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:13:16.867Z,1637183596.867 [DAT](INFO): Powering down
2021-11-17T21:13:17.669Z,1637183597.669 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T21:13:17.669Z,1637183597.669 [DAT] No Fault, FailCount= 6
2021-11-17T21:13:20.168Z,1637183600.168 [DAT](INFO): Powering up
2021-11-17T21:13:20.168Z,1637183600.168 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:14:20.392Z,1637183660.392 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:14:20.392Z,1637183660.392 [DAT] Communications Fault, FailCount= 7
2021-11-17T21:14:20.392Z,1637183660.392 [DAT](ERROR): Communications Fault
2021-11-17T21:14:20.439Z,1637183660.439 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:14:20.799Z,1637183660.799 [DAT](INFO): Powering down
2021-11-17T21:14:21.617Z,1637183661.617 [CBIT](INFO): Clearing failed state for component DAT
2021-11-17T21:14:21.617Z,1637183661.617 [DAT] No Fault, FailCount= 7
2021-11-17T21:14:24.135Z,1637183664.135 [DAT](INFO): Powering up
2021-11-17T21:14:24.136Z,1637183664.136 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:14:36.175Z,1637183676.175 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-11-17T21:14:36.175Z,1637183676.175 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-17T21:14:36.186Z,1637183676.186 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-17T21:14:36.285Z,1637183676.285 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-17T21:14:36.286Z,1637183676.286 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-11-17T21:14:56.540Z,1637183696.540 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-11-17T21:14:56.540Z,1637183696.540 [NAL9602] Data Fault, FailCount= 2
2021-11-17T21:14:56.541Z,1637183696.541 [NAL9602](ERROR): Data Fault
2021-11-17T21:14:56.560Z,1637183696.560 [CBIT](ERROR): Data Fault in component: NAL9602
2021-11-17T21:14:56.946Z,1637183696.946 [NAL9602](INFO): Powering down
2021-11-17T21:14:57.770Z,1637183697.770 [CBIT](INFO): Clearing failed state for component NAL9602
2021-11-17T21:14:57.787Z,1637183697.787 [NAL9602] No Fault, FailCount= 2
2021-11-17T21:15:24.417Z,1637183724.417 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:15:24.417Z,1637183724.417 [DAT] Communications Fault, FailCount= 8
2021-11-17T21:15:24.417Z,1637183724.417 [DAT](ERROR): Communications Fault
2021-11-17T21:15:24.459Z,1637183724.459 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:15:24.460Z,1637183724.460 [CBIT](FAULT): Communications Fault in component: DAT
2021-11-17T21:15:24.816Z,1637183724.816 [DAT](INFO): Powering down
2021-11-17T21:15:27.244Z,1637183727.244 [NAL9602](INFO): Powering up NAL9602
2021-11-17T21:15:38.152Z,1637183738.152 [NAL9602](INFO): NAL9602 initialized
2021-11-17T21:16:30.117Z,1637183790.117 [CommandLine](IMPORTANT): got command 
2021-11-17T21:16:30.117Z,1637183790.117 [CommandLine](FAULT): Incomplete syntax. Try: help
2021-11-17T21:16:31.610Z,1637183791.610 [CommandLine](IMPORTANT): got command failComponent
2021-11-17T21:16:31.610Z,1637183791.610 [CommandLine](IMPORTANT): Failed components:
2021-11-17T21:16:31.610Z,1637183791.610 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2021-11-17T21:16:31.611Z,1637183791.611 [CommandLine](IMPORTANT): DAT: Communications Fault
2021-11-17T21:16:34.493Z,1637183794.493 [CommandLine](IMPORTANT): got command failComponent none DAT
2021-11-17T21:16:34.494Z,1637183794.494 [DAT] No Fault, FailCount= 8
2021-11-17T21:16:34.494Z,1637183794.494 [CommandLine](IMPORTANT): DAT failureMode is No Fault
2021-11-17T21:16:34.816Z,1637183794.816 [DAT](INFO): Powering up
2021-11-17T21:16:34.816Z,1637183794.816 [DAT](DEBUG): Initializing DAT.
2021-11-17T21:16:54.456Z,1637183814.456 [CommandLine](IMPORTANT): got command get DAT.baud
2021-11-17T21:16:54.456Z,1637183814.456 [CommandLine](IMPORTANT): DAT.baud 115200.000000 bps
2021-11-17T21:17:25.943Z,1637183845.943 [CommandLine](IMPORTANT): got command configSet DAT.baud 9600.000000 bit_per_second persist
2021-11-17T21:17:25.943Z,1637183845.943 [CommandLine](IMPORTANT): configSet DAT.baud requires a restart to take effect.
2021-11-17T21:17:35.632Z,1637183855.632 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2021-11-17T21:17:35.632Z,1637183855.632 [DAT] Communications Fault, FailCount= 9
2021-11-17T21:17:35.632Z,1637183855.632 [DAT](ERROR): Communications Fault
2021-11-17T21:17:35.650Z,1637183855.650 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-17T21:17:35.651Z,1637183855.651 [CBIT](FAULT): Communications Fault in component: DAT
2021-11-17T21:17:35.718Z,1637183855.718 [DAT](INFO): Powering down
2021-11-17T21:17:36.909Z,1637183856.909 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-11-17T21:17:36.909Z,1637183856.909 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-11-17T21:17:36.924Z,1637183856.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-11-17T21:17:37.314Z,1637183857.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-11-17T21:17:37.314Z,1637183857.314 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-11-17T21:17:58.879Z,1637183878.879 [Power24vConverter](INFO): Powering down.
2021-11-17T21:18:08.599Z,1637183888.599 [CommandLine](IMPORTANT): got command restart application
2021-11-17T21:18:09.607Z,1637183889.607 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-17T21:18:09.607Z,1637183889.607 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:09.795Z,1637183889.795 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-11-17T21:18:09.795Z,1637183889.795 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:09.795Z,1637183889.795 [CommandLine](INFO): Join timeout helper Thread ID is 1235
2021-11-17T21:18:09.799Z,1637183889.799 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-11-17T21:18:09.799Z,1637183889.799 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:09.799Z,1637183889.799 [NavChartDb](INFO): Join timeout helper Thread ID is 1236
2021-11-17T21:18:10.079Z,1637183890.079 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-17T21:18:10.079Z,1637183890.079 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:10.083Z,1637183890.083 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-11-17T21:18:10.083Z,1637183890.083 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:10.083Z,1637183890.083 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1237
2021-11-17T21:18:10.095Z,1637183890.095 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-17T21:18:10.095Z,1637183890.095 [WetLabsBB2FL](INFO): Powering down
2021-11-17T21:18:10.096Z,1637183890.096 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:10.103Z,1637183890.103 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-11-17T21:18:10.103Z,1637183890.103 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:10.103Z,1637183890.103 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1238
2021-11-17T21:18:10.943Z,1637183890.943 [CTD_Seabird](INFO): Powering down
2021-11-17T21:18:10.955Z,1637183890.955 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-17T21:18:10.955Z,1637183890.955 [CTD_Seabird](INFO): Powering down
2021-11-17T21:18:10.967Z,1637183890.967 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:10.967Z,1637183890.967 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-11-17T21:18:10.967Z,1637183890.967 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:10.968Z,1637183890.968 [Radio_Surface](INFO): Join timeout helper Thread ID is 1239
2021-11-17T21:18:11.167Z,1637183891.167 [Radio_Surface](INFO): Powering down
2021-11-17T21:18:11.168Z,1637183891.168 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-17T21:18:11.168Z,1637183891.168 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:11.193Z,1637183891.193 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-11-17T21:18:11.193Z,1637183891.193 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:11.195Z,1637183891.195 [Onboard](INFO): Join timeout helper Thread ID is 1240
2021-11-17T21:18:15.011Z,1637183895.011 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-17T21:18:15.011Z,1637183895.011 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.031Z,1637183895.031 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-11-17T21:18:15.031Z,1637183895.031 [logger ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.032Z,1637183895.032 [logger](INFO): Join timeout helper Thread ID is 1241
2021-11-17T21:18:15.051Z,1637183895.051 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-17T21:18:15.051Z,1637183895.051 [logger ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.071Z,1637183895.071 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-11-17T21:18:15.071Z,1637183895.071 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.072Z,1637183895.072 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-11-17T21:18:15.072Z,1637183895.072 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.072Z,1637183895.072 [controlThread](INFO): Join timeout helper Thread ID is 1242
2021-11-17T21:18:15.323Z,1637183895.323 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-17T21:18:15.323Z,1637183895.323 [controlThread](DEBUG): Uninitializing ControlThread
2021-11-17T21:18:15.324Z,1637183895.324 [AHRS_M2](INFO): Powering down
2021-11-17T21:18:15.396Z,1637183895.396 [NAL9602](INFO): Powering down
2021-11-17T21:18:15.397Z,1637183895.397 [DAT](INFO): Powering down
2021-11-17T21:18:15.399Z,1637183895.399 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-11-17T21:18:15.400Z,1637183895.400 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-11-17T21:18:15.401Z,1637183895.401 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-11-17T21:18:15.401Z,1637183895.401 [MissionManager](INFO): Uninitializing Mission Default
2021-11-17T21:18:15.401Z,1637183895.401 [Default] Stopped
2021-11-17T21:18:15.402Z,1637183895.402 [Default](DEBUG): Aggregate::uninitialize Default
2021-11-17T21:18:15.402Z,1637183895.402 [Default:B.GoToSurface] Stopped
2021-11-17T21:18:15.402Z,1637183895.402 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-17T21:18:15.402Z,1637183895.402 [Default:CheckIn] Stopped
2021-11-17T21:18:15.402Z,1637183895.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-17T21:18:15.402Z,1637183895.402 [Default:CheckIn:Read_Iridium] Stopped
2021-11-17T21:18:15.406Z,1637183895.406 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-11-17T21:18:15.406Z,1637183895.406 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-11-17T21:18:15.406Z,1637183895.406 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-11-17T21:18:15.407Z,1637183895.407 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-11-17T21:18:15.407Z,1637183895.407 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-11-17T21:18:15.407Z,1637183895.407 [BuoyancyServo](INFO): Powering down
2021-11-17T21:18:15.419Z,1637183895.419 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-11-17T21:18:15.419Z,1637183895.419 [ElevatorServo](INFO): Powering down
2021-11-17T21:18:15.420Z,1637183895.420 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-11-17T21:18:15.420Z,1637183895.420 [MassServo](INFO): Powering down
2021-11-17T21:18:15.421Z,1637183895.421 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-11-17T21:18:15.421Z,1637183895.421 [RudderServo](INFO): Powering down
2021-11-17T21:18:15.422Z,1637183895.422 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-11-17T21:18:15.422Z,1637183895.422 [ThrusterServo](INFO): Powering down
2021-11-17T21:18:15.423Z,1637183895.423 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-11-17T21:18:15.423Z,1637183895.423 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-11-17T21:18:15.424Z,1637183895.424 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-11-17T21:18:15.424Z,1637183895.424 [CBIT](DEBUG): Powering off loads.
2021-11-17T21:18:15.435Z,1637183895.435 [CBIT](DEBUG): Disabling WDT.
2021-11-17T21:18:15.447Z,1637183895.447 [CBIT](DEBUG): Opening all GF detection circuits.
2021-11-17T21:18:15.447Z,1637183895.447 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.491Z,1637183895.491 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.494Z,1637183895.494 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.565Z,1637183895.565 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.569Z,1637183895.569 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.609Z,1637183895.609 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-17T21:18:15.674Z,1637183895.674 [logger ThreadHandler](INFO): Thread cancelled.