2022-04-01T21:49:42.690Z,1648849782.690 [Supervisor](DEBUG): Initializing supervisor.
2022-04-01T21:49:42.695Z,1648849782.695 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-04-01T21:49:42.695Z,1648849782.695 [SyncHandler](INFO): Protected caller Thread ID is 828
2022-04-01T21:49:42.696Z,1648849782.696 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-04-01T21:49:42.697Z,1648849782.697 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-04-01T21:49:42.697Z,1648849782.697 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 829
2022-04-01T21:49:42.701Z,1648849782.701 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-04-01T21:49:42.721Z,1648849782.721 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-04-01T21:49:42.722Z,1648849782.722 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-04-01T21:49:42.722Z,1648849782.722 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 830
2022-04-01T21:49:42.726Z,1648849782.726 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-04-01T21:49:42.727Z,1648849782.727 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-04-01T21:49:42.728Z,1648849782.728 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 831
2022-04-01T21:49:42.730Z,1648849782.730 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-04-01T21:49:42.731Z,1648849782.731 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-04-01T21:49:42.731Z,1648849782.731 [logger ThreadHandler](INFO): Protected caller Thread ID is 832
2022-04-01T21:49:42.735Z,1648849782.735 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-04-01T21:49:42.735Z,1648849782.735 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-04-01T21:49:42.739Z,1648849782.739 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-04-01T21:49:43.100Z,1648849783.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-04-01T21:49:43.101Z,1648849783.101 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-04-01T21:49:43.196Z,1648849783.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-04-01T21:49:43.199Z,1648849783.199 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-04-01T21:49:43.531Z,1648849783.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-04-01T21:49:43.533Z,1648849783.533 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-04-01T21:49:43.982Z,1648849783.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-04-01T21:49:43.984Z,1648849783.984 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-04-01T21:49:44.204Z,1648849784.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-04-01T21:49:44.204Z,1648849784.204 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-04-01T21:49:44.898Z,1648849784.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-04-01T21:49:44.901Z,1648849784.901 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-04-01T21:49:45.183Z,1648849785.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-04-01T21:49:45.185Z,1648849785.185 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-04-01T21:49:45.789Z,1648849785.789 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-04-01T21:49:45.990Z,1648849785.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-04-01T21:49:45.992Z,1648849785.992 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-04-01T21:49:46.590Z,1648849786.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-04-01T21:49:46.591Z,1648849786.591 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-04-01T21:49:46.821Z,1648849786.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-04-01T21:49:46.822Z,1648849786.822 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-04-01T21:49:46.902Z,1648849786.902 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-04-01T21:49:47.120Z,1648849787.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-04-01T21:49:47.123Z,1648849787.123 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-04-01T21:49:47.494Z,1648849787.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-04-01T21:49:47.497Z,1648849787.497 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-04-01T21:49:47.627Z,1648849787.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-04-01T21:49:47.629Z,1648849787.629 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-04-01T21:49:47.742Z,1648849787.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-04-01T21:49:47.743Z,1648849787.743 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-tethys/
2022-04-01T21:49:47.746Z,1648849787.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Battery.cfg
2022-04-01T21:49:48.016Z,1648849788.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-04-01T21:49:48.018Z,1648849788.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Servo.cfg
2022-04-01T21:49:48.126Z,1648849788.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/secure.cfg
2022-04-01T21:49:48.210Z,1648849788.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Control.cfg
2022-04-01T21:49:48.306Z,1648849788.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/vehicle.cfg
2022-04-01T21:49:48.504Z,1648849788.504 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Sensor.cfg
2022-04-01T21:49:48.657Z,1648849788.657 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Navigation.cfg
2022-04-01T21:49:48.751Z,1648849788.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/logger.cfg
2022-04-01T21:49:48.834Z,1648849788.834 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Simulator.cfg
2022-04-01T21:49:48.946Z,1648849788.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Dock.cfg
2022-04-01T21:49:49.132Z,1648849789.132 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/BIT.cfg
2022-04-01T21:49:49.229Z,1648849789.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Science.cfg
2022-04-01T21:49:49.405Z,1648849789.405 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/workSite.cfg
2022-04-01T21:49:49.499Z,1648849789.499 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2022-04-01T21:49:49.505Z,1648849789.505 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-04-01T21:49:49.649Z,1648849789.649 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-04-01T21:49:49.649Z,1648849789.649 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-04-01T21:49:49.663Z,1648849789.663 [NavChart] Loaded
2022-04-01T21:49:49.663Z,1648849789.663 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-04-01T21:49:49.668Z,1648849789.668 [UniversalFixResidualReporter] Loaded
2022-04-01T21:49:49.669Z,1648849789.669 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-04-01T21:49:49.669Z,1648849789.669 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-04-01T21:49:49.670Z,1648849789.670 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-04-01T21:49:49.862Z,1648849789.862 [WetLabsSeaOWL_UV_A] Loaded
2022-04-01T21:49:49.862Z,1648849789.862 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2022-04-01T21:49:49.864Z,1648849789.864 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 405D74E0
2022-04-01T21:49:49.864Z,1648849789.864 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 920
2022-04-01T21:49:49.865Z,1648849789.865 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-04-01T21:49:49.865Z,1648849789.865 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-04-01T21:49:49.882Z,1648849789.882 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-04-01T21:49:49.882Z,1648849789.882 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-04-01T21:49:50.616Z,1648849790.616 [AHRS_M2] Loaded
2022-04-01T21:49:50.616Z,1648849790.616 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-04-01T21:49:50.869Z,1648849790.869 [BackseatComponent] Loaded
2022-04-01T21:49:50.869Z,1648849790.869 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-04-01T21:49:50.870Z,1648849790.870 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B54E0
2022-04-01T21:49:50.871Z,1648849790.871 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2022-04-01T21:49:50.873Z,1648849790.873 [LcmUniversalReporter] Loaded
2022-04-01T21:49:50.874Z,1648849790.874 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-04-01T21:49:51.715Z,1648849791.715 [BPC1] Loaded
2022-04-01T21:49:51.715Z,1648849791.715 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-04-01T21:49:51.857Z,1648849791.857 [DataOverHttps] Loaded
2022-04-01T21:49:51.857Z,1648849791.857 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-04-01T21:49:51.858Z,1648849791.858 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408E54E0
2022-04-01T21:49:51.859Z,1648849791.859 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2022-04-01T21:49:51.879Z,1648849791.879 [Depth_Keller] Loaded
2022-04-01T21:49:51.879Z,1648849791.879 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-04-01T21:49:51.884Z,1648849791.884 [DropWeight] Loaded
2022-04-01T21:49:51.884Z,1648849791.884 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-04-01T21:49:51.942Z,1648849791.942 [NAL9602] Loaded
2022-04-01T21:49:51.942Z,1648849791.942 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-04-01T21:49:51.956Z,1648849791.956 [NanoDVR] Loaded
2022-04-01T21:49:51.956Z,1648849791.956 [ComponentRegistry](DEBUG): SyncComponent "NanoDVR" handled in the control thread.
2022-04-01T21:49:51.985Z,1648849791.985 [Onboard] Loaded
2022-04-01T21:49:51.985Z,1648849791.985 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-04-01T21:49:51.986Z,1648849791.986 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409154E0
2022-04-01T21:49:51.986Z,1648849791.986 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2022-04-01T21:49:51.992Z,1648849791.992 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2022-04-01T21:49:52.003Z,1648849792.003 [PowerOnly] Loaded
2022-04-01T21:49:52.003Z,1648849792.003 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-04-01T21:49:52.015Z,1648849792.015 [Power24vConverter] Loaded
2022-04-01T21:49:52.015Z,1648849792.015 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-04-01T21:49:52.029Z,1648849792.029 [Radio_Surface] Loaded
2022-04-01T21:49:52.029Z,1648849792.029 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-04-01T21:49:52.030Z,1648849792.030 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409454E0
2022-04-01T21:49:52.031Z,1648849792.031 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2022-04-01T21:49:52.072Z,1648849792.072 [RDI_Pathfinder] Loaded
2022-04-01T21:49:52.073Z,1648849792.073 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2022-04-01T21:49:52.156Z,1648849792.156 [DAT] Loaded
2022-04-01T21:49:52.156Z,1648849792.156 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-04-01T21:49:52.157Z,1648849792.157 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409754E0
2022-04-01T21:49:52.158Z,1648849792.158 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925
2022-04-01T21:49:52.158Z,1648849792.158 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-04-01T21:49:52.159Z,1648849792.159 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-04-01T21:49:52.339Z,1648849792.339 [InternalSim] Loaded
2022-04-01T21:49:52.339Z,1648849792.339 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2022-04-01T21:49:52.340Z,1648849792.340 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-04-01T21:49:52.342Z,1648849792.342 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-04-01T21:49:52.410Z,1648849792.410 [VerticalControl](DEBUG): Construct VerticalControl.
2022-04-01T21:49:52.470Z,1648849792.470 [VerticalControl] Loaded
2022-04-01T21:49:52.470Z,1648849792.470 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-04-01T21:49:52.473Z,1648849792.473 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-04-01T21:49:52.513Z,1648849792.513 [HorizontalControl] Loaded
2022-04-01T21:49:52.514Z,1648849792.514 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-04-01T21:49:52.516Z,1648849792.516 [SpeedControl](DEBUG): Construct SpeedControl.
2022-04-01T21:49:52.518Z,1648849792.518 [SpeedControl] Loaded
2022-04-01T21:49:52.519Z,1648849792.519 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-04-01T21:49:52.521Z,1648849792.521 [LoopControl](DEBUG): Construct LoopControl.
2022-04-01T21:49:52.522Z,1648849792.522 [LoopControl] Loaded
2022-04-01T21:49:52.522Z,1648849792.522 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-04-01T21:49:52.523Z,1648849792.523 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-04-01T21:49:52.523Z,1648849792.523 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-04-01T21:49:52.567Z,1648849792.567 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-04-01T21:49:52.569Z,1648849792.569 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-04-01T21:49:52.940Z,1648849792.940 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-04-01T21:49:52.941Z,1648849792.941 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-04-01T21:49:53.010Z,1648849793.010 [DepthRateCalculator] Loaded
2022-04-01T21:49:53.010Z,1648849793.010 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-04-01T21:49:53.015Z,1648849793.015 [PitchRateCalculator] Loaded
2022-04-01T21:49:53.016Z,1648849793.016 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-04-01T21:49:53.025Z,1648849793.025 [SpeedCalculator] Loaded
2022-04-01T21:49:53.025Z,1648849793.025 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-04-01T21:49:53.029Z,1648849793.029 [YawRateCalculator] Loaded
2022-04-01T21:49:53.030Z,1648849793.030 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-04-01T21:49:53.048Z,1648849793.048 [ElevatorOffsetCalculator] Loaded
2022-04-01T21:49:53.049Z,1648849793.049 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-04-01T21:49:53.049Z,1648849793.049 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-04-01T21:49:53.050Z,1648849793.050 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-04-01T21:49:53.203Z,1648849793.203 [BuoyancyServo] Loaded
2022-04-01T21:49:53.203Z,1648849793.203 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-04-01T21:49:53.224Z,1648849793.224 [ElevatorServo] Loaded
2022-04-01T21:49:53.224Z,1648849793.224 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-04-01T21:49:53.252Z,1648849793.252 [DockingServo] Loaded
2022-04-01T21:49:53.252Z,1648849793.252 [ComponentRegistry](DEBUG): SyncComponent "DockingServo" handled in the control thread.
2022-04-01T21:49:53.272Z,1648849793.272 [MassServo] Loaded
2022-04-01T21:49:53.272Z,1648849793.272 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-04-01T21:49:53.291Z,1648849793.291 [RudderServo] Loaded
2022-04-01T21:49:53.291Z,1648849793.291 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-04-01T21:49:53.306Z,1648849793.306 [ThrusterHE] Loaded
2022-04-01T21:49:53.306Z,1648849793.306 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-04-01T21:49:53.307Z,1648849793.307 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-04-01T21:49:53.309Z,1648849793.309 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-04-01T21:49:53.479Z,1648849793.479 [SBIT](DEBUG): Construct Startup Built In Test.
2022-04-01T21:49:53.488Z,1648849793.488 [SBIT] Loaded
2022-04-01T21:49:53.488Z,1648849793.488 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-04-01T21:49:53.491Z,1648849793.491 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-04-01T21:49:53.506Z,1648849793.506 [IBIT] Loaded
2022-04-01T21:49:53.506Z,1648849793.506 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-04-01T21:49:53.512Z,1648849793.512 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-04-01T21:49:53.619Z,1648849793.619 [CBIT] Loaded
2022-04-01T21:49:53.620Z,1648849793.620 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-04-01T21:49:53.620Z,1648849793.620 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-04-01T21:49:53.622Z,1648849793.622 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-04-01T21:49:53.744Z,1648849793.744 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-04-01T21:49:53.744Z,1648849793.744 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-04-01T21:49:53.830Z,1648849793.830 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-04-01T21:49:53.836Z,1648849793.836 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-04-01T21:49:53.839Z,1648849793.839 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-04-01T21:49:53.851Z,1648849793.851 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-04-01T21:49:53.852Z,1648849793.852 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA54E0
2022-04-01T21:49:53.852Z,1648849793.852 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2022-04-01T21:49:53.857Z,1648849793.857 [Supervisor](INFO): Main Thread ID is 823
2022-04-01T21:49:53.857Z,1648849793.857 [Supervisor](DEBUG): Running supervisor.
2022-04-01T21:49:53.858Z,1648849793.858 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927
2022-04-01T21:49:53.858Z,1648849793.858 [CommandExec](INFO): Initializing the command executive.
2022-04-01T21:49:53.859Z,1648849793.859 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928
2022-04-01T21:49:53.862Z,1648849793.862 [controlThread ThreadHandler](INFO): Handler Thread ID is 929
2022-04-01T21:49:53.862Z,1648849793.862 [controlThread](DEBUG): Initializing ControlThread
2022-04-01T21:49:53.863Z,1648849793.863 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-04-01T21:49:53.864Z,1648849793.864 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-04-01T21:49:53.871Z,1648849793.871 [InternalSim](DEBUG):  InternalSim initializing...
2022-04-01T21:49:53.878Z,1648849793.878 [InternalSim](DEBUG): oceanModelVarCount=0
2022-04-01T21:49:53.893Z,1648849793.893 [logger ThreadHandler](INFO): Handler Thread ID is 930
2022-04-01T21:49:53.921Z,1648849793.921 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 931
2022-04-01T21:49:54.005Z,1648849794.005 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 932
2022-04-01T21:49:54.017Z,1648849794.017 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-04-01T21:49:54.018Z,1648849794.018 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-04-01T21:49:54.019Z,1648849794.019 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-04-01T21:49:54.020Z,1648849794.020 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-04-01T21:49:54.020Z,1648849794.020 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-04-01T21:49:54.020Z,1648849794.020 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-04-01T21:49:54.021Z,1648849794.021 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-04-01T21:49:54.021Z,1648849794.021 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-04-01T21:49:54.021Z,1648849794.021 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-04-01T21:49:54.023Z,1648849794.023 [SBIT](INFO): Initialize SBIT Component.
2022-04-01T21:49:54.024Z,1648849794.024 [SBIT](IMPORTANT): git: 2022-03-09-34-gb64104af4
2022-04-01T21:49:54.024Z,1648849794.024 [SBIT](INFO): git hash: b64104af46f004601013dadfedb36c8ada1c55f6
2022-04-01T21:49:54.024Z,1648849794.024 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-04-01T21:49:54.026Z,1648849794.026 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 933
2022-04-01T21:49:54.026Z,1648849794.026 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-04-01T21:49:54.028Z,1648849794.028 [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
2022-04-01T21:49:54.030Z,1648849794.030 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2022-04-01T21:49:54.037Z,1648849794.037 [IBIT](INFO): Initialize IBIT Component.
2022-04-01T21:49:54.038Z,1648849794.038 [CBIT](DEBUG): Initialize CBIT Component.
2022-04-01T21:49:54.039Z,1648849794.039 [Onboard ThreadHandler](INFO): Handler Thread ID is 934
2022-04-01T21:49:54.049Z,1648849794.049 [CBIT](DEBUG): Initialized mux pins.
2022-04-01T21:49:54.050Z,1648849794.050 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2022-04-01T21:49:54.050Z,1648849794.050 [CBIT](DEBUG): Initializing the watchdog timer.
2022-04-01T21:49:54.062Z,1648849794.062 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 935
2022-04-01T21:49:54.073Z,1648849794.073 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-04-01T21:49:54.074Z,1648849794.074 [CBIT](DEBUG): Initializing heartbeat.
2022-04-01T21:49:54.085Z,1648849794.085 [DAT ThreadHandler](INFO): Handler Thread ID is 936
2022-04-01T21:49:54.086Z,1648849794.086 [DAT](INFO): Powering up
2022-04-01T21:49:54.086Z,1648849794.086 [DAT](DEBUG): Initializing DAT.
2022-04-01T21:49:54.093Z,1648849794.093 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 938
2022-04-01T21:49:54.103Z,1648849794.103 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-04-01T21:49:54.103Z,1648849794.103 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-04-01T21:49:54.104Z,1648849794.104 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-04-01T21:49:54.104Z,1648849794.104 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-04-01T21:49:54.104Z,1648849794.104 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-04-01T21:49:54.104Z,1648849794.104 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-04-01T21:49:54.104Z,1648849794.104 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-04-01T21:49:54.104Z,1648849794.104 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-04-01T21:49:54.105Z,1648849794.105 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-04-01T21:49:54.105Z,1648849794.105 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-04-01T21:49:54.105Z,1648849794.105 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-04-01T21:49:54.105Z,1648849794.105 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-04-01T21:49:54.105Z,1648849794.105 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-04-01T21:49:54.106Z,1648849794.106 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-04-01T21:49:54.106Z,1648849794.106 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-04-01T21:49:54.106Z,1648849794.106 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-04-01T21:49:54.145Z,1648849794.145 [CBIT](DEBUG): Deactivating GF circuits.
2022-04-01T21:49:54.145Z,1648849794.145 [CBIT](DEBUG): Deactivating emergency mode.
2022-04-01T21:49:54.181Z,1648849794.181 [CBIT](DEBUG): Backplane powered.
2022-04-01T21:49:54.182Z,1648849794.182 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-04-01T21:49:54.208Z,1648849794.208 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-01T21:49:54.236Z,1648849794.236 [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>


2022-04-01T21:49:54.237Z,1648849794.237 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.xml
2022-04-01T21:49:54.395Z,1648849794.395 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2022-04-01T21:49:54.399Z,1648849794.399 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2022-04-01T21:49:54.417Z,1648849794.417 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count
2022-04-01T21:49:54.420Z,1648849794.420 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s
2022-04-01T21:49:54.440Z,1648849794.440 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2022-04-01T21:49:54.451Z,1648849794.451 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachModeDDM = 2.000000 count
2022-04-01T21:49:54.453Z,1648849794.453 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2022-04-01T21:49:54.469Z,1648849794.469 [Radio_Surface](INFO): Powering up
2022-04-01T21:49:54.599Z,1648849794.599 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2022-04-01T21:49:54.599Z,1648849794.599 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2022-04-01T21:49:54.633Z,1648849794.633 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-01T21:49:54.669Z,1648849794.669 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-04-01T21:49:54.671Z,1648849794.671 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-04-01T21:49:54.688Z,1648849794.688 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2022-04-01T21:49:54.705Z,1648849794.705 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Control="Tethys/Control"
         xmlns:Dock="Tethys/Dock"
         xmlns:Estimation="Tethys/Estimation"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Sensor="Tethys/Sensor"
         xmlns:Units="Tethys/Units"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Dock http://okeanids.mbari.org/tethys/Xml/Dock.xsd
                             Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="DefaultWithUndock">

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

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to detach form the dock.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Dock transponder address.
    </Description><Dock:Dock.transponderCode/></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        Time duration between acoustic range queries while the mission is
        detaching from the dock.
    </Description><Units:second/><Value>2.5</Value></DefineArg>

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="DetachModeDDM"><Description>
        DDM DETACH mode enumeration (whiskers retracted, latch open).
    </Description><Units:count/><Value>2</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>30</Value></Setting>
    </Guidance:Wait>

    <Aggregate Id="LeaveDock">

        <Sequence/>

        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out while trying to detach from dock at current depth of <Universal:depth/><Units:meter/>.</Syslog>
        </Timeout>

<!-- Acoustic tracking directives -->

        <Estimation:TrackAcousticContact>
            <Parallel/>
            <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
            <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
            <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
        </Estimation:TrackAcousticContact>

<!-- Leave the dock -->

        <Dock:Undock>
            <Sequence/>
        </Dock:Undock>

    </Aggregate>

    <Aggregate Id="SurfaceDefault">

        <Sequence/>

<!-- This mission assumes open water so from here on it's the same old default that's always used -->

        <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>

    </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>


2022-04-01T21:49:54.713Z,1648849794.713 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,NanoDVR,PowerOnly,Power24vConverter,RDI_Pathfinder,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-04-01T21:49:54.773Z,1648849794.773 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-04-01T21:49:54.931Z,1648849794.931 [NanoDVR](INFO): Powering down.
2022-04-01T21:49:54.933Z,1648849794.933 [Power24vConverter](INFO): Powering up.
2022-04-01T21:49:54.995Z,1648849794.995 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-04-01T21:49:55.001Z,1648849795.001 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-04-01T21:49:55.003Z,1648849795.003 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-04-01T21:49:55.009Z,1648849795.009 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-04-01T21:49:55.010Z,1648849795.010 [DockingServo](DEBUG): Initializing EZServoServo.
2022-04-01T21:49:55.017Z,1648849795.017 [DockingServo](DEBUG): Initializing DockingServo.
2022-04-01T21:49:55.018Z,1648849795.018 [MassServo](DEBUG): Initializing EZServoServo.
2022-04-01T21:49:55.026Z,1648849795.026 [MassServo](DEBUG): Initializing MassServo.
2022-04-01T21:49:55.026Z,1648849795.026 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-01T21:49:55.033Z,1648849795.033 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-01T21:49:55.034Z,1648849795.034 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-04-01T21:49:55.041Z,1648849795.041 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-04-01T21:49:55.861Z,1648849795.861 [DockingServo](ERROR): Docking arm initialization uart error serial timeout
2022-04-01T21:49:55.861Z,1648849795.861 [DockingServo](FAULT): Failed to initialize
2022-04-01T21:49:55.861Z,1648849795.861 [DockingServo] Communications Fault, FailCount= 1
2022-04-01T21:49:55.861Z,1648849795.861 [DockingServo](ERROR): Communications Fault
2022-04-01T21:49:55.909Z,1648849795.909 [CBIT](ERROR): Communications Fault in component: DockingServo
2022-04-01T21:49:56.085Z,1648849796.085 [DockingServo](DEBUG): Uninitialize Docking Servo.
2022-04-01T21:49:56.085Z,1648849796.085 [DockingServo](INFO): Powering down
2022-04-01T21:49:56.762Z,1648849796.762 [DockingServo](DEBUG): Initializing EZServoServo.
2022-04-01T21:49:56.882Z,1648849796.882 [DockingServo](DEBUG): Initializing DockingServo.
2022-04-01T21:49:56.886Z,1648849796.886 [CBIT](INFO): Clearing failed state for component DockingServo
2022-04-01T21:49:56.886Z,1648849796.886 [DockingServo] No Fault, FailCount= 1
2022-04-01T21:50:00.810Z,1648849800.810 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-04-01T21:50:09.459Z,1648849809.459 [DAT](INFO): commRate: 800
2022-04-01T21:50:11.525Z,1648849811.525 [DAT](INFO): entering command mode
2022-04-01T21:50:11.726Z,1648849811.726 [DAT](INFO): setting verbose to 3
2022-04-01T21:50:11.979Z,1648849811.979 [DAT](INFO): set verbose to 3
2022-04-01T21:50:11.979Z,1648849811.979 [DAT](INFO): setting DatVerbose to 27440
2022-04-01T21:50:12.230Z,1648849812.230 [DAT](INFO): set DatVerbose to 27440
2022-04-01T21:50:12.231Z,1648849812.231 [DAT](INFO): setting transmit power to 8
2022-04-01T21:50:12.482Z,1648849812.482 [DAT](INFO): set transmit power to 8
2022-04-01T21:50:12.483Z,1648849812.483 [DAT](INFO): setting local address to 6
2022-04-01T21:50:12.734Z,1648849812.734 [DAT](INFO): set local address to 6
2022-04-01T21:50:12.735Z,1648849812.735 [DAT](INFO): Setting time to: 21:50:12 And date to:4/1/2022
2022-04-01T21:50:12.986Z,1648849812.986 [DAT](INFO): Local DAT time set to Fri Apr 1, 2022  21:50:12  

2022-04-01T21:50:18.683Z,1648849818.683 [SBIT](IMPORTANT): Beginning Startup BIT
2022-04-01T21:50:18.692Z,1648849818.692 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-01T21:50:22.316Z,1648849822.316 [NAL9602](INFO): Powering up NAL9602
2022-04-01T21:50:29.635Z,1648849829.635 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004709
CHAN A1 (24V): -0.025925
CHAN A2 (12V): -0.004328
CHAN A3 (5V): -0.002574
CHAN B0 (3.3V): -0.000966
CHAN B1 (3.15aV): -0.001556
CHAN B2 (3.15bV): -0.000966
CHAN B3 (GND): -0.000659
OPEN: 0.005627
Full Scale: +/- 1 mA
2022-04-01T21:50:33.219Z,1648849833.219 [NAL9602](INFO): NAL9602 initialized
2022-04-01T21:50:49.788Z,1648849849.788 [NAL9602](INFO): SBD MO Status=0, MOMSN=12460, MT Status=0, MTMSN=0
2022-04-01T21:50:49.788Z,1648849849.788 [NAL9602](INFO): No messages in MT queue
2022-04-01T21:51:12.835Z,1648849872.835 [SBIT](IMPORTANT): SBIT PASSED
2022-04-01T21:51:12.835Z,1648849872.835 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2022-04-01T21:51:13.255Z,1648849873.255 [MissionManager](IMPORTANT): Started mission Startup
2022-04-01T21:51:13.255Z,1648849873.255 [Startup] Running Loop=1
2022-04-01T21:51:13.255Z,1648849873.255 [Startup](DEBUG): Aggregate::initialize Startup
2022-04-01T21:51:13.255Z,1648849873.255 [Startup:A.GoToSurface] Running Loop=1
2022-04-01T21:51:13.255Z,1648849873.255 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-01T21:51:13.256Z,1648849873.256 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-01T21:51:13.256Z,1648849873.256 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-01T21:51:13.258Z,1648849873.258 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-01T21:51:13.258Z,1648849873.258 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-01T21:51:13.258Z,1648849873.258 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-01T21:51:13.260Z,1648849873.260 [Startup:StartupSatComms] Running Loop=1
2022-04-01T21:51:13.260Z,1648849873.260 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-04-01T21:51:13.260Z,1648849873.260 [Startup:StartupSatComms:A] Running Loop=1
2022-04-01T21:51:13.630Z,1648849873.630 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-04-01T21:52:13.438Z,1648849933.438 [Startup:StartupSatComms:A](INFO): Timed out from 2022-04-01T21:51:13.3Z
2022-04-01T21:52:13.438Z,1648849933.438 [Startup:StartupSatComms:A] Stopped
2022-04-01T21:52:13.438Z,1648849933.438 [Startup:StartupSatComms:B] Running Loop=1
2022-04-01T21:52:13.831Z,1648849933.831 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-01T21:52:40.590Z,1648849960.589 [NAL9602](INFO): SBD MO Status=1, MOMSN=12461, MT Status=0, MTMSN=0
2022-04-01T21:52:40.801Z,1648849960.801 [NAL9602](INFO): Sent 18 bytes from file Logs/20220401T213136/Courier0010.lzma
2022-04-01T21:52:40.801Z,1648849960.801 [NAL9602](INFO): Packets left to send: 0
2022-04-01T21:52:54.260Z,1648849974.260 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-04-01T21:52:54.260Z,1648849974.260 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-01T21:52:54.270Z,1648849974.270 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-01T21:52:54.679Z,1648849974.679 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-01T21:52:54.679Z,1648849974.679 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-04-01T21:53:00.712Z,1648849980.712 [NAL9602](INFO): SBD MO Status=2, MOMSN=12462, MT Status=2, MTMSN=0
2022-04-01T21:53:00.713Z,1648849980.713 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-01T21:53:13.514Z,1648849993.514 [Startup:StartupSatComms:B](INFO): Timed out from 2022-04-01T21:52:13.4Z
2022-04-01T21:53:13.514Z,1648849993.514 [Startup:StartupSatComms:B] Stopped
2022-04-01T21:53:13.514Z,1648849993.514 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-04-01T21:53:13.514Z,1648849993.514 [Startup:StartupSatComms] Stopped
2022-04-01T21:53:13.514Z,1648849993.514 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-04-01T21:53:13.515Z,1648849993.515 [Startup](INFO): Completed Startup
2022-04-01T21:53:13.515Z,1648849993.515 [MissionManager](INFO): Startup is completed.
2022-04-01T21:53:13.515Z,1648849993.515 [MissionManager](INFO): Uninitializing Mission Startup
2022-04-01T21:53:13.515Z,1648849993.515 [Startup] Stopped
2022-04-01T21:53:13.515Z,1648849993.515 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-04-01T21:53:13.515Z,1648849993.515 [Startup:A.GoToSurface] Stopped
2022-04-01T21:53:13.515Z,1648849993.515 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-01T21:53:13.928Z,1648849993.928 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2022-04-01T21:53:13.928Z,1648849993.928 [DefaultWithUndock] Running Loop=1
2022-04-01T21:53:13.928Z,1648849993.928 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2022-04-01T21:53:13.928Z,1648849993.928 [DefaultWithUndock:A.Wait] Running Loop=1
2022-04-01T21:53:13.928Z,1648849993.928 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2022-04-01T21:53:20.383Z,1648850000.383 [NAL9602](INFO): SBD MO Status=1, MOMSN=12462, MT Status=0, MTMSN=0
2022-04-01T21:53:20.433Z,1648850000.433 [NAL9602](INFO): Sent 151 bytes from file Logs/20220401T214942/Courier0000.lzma
2022-04-01T21:53:20.433Z,1648850000.433 [NAL9602](INFO): Packets left to send: 0
2022-04-01T21:53:26.647Z,1648850006.647 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004378
2022-04-01T21:53:44.226Z,1648850024.226 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2022-04-01T21:53:44.226Z,1648850024.226 [DefaultWithUndock:A.Wait] Stopped
2022-04-01T21:53:44.226Z,1648850024.226 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2022-04-01T21:53:44.226Z,1648850024.226 [DefaultWithUndock:LeaveDock] Running Loop=1
2022-04-01T21:53:44.226Z,1648850024.226 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2022-04-01T21:53:44.226Z,1648850024.226 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2022-04-01T21:53:44.226Z,1648850024.226 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2022-04-01T21:53:44.227Z,1648850024.227 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2022-04-01T21:53:44.227Z,1648850024.227 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2022-04-01T21:53:44.227Z,1648850024.227 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2022-04-01T21:53:44.647Z,1648850024.647 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2022-04-01T21:53:45.049Z,1648850025.049 [PowerOnly](INFO): Powering up loadControl
2022-04-01T21:53:45.467Z,1648850025.467 [DockingServo](INFO): Changing to mode: 3
2022-04-01T21:53:47.094Z,1648850027.094 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:53:47.094Z,1648850027.094 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:53:47.302Z,1648850027.302 [DAT](INFO): setting remote address to 0
2022-04-01T21:53:47.555Z,1648850027.555 [DAT](INFO): set remote address to 0
2022-04-01T21:53:47.806Z,1648850027.806 [DAT](INFO): Ping request sent.
2022-04-01T21:53:49.889Z,1648850029.889 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:53:49.889Z,1648850029.889 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:53:51.208Z,1648850031.208 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock.
2022-04-01T21:53:51.610Z,1648850031.610 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2022-04-01T21:53:52.807Z,1648850032.807 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:53:52.807Z,1648850032.807 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:53:55.667Z,1648850035.667 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:53:55.667Z,1648850035.667 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:53:56.126Z,1648850036.126 [DAT](ERROR): No response from remote modem.
2022-04-01T21:53:58.142Z,1648850038.142 [DAT](INFO): Ping request sent.
2022-04-01T21:53:58.319Z,1648850038.319 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:53:58.320Z,1648850038.320 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:54:00.829Z,1648850040.829 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:54:00.830Z,1648850040.830 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:54:03.667Z,1648850043.667 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:54:03.667Z,1648850043.667 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:54:06.458Z,1648850046.458 [DAT](ERROR): No response from remote modem.
2022-04-01T21:54:06.515Z,1648850046.515 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:54:06.515Z,1648850046.515 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:54:06.710Z,1648850046.710 [DAT](INFO): Ping request sent.
2022-04-01T21:54:09.423Z,1648850049.423 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:54:09.423Z,1648850049.423 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:54:11.782Z,1648850051.782 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2022-04-01T21:54:12.195Z,1648850052.195 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:54:12.195Z,1648850052.195 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:54:12.223Z,1648850052.223 [DockingServo](INFO): Changing to mode: 1
2022-04-01T21:54:15.026Z,1648850055.026 [DAT](ERROR): No response from remote modem.
2022-04-01T21:54:15.053Z,1648850055.053 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:54:15.053Z,1648850055.053 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:54:15.278Z,1648850055.278 [DAT](INFO): Ping request sent.
2022-04-01T21:54:17.847Z,1648850057.847 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2022-04-01T21:54:17.848Z,1648850057.848 [DefaultWithUndock:LeaveDock:A.](INFO): update period (2.500000 s) has elapsed, querying...
2022-04-01T21:54:17.848Z,1648850057.848 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2022-04-01T21:54:18.239Z,1648850058.239 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2022-04-01T21:54:18.239Z,1648850058.239 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2022-04-01T21:54:18.239Z,1648850058.239 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2022-04-01T21:54:18.239Z,1648850058.239 [DefaultWithUndock:LeaveDock] Stopped
2022-04-01T21:54:18.239Z,1648850058.239 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2022-04-01T21:54:18.239Z,1648850058.239 [DefaultWithUndock:LeaveDock:A.] Stopped
2022-04-01T21:54:18.239Z,1648850058.239 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2022-04-01T21:54:18.240Z,1648850058.240 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2022-04-01T21:54:18.240Z,1648850058.240 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2022-04-01T21:54:18.240Z,1648850058.240 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2022-04-01T21:54:18.240Z,1648850058.240 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-01T21:54:18.240Z,1648850058.240 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-01T21:54:18.240Z,1648850058.240 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-01T21:54:18.241Z,1648850058.241 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-01T21:54:18.241Z,1648850058.241 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-01T21:54:18.242Z,1648850058.242 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-01T21:54:18.650Z,1648850058.650 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2022-04-01T21:54:18.651Z,1648850058.651 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2022-04-01T21:54:18.651Z,1648850058.651 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2022-04-01T21:54:19.063Z,1648850059.063 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-04-01T21:54:20.326Z,1648850060.326 [DAT](INFO): Ping request sent.
2022-04-01T21:54:25.131Z,1648850065.131 [DAT](INFO): unknown deviceResponse_:  PEAK       , IDX= 231, 0.25,-1.844, 3.105,-1.152,-2.067, PHS= 0.310,-1.064, 0.911, RAW= 167.3,  -1.8, CAL= 169.8,  -5.6, ROT=  10.2,   5.6, FLG=0x0001

2022-04-01T21:55:46.343Z,1648850146.343 [PowerOnly](INFO): Powering down loadControl
2022-04-01T21:55:55.238Z,1648850155.238 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-04-01T21:55:55.238Z,1648850155.238 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-01T21:55:55.248Z,1648850155.248 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-01T21:55:55.661Z,1648850155.661 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-01T21:55:55.661Z,1648850155.661 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-04-01T21:57:16.948Z,1648850236.948 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2022-04-01T21:57:16.960Z,1648850236.960 [BPC1](INFO): Received data from all battery sticks.
2022-04-01T21:58:56.280Z,1648850336.280 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-04-01T21:58:56.280Z,1648850336.280 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-01T21:58:56.307Z,1648850336.307 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-01T21:58:56.730Z,1648850336.730 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-01T21:58:56.730Z,1648850336.730 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-04-01T21:59:19.062Z,1648850359.062 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](INFO): Timed out from 2022-04-01T21:54:18.7Z
2022-04-01T21:59:19.062Z,1648850359.062 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2022-04-01T21:59:19.062Z,1648850359.062 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2022-04-01T21:59:19.374Z,1648850359.374 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-01T21:59:29.072Z,1648850369.072 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220401T214942/Courier0004.lzma
2022-04-01T21:59:30.075Z,1648850370.075 [DataOverHttps](INFO): Moved sent file to Logs/20220401T214942/Courier0004.lzma.bak
2022-04-01T21:59:30.075Z,1648850370.075 [DataOverHttps](INFO): SBD MOMSN=16622619
2022-04-01T21:59:47.984Z,1648850387.984 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220401T213136/Express0011.lzma
2022-04-01T21:59:48.987Z,1648850388.987 [DataOverHttps](INFO): Moved sent file to Logs/20220401T213136/Express0011.lzma.bak
2022-04-01T21:59:48.987Z,1648850388.987 [DataOverHttps](INFO): SBD MOMSN=16622621
2022-04-01T22:00:07.021Z,1648850407.021 [DataOverHttps](INFO): Sending 656 bytes from file Logs/20220401T214942/Express0001.lzma
2022-04-01T22:00:08.023Z,1648850408.023 [DataOverHttps](INFO): Moved sent file to Logs/20220401T214942/Express0001.lzma.bak
2022-04-01T22:00:08.023Z,1648850408.023 [DataOverHttps](INFO): SBD MOMSN=16622624
2022-04-01T22:00:26.021Z,1648850426.021 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20220401T214942/Express0005.lzma
2022-04-01T22:00:27.023Z,1648850427.023 [DataOverHttps](INFO): Moved sent file to Logs/20220401T214942/Express0005.lzma.bak
2022-04-01T22:00:27.023Z,1648850427.023 [DataOverHttps](INFO): SBD MOMSN=16622637
2022-04-01T22:00:30.442Z,1648850430.442 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2022-04-01T22:00:30.442Z,1648850430.442 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2022-04-01T22:00:30.442Z,1648850430.442 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-01T22:00:35.650Z,1648850435.650 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-01T22:00:35.651Z,1648850435.651 [NAL9602] Data Fault, FailCount= 1
2022-04-01T22:00:35.651Z,1648850435.651 [NAL9602](ERROR): Data Fault
2022-04-01T22:00:35.699Z,1648850435.699 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-01T22:00:36.066Z,1648850436.066 [NAL9602](INFO): Powering down
2022-04-01T22:00:36.899Z,1648850436.899 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-01T22:00:36.899Z,1648850436.899 [NAL9602] No Fault, FailCount= 1
2022-04-01T22:01:06.358Z,1648850466.358 [NAL9602](INFO): Powering up NAL9602
2022-04-01T22:01:17.327Z,1648850477.327 [NAL9602](INFO): NAL9602 initialized
2022-04-01T22:01:48.110Z,1648850508.110 [DataOverHttps](IMPORTANT): SBD MTMSN=20220401T220045
2022-04-01T22:01:55.589Z,1648850515.589 [DataOverHttps](INFO): Received command: restart app
2022-04-01T22:01:55.594Z,1648850515.594 [CommandExec](IMPORTANT): got command restart application
2022-04-01T22:01:56.597Z,1648850516.597 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-04-01T22:01:56.597Z,1648850516.597 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:01:56.597Z,1648850516.597 [CommandExec](INFO): Uninitializing the command executive.
2022-04-01T22:01:56.597Z,1648850516.597 [CommandExec](INFO): Uninitializing the command scheduler.
2022-04-01T22:01:56.598Z,1648850516.598 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:56.669Z,1648850516.669 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-04-01T22:01:56.669Z,1648850516.669 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-04-01T22:01:56.669Z,1648850516.669 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:56.670Z,1648850516.670 [NavChartDb](INFO): Join timeout helper Thread ID is 1004
2022-04-01T22:01:56.845Z,1648850516.845 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:01:56.845Z,1648850516.845 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:56.849Z,1648850516.849 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-04-01T22:01:56.849Z,1648850516.849 [DAT ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:56.849Z,1648850516.849 [DAT](INFO): Join timeout helper Thread ID is 1005
2022-04-01T22:01:56.946Z,1648850516.946 [DAT](INFO): Powering down
2022-04-01T22:01:57.017Z,1648850517.017 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:01:57.018Z,1648850517.018 [DAT](INFO): Powering down
2022-04-01T22:01:57.018Z,1648850517.018 [DAT ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:57.021Z,1648850517.021 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-04-01T22:01:57.021Z,1648850517.021 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:57.021Z,1648850517.021 [Radio_Surface](INFO): Join timeout helper Thread ID is 1006
2022-04-01T22:01:57.189Z,1648850517.189 [Radio_Surface](INFO): Powering down
2022-04-01T22:01:57.190Z,1648850517.190 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:01:57.190Z,1648850517.190 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:57.213Z,1648850517.213 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-04-01T22:01:57.213Z,1648850517.213 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:57.214Z,1648850517.214 [Onboard](INFO): Join timeout helper Thread ID is 1007
2022-04-01T22:01:57.345Z,1648850517.345 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-04-01T22:01:57.345Z,1648850517.345 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-01T22:01:57.356Z,1648850517.356 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-01T22:01:57.593Z,1648850517.593 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-04-01T22:01:57.757Z,1648850517.757 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-01T22:01:57.758Z,1648850517.758 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-04-01T22:01:59.629Z,1648850519.629 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:01:59.630Z,1648850519.630 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:59.649Z,1648850519.649 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-04-01T22:01:59.649Z,1648850519.649 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:01:59.649Z,1648850519.649 [DataOverHttps](INFO): Join timeout helper Thread ID is 1008
2022-04-01T22:02:00.605Z,1648850520.605 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:02:00.605Z,1648850520.605 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.622Z,1648850520.622 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-04-01T22:02:00.622Z,1648850520.622 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.622Z,1648850520.622 [BackseatComponent](INFO): Join timeout helper Thread ID is 1009
2022-04-01T22:02:00.649Z,1648850520.649 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:02:00.649Z,1648850520.649 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.666Z,1648850520.666 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2022-04-01T22:02:00.666Z,1648850520.666 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.666Z,1648850520.666 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1010
2022-04-01T22:02:00.757Z,1648850520.757 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:02:00.757Z,1648850520.757 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.765Z,1648850520.765 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-04-01T22:02:00.766Z,1648850520.766 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.766Z,1648850520.766 [logger](INFO): Join timeout helper Thread ID is 1011
2022-04-01T22:02:00.801Z,1648850520.801 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:02:00.801Z,1648850520.801 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.805Z,1648850520.805 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-04-01T22:02:00.806Z,1648850520.806 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.806Z,1648850520.806 [CommandLine](INFO): Join timeout helper Thread ID is 1012
2022-04-01T22:02:00.857Z,1648850520.857 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:02:00.857Z,1648850520.857 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.865Z,1648850520.865 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-04-01T22:02:00.866Z,1648850520.866 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.866Z,1648850520.866 [CommandExec](INFO): Join timeout helper Thread ID is 1013
2022-04-01T22:02:00.867Z,1648850520.867 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-04-01T22:02:00.867Z,1648850520.867 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:00.868Z,1648850520.868 [controlThread](INFO): Join timeout helper Thread ID is 1014
2022-04-01T22:02:00.929Z,1648850520.929 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-01T22:02:00.930Z,1648850520.930 [controlThread](DEBUG): Uninitializing ControlThread
2022-04-01T22:02:00.931Z,1648850520.931 [AHRS_M2](INFO): Powering down
2022-04-01T22:02:01.002Z,1648850521.002 [NAL9602](INFO): Powering down
2022-04-01T22:02:01.073Z,1648850521.073 [RDI_Pathfinder](INFO): Powering down
2022-04-01T22:02:01.074Z,1648850521.074 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-04-01T22:02:01.075Z,1648850521.075 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-04-01T22:02:01.075Z,1648850521.075 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-04-01T22:02:01.076Z,1648850521.076 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2022-04-01T22:02:01.076Z,1648850521.076 [DefaultWithUndock] Stopped
2022-04-01T22:02:01.076Z,1648850521.076 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2022-04-01T22:02:01.076Z,1648850521.076 [DefaultWithUndock:SurfaceDefault] Stopped
2022-04-01T22:02:01.076Z,1648850521.076 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2022-04-01T22:02:01.076Z,1648850521.076 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2022-04-01T22:02:01.076Z,1648850521.076 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-01T22:02:01.076Z,1648850521.076 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2022-04-01T22:02:01.076Z,1648850521.076 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2022-04-01T22:02:01.077Z,1648850521.077 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2022-04-01T22:02:01.077Z,1648850521.077 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-01T22:02:01.084Z,1648850521.084 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-04-01T22:02:01.084Z,1648850521.084 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-04-01T22:02:01.084Z,1648850521.084 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-04-01T22:02:01.084Z,1648850521.084 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-04-01T22:02:01.085Z,1648850521.085 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-04-01T22:02:01.085Z,1648850521.085 [BuoyancyServo](INFO): Powering down
2022-04-01T22:02:01.097Z,1648850521.097 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-04-01T22:02:01.097Z,1648850521.097 [ElevatorServo](INFO): Powering down
2022-04-01T22:02:01.098Z,1648850521.098 [DockingServo](DEBUG): Uninitialize Docking Servo.
2022-04-01T22:02:01.098Z,1648850521.098 [DockingServo](INFO): Powering down
2022-04-01T22:02:01.099Z,1648850521.099 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-04-01T22:02:01.099Z,1648850521.099 [MassServo](INFO): Powering down
2022-04-01T22:02:01.100Z,1648850521.100 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-04-01T22:02:01.100Z,1648850521.100 [RudderServo](INFO): Powering down
2022-04-01T22:02:01.101Z,1648850521.101 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-04-01T22:02:01.101Z,1648850521.101 [ThrusterHE](INFO): Powering down
2022-04-01T22:02:01.102Z,1648850521.102 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-04-01T22:02:01.102Z,1648850521.102 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-04-01T22:02:01.102Z,1648850521.102 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-04-01T22:02:01.102Z,1648850521.102 [CBIT](DEBUG): Powering off loads.
2022-04-01T22:02:01.113Z,1648850521.113 [CBIT](DEBUG): Disabling WDT.
2022-04-01T22:02:01.125Z,1648850521.125 [CBIT](DEBUG): Opening all GF detection circuits.
2022-04-01T22:02:01.126Z,1648850521.126 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:01.154Z,1648850521.154 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:01.164Z,1648850521.164 [DAT ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:01.171Z,1648850521.171 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:01.174Z,1648850521.174 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:01.181Z,1648850521.181 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:01.225Z,1648850521.225 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:01.316Z,1648850521.316 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-01T22:02:01.385Z,1648850521.385 [logger ThreadHandler](INFO): Thread cancelled.