2022-01-31T17:53:24.773Z,1643651604.773 [Supervisor](DEBUG): Initializing supervisor.
2022-01-31T17:53:24.777Z,1643651604.777 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-01-31T17:53:24.777Z,1643651604.777 [SyncHandler](INFO): Protected caller Thread ID is 5928
2022-01-31T17:53:24.778Z,1643651604.778 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-01-31T17:53:24.779Z,1643651604.779 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-01-31T17:53:24.779Z,1643651604.779 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5929
2022-01-31T17:53:24.783Z,1643651604.783 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-01-31T17:53:24.875Z,1643651604.875 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-01-31T17:53:24.876Z,1643651604.876 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-01-31T17:53:24.877Z,1643651604.877 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5930
2022-01-31T17:53:24.888Z,1643651604.888 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-01-31T17:53:24.896Z,1643651604.896 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-01-31T17:53:24.896Z,1643651604.896 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5931
2022-01-31T17:53:24.898Z,1643651604.898 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-01-31T17:53:24.899Z,1643651604.899 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-01-31T17:53:24.899Z,1643651604.899 [logger ThreadHandler](INFO): Protected caller Thread ID is 5932
2022-01-31T17:53:24.903Z,1643651604.903 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-01-31T17:53:24.940Z,1643651604.940 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-01-31T17:53:24.950Z,1643651604.950 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-01-31T17:53:25.565Z,1643651605.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-01-31T17:53:25.567Z,1643651605.567 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-01-31T17:53:25.647Z,1643651605.647 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-01-31T17:53:25.971Z,1643651605.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-01-31T17:53:25.971Z,1643651605.971 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-01-31T17:53:26.079Z,1643651606.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-01-31T17:53:26.081Z,1643651606.081 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-01-31T17:53:26.283Z,1643651606.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-01-31T17:53:26.285Z,1643651606.285 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-01-31T17:53:26.797Z,1643651606.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-01-31T17:53:26.798Z,1643651606.798 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-01-31T17:53:27.063Z,1643651607.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-01-31T17:53:27.064Z,1643651607.064 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-01-31T17:53:27.211Z,1643651607.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-01-31T17:53:27.213Z,1643651607.213 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-01-31T17:53:27.403Z,1643651607.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-01-31T17:53:27.405Z,1643651607.405 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-01-31T17:53:27.503Z,1643651607.503 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-01-31T17:53:27.504Z,1643651607.504 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-01-31T17:53:27.723Z,1643651607.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-01-31T17:53:27.725Z,1643651607.725 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-01-31T17:53:28.108Z,1643651608.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-01-31T17:53:28.109Z,1643651608.109 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-01-31T17:53:28.190Z,1643651608.190 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-01-31T17:53:28.317Z,1643651608.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-01-31T17:53:28.318Z,1643651608.318 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-01-31T17:53:29.006Z,1643651609.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-01-31T17:53:29.007Z,1643651609.007 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-01-31T17:53:29.367Z,1643651609.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-01-31T17:53:29.369Z,1643651609.369 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-polaris/
2022-01-31T17:53:29.373Z,1643651609.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/vehicle.cfg
2022-01-31T17:53:29.571Z,1643651609.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Control.cfg
2022-01-31T17:53:29.672Z,1643651609.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Estimation.cfg
2022-01-31T17:53:29.778Z,1643651609.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/BIT.cfg
2022-01-31T17:53:30.383Z,1643651610.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Battery.cfg
2022-01-31T17:53:31.160Z,1643651611.160 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-01-31T17:53:31.162Z,1643651611.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Simulator.cfg
2022-01-31T17:53:31.248Z,1643651611.248 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Navigation.cfg
2022-01-31T17:53:31.351Z,1643651611.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Derivation.cfg
2022-01-31T17:53:31.476Z,1643651611.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/logger.cfg
2022-01-31T17:53:31.588Z,1643651611.588 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/secure.cfg
2022-01-31T17:53:31.674Z,1643651611.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Dock.cfg
2022-01-31T17:53:31.761Z,1643651611.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Servo.cfg
2022-01-31T17:53:31.873Z,1643651611.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/workSite.cfg
2022-01-31T17:53:31.971Z,1643651611.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Sensor.cfg
2022-01-31T17:53:32.177Z,1643651612.177 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Science.cfg
2022-01-31T17:53:32.339Z,1643651612.339 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-01-31T17:53:32.362Z,1643651612.362 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-01-31T17:53:33.013Z,1643651613.013 [AHRS_M2] Loaded
2022-01-31T17:53:33.014Z,1643651613.014 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-01-31T17:53:33.047Z,1643651613.047 [BackseatComponent] Loaded
2022-01-31T17:53:33.048Z,1643651613.048 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-01-31T17:53:33.049Z,1643651613.049 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 407DF4E0
2022-01-31T17:53:33.049Z,1643651613.049 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6026
2022-01-31T17:53:33.052Z,1643651613.052 [LcmUniversalReporter] Loaded
2022-01-31T17:53:33.052Z,1643651613.052 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-01-31T17:53:33.811Z,1643651613.811 [BPC1] Loaded
2022-01-31T17:53:33.812Z,1643651613.812 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-01-31T17:53:34.183Z,1643651614.183 [DataOverHttps] Loaded
2022-01-31T17:53:34.183Z,1643651614.183 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-01-31T17:53:34.184Z,1643651614.184 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4080F4E0
2022-01-31T17:53:34.185Z,1643651614.185 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6027
2022-01-31T17:53:34.204Z,1643651614.204 [Depth_Keller] Loaded
2022-01-31T17:53:34.204Z,1643651614.204 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-01-31T17:53:34.209Z,1643651614.209 [DropWeight] Loaded
2022-01-31T17:53:34.209Z,1643651614.209 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-01-31T17:53:34.267Z,1643651614.267 [NAL9602] Loaded
2022-01-31T17:53:34.267Z,1643651614.267 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-01-31T17:53:34.295Z,1643651614.295 [Onboard] Loaded
2022-01-31T17:53:34.295Z,1643651614.295 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-01-31T17:53:34.296Z,1643651614.296 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4083F4E0
2022-01-31T17:53:34.297Z,1643651614.297 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6028
2022-01-31T17:53:34.303Z,1643651614.303 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2022-01-31T17:53:34.313Z,1643651614.313 [PowerOnly] Loaded
2022-01-31T17:53:34.313Z,1643651614.313 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-01-31T17:53:34.324Z,1643651614.324 [Power24vConverter] Loaded
2022-01-31T17:53:34.325Z,1643651614.325 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-01-31T17:53:34.338Z,1643651614.338 [Radio_Surface] Loaded
2022-01-31T17:53:34.338Z,1643651614.338 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-01-31T17:53:34.339Z,1643651614.339 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0
2022-01-31T17:53:34.340Z,1643651614.340 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6029
2022-01-31T17:53:34.382Z,1643651614.382 [RDI_Pathfinder] Loaded
2022-01-31T17:53:34.383Z,1643651614.383 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2022-01-31T17:53:34.383Z,1643651614.383 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-01-31T17:53:34.384Z,1643651614.384 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-01-31T17:53:34.399Z,1643651614.399 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-01-31T17:53:34.400Z,1643651614.400 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-01-31T17:53:34.532Z,1643651614.532 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-01-31T17:53:34.532Z,1643651614.532 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-01-31T17:53:34.561Z,1643651614.561 [LBLNavigation] Loaded
2022-01-31T17:53:34.561Z,1643651614.561 [ComponentRegistry](DEBUG): SyncComponent "LBLNavigation" handled in the control thread.
2022-01-31T17:53:34.574Z,1643651614.574 [NavChart] Loaded
2022-01-31T17:53:34.574Z,1643651614.574 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-01-31T17:53:34.580Z,1643651614.580 [UniversalFixResidualReporter] Loaded
2022-01-31T17:53:34.580Z,1643651614.580 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-01-31T17:53:34.590Z,1643651614.590 [WorkSite] Loaded
2022-01-31T17:53:34.591Z,1643651614.591 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2022-01-31T17:53:34.591Z,1643651614.591 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-01-31T17:53:34.591Z,1643651614.591 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-01-31T17:53:34.709Z,1643651614.709 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-01-31T17:53:34.710Z,1643651614.710 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-01-31T17:53:34.860Z,1643651614.860 [BuoyancyServo] Loaded
2022-01-31T17:53:34.860Z,1643651614.860 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-01-31T17:53:34.881Z,1643651614.881 [ElevatorServo] Loaded
2022-01-31T17:53:34.881Z,1643651614.881 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-01-31T17:53:34.902Z,1643651614.902 [MassServo] Loaded
2022-01-31T17:53:34.902Z,1643651614.902 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-01-31T17:53:34.921Z,1643651614.921 [RudderServo] Loaded
2022-01-31T17:53:34.922Z,1643651614.922 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-01-31T17:53:34.938Z,1643651614.938 [ThrusterHE] Loaded
2022-01-31T17:53:34.938Z,1643651614.938 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-01-31T17:53:34.938Z,1643651614.938 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-01-31T17:53:34.939Z,1643651614.939 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-01-31T17:53:35.639Z,1643651615.639 [CTD_Seabird] Loaded
2022-01-31T17:53:35.639Z,1643651615.639 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-01-31T17:53:35.640Z,1643651615.640 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0
2022-01-31T17:53:35.641Z,1643651615.641 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6030
2022-01-31T17:53:35.660Z,1643651615.660 [PAR_Licor] Loaded
2022-01-31T17:53:35.660Z,1643651615.660 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-01-31T17:53:35.693Z,1643651615.693 [WetLabsSeaOWL_UV_A] Loaded
2022-01-31T17:53:35.693Z,1643651615.693 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2022-01-31T17:53:35.694Z,1643651615.694 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2022-01-31T17:53:35.694Z,1643651615.694 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 6031
2022-01-31T17:53:35.695Z,1643651615.695 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-01-31T17:53:35.695Z,1643651615.695 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-01-31T17:53:36.062Z,1643651616.062 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-01-31T17:53:36.063Z,1643651616.063 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-01-31T17:53:36.120Z,1643651616.120 [DepthRateCalculator] Loaded
2022-01-31T17:53:36.120Z,1643651616.120 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-01-31T17:53:36.125Z,1643651616.125 [PitchRateCalculator] Loaded
2022-01-31T17:53:36.126Z,1643651616.126 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-01-31T17:53:36.135Z,1643651616.135 [SpeedCalculator] Loaded
2022-01-31T17:53:36.135Z,1643651616.135 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-01-31T17:53:36.148Z,1643651616.148 [TempGradientCalculator] Loaded
2022-01-31T17:53:36.148Z,1643651616.148 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-01-31T17:53:36.163Z,1643651616.163 [VerticalHomogeneityIndexCalculator] Loaded
2022-01-31T17:53:36.164Z,1643651616.164 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2022-01-31T17:53:36.168Z,1643651616.168 [YawRateCalculator] Loaded
2022-01-31T17:53:36.169Z,1643651616.169 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-01-31T17:53:36.190Z,1643651616.190 [ElevatorOffsetCalculator] Loaded
2022-01-31T17:53:36.190Z,1643651616.190 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-01-31T17:53:36.190Z,1643651616.190 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-01-31T17:53:36.191Z,1643651616.191 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-01-31T17:53:36.364Z,1643651616.364 [SBIT](DEBUG): Construct Startup Built In Test.
2022-01-31T17:53:36.376Z,1643651616.376 [SBIT] Loaded
2022-01-31T17:53:36.376Z,1643651616.376 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-01-31T17:53:36.379Z,1643651616.379 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-01-31T17:53:36.392Z,1643651616.392 [IBIT] Loaded
2022-01-31T17:53:36.392Z,1643651616.392 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-01-31T17:53:36.398Z,1643651616.398 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-01-31T17:53:36.500Z,1643651616.500 [CBIT] Loaded
2022-01-31T17:53:36.500Z,1643651616.500 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-01-31T17:53:36.500Z,1643651616.500 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-01-31T17:53:36.501Z,1643651616.501 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-01-31T17:53:36.579Z,1643651616.579 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-01-31T17:53:36.579Z,1643651616.579 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-01-31T17:53:36.710Z,1643651616.710 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-01-31T17:53:36.710Z,1643651616.710 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-01-31T17:53:36.774Z,1643651616.774 [VerticalControl](DEBUG): Construct VerticalControl.
2022-01-31T17:53:36.826Z,1643651616.826 [VerticalControl] Loaded
2022-01-31T17:53:36.826Z,1643651616.826 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-01-31T17:53:36.829Z,1643651616.829 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-01-31T17:53:36.866Z,1643651616.866 [HorizontalControl] Loaded
2022-01-31T17:53:36.866Z,1643651616.866 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-01-31T17:53:36.868Z,1643651616.868 [SpeedControl](DEBUG): Construct SpeedControl.
2022-01-31T17:53:36.870Z,1643651616.870 [SpeedControl] Loaded
2022-01-31T17:53:36.870Z,1643651616.870 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-01-31T17:53:36.873Z,1643651616.873 [LoopControl](DEBUG): Construct LoopControl.
2022-01-31T17:53:36.874Z,1643651616.874 [LoopControl] Loaded
2022-01-31T17:53:36.874Z,1643651616.874 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-01-31T17:53:36.874Z,1643651616.874 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-01-31T17:53:36.875Z,1643651616.875 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-01-31T17:53:36.913Z,1643651616.913 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-01-31T17:53:36.919Z,1643651616.919 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-01-31T17:53:36.922Z,1643651616.922 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-01-31T17:53:36.932Z,1643651616.932 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-01-31T17:53:36.933Z,1643651616.933 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B8D4E0
2022-01-31T17:53:36.934Z,1643651616.934 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6032
2022-01-31T17:53:36.938Z,1643651616.938 [Supervisor](INFO): Main Thread ID is 829
2022-01-31T17:53:36.938Z,1643651616.938 [Supervisor](DEBUG): Running supervisor.
2022-01-31T17:53:36.939Z,1643651616.939 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6033
2022-01-31T17:53:36.939Z,1643651616.939 [CommandExec](INFO): Initializing the command executive.
2022-01-31T17:53:36.941Z,1643651616.941 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6034
2022-01-31T17:53:36.943Z,1643651616.943 [controlThread ThreadHandler](INFO): Handler Thread ID is 6035
2022-01-31T17:53:36.943Z,1643651616.943 [controlThread](DEBUG): Initializing ControlThread
2022-01-31T17:53:36.951Z,1643651616.951 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-01-31T17:53:36.951Z,1643651616.951 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-01-31T17:53:36.951Z,1643651616.951 [WorkSite](DEBUG): Initializing WorkSite component.
2022-01-31T17:53:36.956Z,1643651616.956 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-01-31T17:53:36.957Z,1643651616.957 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-01-31T17:53:36.957Z,1643651616.957 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-01-31T17:53:36.957Z,1643651616.957 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-01-31T17:53:36.958Z,1643651616.958 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2022-01-31T17:53:36.959Z,1643651616.959 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-01-31T17:53:36.959Z,1643651616.959 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-01-31T17:53:36.960Z,1643651616.960 [SBIT](INFO): Initialize SBIT Component.
2022-01-31T17:53:36.960Z,1643651616.960 [SBIT](IMPORTANT): git: 2022-01-19
2022-01-31T17:53:36.960Z,1643651616.960 [SBIT](INFO): git hash: 3c152841e44dce493716324b2ee7dcf988968060
2022-01-31T17:53:36.961Z,1643651616.961 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-01-31T17:53:36.962Z,1643651616.962 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2022-01-31T17:53:36.963Z,1643651616.963 [SBIT](INFO): Beginning SBIT in 44.000000 seconds.
2022-01-31T17:53:36.964Z,1643651616.964 [IBIT](INFO): Initialize IBIT Component.
2022-01-31T17:53:36.965Z,1643651616.965 [CBIT](DEBUG): Initialize CBIT Component.
2022-01-31T17:53:36.966Z,1643651616.966 [logger ThreadHandler](INFO): Handler Thread ID is 6036
2022-01-31T17:53:36.976Z,1643651616.976 [CBIT](DEBUG): Initialized mux pins.
2022-01-31T17:53:36.976Z,1643651616.976 [CBIT](DEBUG): Initializing the watchdog timer.
2022-01-31T17:53:36.984Z,1643651616.984 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6037
2022-01-31T17:53:36.996Z,1643651616.996 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6038
2022-01-31T17:53:36.997Z,1643651616.997 [DataOverHttps](DEBUG): dashIP=128.128.181.226 starts with a digit so assuming it is a numeric IP
2022-01-31T17:53:36.000Z,1643651617.000 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-01-31T17:53:37.000Z,1643651617.000 [CBIT](DEBUG): Initializing heartbeat.
2022-01-31T17:53:37.008Z,1643651617.008 [Onboard ThreadHandler](INFO): Handler Thread ID is 6039
2022-01-31T17:53:37.041Z,1643651617.041 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6040
2022-01-31T17:53:37.045Z,1643651617.045 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6041
2022-01-31T17:53:37.048Z,1643651617.048 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-01-31T17:53:37.053Z,1643651617.053 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 6043
2022-01-31T17:53:37.054Z,1643651617.054 [WetLabsSeaOWL_UV_A](INFO): Powering down
2022-01-31T17:53:37.065Z,1643651617.065 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6044
2022-01-31T17:53:37.072Z,1643651617.072 [CBIT](DEBUG): Deactivating GF circuits.
2022-01-31T17:53:37.072Z,1643651617.072 [CBIT](DEBUG): Deactivating emergency mode.
2022-01-31T17:53:37.074Z,1643651617.074 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-01-31T17:53:37.074Z,1643651617.074 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-01-31T17:53:37.074Z,1643651617.074 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-01-31T17:53:37.074Z,1643651617.074 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-01-31T17:53:37.074Z,1643651617.074 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-01-31T17:53:37.074Z,1643651617.074 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-01-31T17:53:37.075Z,1643651617.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-01-31T17:53:37.075Z,1643651617.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-01-31T17:53:37.075Z,1643651617.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-01-31T17:53:37.075Z,1643651617.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-01-31T17:53:37.075Z,1643651617.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-01-31T17:53:37.075Z,1643651617.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-01-31T17:53:37.076Z,1643651617.076 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-01-31T17:53:37.076Z,1643651617.076 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-01-31T17:53:37.076Z,1643651617.076 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-01-31T17:53:37.076Z,1643651617.076 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-01-31T17:53:37.108Z,1643651617.108 [CBIT](DEBUG): Backplane powered.
2022-01-31T17:53:37.108Z,1643651617.108 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-01-31T17:53:37.110Z,1643651617.110 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-01-31T17:53:37.111Z,1643651617.111 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-01-31T17:53:37.111Z,1643651617.111 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-01-31T17:53:37.112Z,1643651617.112 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-01-31T17:53:37.129Z,1643651617.129 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-01-31T17:53:37.169Z,1643651617.169 [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-01-31T17:53:37.170Z,1643651617.170 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-01-31T17:53:37.244Z,1643651617.244 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-01-31T17:53:37.245Z,1643651617.245 [Default:A.Wait](DEBUG): Construct Wait.
2022-01-31T17:53:37.268Z,1643651617.268 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-01-31T17:53:37.279Z,1643651617.279 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-01-31T17:53:37.301Z,1643651617.301 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-01-31T17:53:37.326Z,1643651617.326 [Default:E.Execute](DEBUG): Construct Execute.
2022-01-31T17:53:37.346Z,1643651617.346 [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>


2022-01-31T17:53:37.351Z,1643651617.351 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,LBLNavigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-01-31T17:53:37.364Z,1643651617.364 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-01-31T17:53:37.432Z,1643651617.432 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2022-01-31T17:53:37.434Z,1643651617.434 [Power24vConverter](INFO): Powering up.
2022-01-31T17:53:37.444Z,1643651617.444 [Radio_Surface](INFO): Powering up
2022-01-31T17:53:37.454Z,1643651617.454 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-01-31T17:53:37.478Z,1643651617.478 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-01-31T17:53:37.484Z,1643651617.484 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-01-31T17:53:37.485Z,1643651617.485 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-01-31T17:53:37.496Z,1643651617.496 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-01-31T17:53:37.497Z,1643651617.497 [MassServo](DEBUG): Initializing EZServoServo.
2022-01-31T17:53:37.504Z,1643651617.504 [MassServo](DEBUG): Initializing MassServo.
2022-01-31T17:53:37.505Z,1643651617.505 [RudderServo](DEBUG): Initializing EZServoServo.
2022-01-31T17:53:37.512Z,1643651617.512 [RudderServo](DEBUG): Initializing RudderServo.
2022-01-31T17:53:37.513Z,1643651617.513 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-01-31T17:53:37.520Z,1643651617.520 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-01-31T17:53:44.092Z,1643651624.092 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-01-31T17:54:04.426Z,1643651644.426 [NAL9602](INFO): Powering up NAL9602
2022-01-31T17:54:15.350Z,1643651655.350 [NAL9602](INFO): NAL9602 initialized
2022-01-31T17:54:21.470Z,1643651661.470 [SBIT](IMPORTANT): Beginning Startup BIT
2022-01-31T17:54:21.475Z,1643651661.474 [CBIT](IMPORTANT): Beginning ground fault scan
2022-01-31T17:54:28.284Z,1643651668.284 [NAL9602](INFO): SBD MO Status=0, MOMSN=35441, MT Status=0, MTMSN=0
2022-01-31T17:54:28.284Z,1643651668.284 [NAL9602](INFO): No messages in MT queue
2022-01-31T17:54:32.780Z,1643651672.780 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.060872
CHAN A1 (24V): 0.122565
CHAN A2 (12V): 0.039030
CHAN A3 (5V): 0.009543
CHAN B0 (3.3V): 0.006346
CHAN B1 (3.15aV): 0.005740
CHAN B2 (3.15bV): 0.005201
CHAN B3 (GND): -0.011353
OPEN: 0.006881
Full Scale: +/- 1 mA
2022-01-31T17:54:58.999Z,1643651698.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175500.00,A,4128.97581,N,07052.65293,W,0.758,0.00,310122,,,A*75
2022-01-31T17:54:59.002Z,1643651699.002 [NAL9602](INFO): GPS fix at 20220131T175500: (41.482930, -70.877549)
2022-01-31T17:54:59.015Z,1643651699.015 [LBLNavigation](INFO): Reset ping filters
2022-01-31T17:54:59.015Z,1643651699.015 [LBLNavigation](INFO): Reset fix filter
2022-01-31T17:55:15.583Z,1643651715.583 [SBIT](IMPORTANT): SBIT PASSED
2022-01-31T17:55:15.583Z,1643651715.583 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-01-31T17:55:15.586Z,1643651715.586 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=2 none;

2022-01-31T17:55:15.586Z,1643651715.586 [SBIT](IMPORTANT): BuoyancyServo.velocity=800 none;

2022-01-31T17:55:15.586Z,1643651715.586 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2022-01-31T17:55:15.587Z,1643651715.587 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool;

2022-01-31T17:55:15.587Z,1643651715.587 [SBIT](IMPORTANT): DataOverHttps.loadAtStartup=1 bool;

2022-01-31T17:55:15.587Z,1643651715.587 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.050000 practical_salinity_unit;

2022-01-31T17:55:15.587Z,1643651715.587 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_temperature 0.500000 celsius;

2022-01-31T17:55:15.587Z,1643651715.587 [SBIT](IMPORTANT): Express none WetLabsSeaOWL_UV_A.Output700;

2022-01-31T17:55:15.588Z,1643651715.588 [SBIT](IMPORTANT): Express none WetLabsSeaOWL_UV_A.OutputFDOM;

2022-01-31T17:55:15.588Z,1643651715.588 [SBIT](IMPORTANT): Express none WetLabsSeaOWL_UV_A.concentration_of_colored_dissolved_organic_matter_in_sea_water;

2022-01-31T17:55:15.589Z,1643651715.589 [SBIT](IMPORTANT): Express none WetLabsSeaOWL_UV_A.mass_concentration_of_chlorophyll_in_sea_water;

2022-01-31T17:55:15.589Z,1643651715.589 [SBIT](IMPORTANT): Express none WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water;

2022-01-31T17:55:15.589Z,1643651715.589 [SBIT](IMPORTANT): Express none concentration_of_colored_dissolved_organic_matter_in_sea_water;

2022-01-31T17:55:15.589Z,1643651715.589 [SBIT](IMPORTANT): Express none mass_concentration_of_chlorophyll_in_sea_water;

2022-01-31T17:55:15.589Z,1643651715.589 [SBIT](IMPORTANT): Express linearApproximation platform_average_current 0.500000 ampere;

2022-01-31T17:55:15.590Z,1643651715.590 [SBIT](IMPORTANT): Micromodem.loadAtStartup=0 bool;

2022-01-31T17:55:15.590Z,1643651715.590 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2022-01-31T17:55:15.590Z,1643651715.590 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2022-01-31T17:55:15.590Z,1643651715.590 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;

2022-01-31T17:55:15.591Z,1643651715.591 [SBIT](IMPORTANT): RDI_PathfinderUp.loadAtStartup=0 bool;

2022-01-31T17:55:15.591Z,1643651715.591 [SBIT](IMPORTANT): Radio_Surface.loadAtStartup=1 bool;

2022-01-31T17:55:15.591Z,1643651715.591 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=154 cubic_centimeter;

2022-01-31T17:55:15.591Z,1643651715.591 [SBIT](IMPORTANT): VerticalControl.massDefault=6 millimeter;

2022-01-31T17:55:15.591Z,1643651715.591 [SBIT](IMPORTANT): WetLabsSeaOWL_UV_A.loadAtStartup=1 bool;

2022-01-31T17:55:15.986Z,1643651715.986 [WorkSite](INFO): Already have valid position. Ignoring workSite position from Data/workSite.cfg
2022-01-31T17:55:15.991Z,1643651715.991 [MissionManager](IMPORTANT): Started mission Startup
2022-01-31T17:55:15.991Z,1643651715.991 [Startup] Running Loop=1
2022-01-31T17:55:15.996Z,1643651715.996 [Startup](DEBUG): Aggregate::initialize Startup
2022-01-31T17:55:15.996Z,1643651715.996 [Startup:A.GoToSurface] Running Loop=1
2022-01-31T17:55:15.996Z,1643651715.996 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-01-31T17:55:15.997Z,1643651715.997 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-01-31T17:55:15.997Z,1643651715.997 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-01-31T17:55:15.997Z,1643651715.997 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-01-31T17:55:15.998Z,1643651715.998 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-01-31T17:55:15.998Z,1643651715.998 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-01-31T17:55:15.999Z,1643651715.999 [Startup:StartupSatComms] Running Loop=1
2022-01-31T17:55:16.004Z,1643651716.004 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-01-31T17:55:16.004Z,1643651716.004 [Startup:StartupSatComms:A] Running Loop=1
2022-01-31T17:55:16.363Z,1643651716.363 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-01-31T17:55:17.963Z,1643651717.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175519.00,A,4128.97524,N,07052.65743,W,0.330,0.00,310122,,,A*70
2022-01-31T17:55:17.966Z,1643651717.966 [NAL9602](INFO): GPS fix at 20220131T175519: (41.482921, -70.877624)
2022-01-31T17:55:17.997Z,1643651717.997 [Startup:StartupSatComms:A] Stopped
2022-01-31T17:55:17.997Z,1643651717.997 [Startup:StartupSatComms:B] Running Loop=1
2022-01-31T17:55:18.391Z,1643651718.391 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-01-31T17:55:32.157Z,1643651732.157 [NAL9602](INFO): SBD MO Status=1, MOMSN=35442, MT Status=0, MTMSN=0
2022-01-31T17:55:32.224Z,1643651732.224 [NAL9602](INFO): Sent 165 bytes from file Logs/20220131T154732/Courier0003.lzma
2022-01-31T17:55:32.224Z,1643651732.224 [NAL9602](INFO): Packets left to send: 0
2022-01-31T17:55:39.105Z,1643651739.105 [NAL9602](INFO): SBD MO Status=1, MOMSN=35443, MT Status=0, MTMSN=0
2022-01-31T17:55:39.152Z,1643651739.152 [NAL9602](INFO): Sent 51 bytes from file Logs/20220131T154732/Courier0006.lzma
2022-01-31T17:55:39.152Z,1643651739.152 [NAL9602](INFO): Packets left to send: 0
2022-01-31T17:55:57.727Z,1643651757.727 [NAL9602](INFO): SBD MO Status=2, MOMSN=35444, MT Status=2, MTMSN=0
2022-01-31T17:55:57.727Z,1643651757.727 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-01-31T17:56:10.749Z,1643651770.749 [NAL9602](INFO): SBD MO Status=1, MOMSN=35444, MT Status=0, MTMSN=0
2022-01-31T17:56:10.800Z,1643651770.800 [NAL9602](INFO): Sent 184 bytes from file Logs/20220131T175324/Courier0000.lzma
2022-01-31T17:56:10.800Z,1643651770.800 [NAL9602](INFO): Packets left to send: 1
2022-01-31T17:56:18.141Z,1643651778.141 [Startup:StartupSatComms:B](INFO): Timed out from 2022-01-31T17:55:17.0Z
2022-01-31T17:56:18.141Z,1643651778.141 [Startup:StartupSatComms:B] Stopped
2022-01-31T17:56:18.142Z,1643651778.142 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-01-31T17:56:18.142Z,1643651778.142 [Startup:StartupSatComms] Stopped
2022-01-31T17:56:18.142Z,1643651778.142 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-01-31T17:56:18.143Z,1643651778.143 [Startup](INFO): Completed Startup
2022-01-31T17:56:18.143Z,1643651778.143 [MissionManager](INFO): Startup is completed.
2022-01-31T17:56:18.143Z,1643651778.143 [MissionManager](INFO): Uninitializing Mission Startup
2022-01-31T17:56:18.143Z,1643651778.143 [Startup] Stopped
2022-01-31T17:56:18.143Z,1643651778.143 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-01-31T17:56:18.143Z,1643651778.143 [Startup:A.GoToSurface] Stopped
2022-01-31T17:56:18.143Z,1643651778.143 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-01-31T17:56:18.552Z,1643651778.552 [MissionManager](IMPORTANT): Started mission Default
2022-01-31T17:56:18.552Z,1643651778.552 [Default] Running Loop=1
2022-01-31T17:56:18.552Z,1643651778.552 [Default](DEBUG): Aggregate::initialize Default
2022-01-31T17:56:18.552Z,1643651778.552 [Default:B.GoToSurface] Running Loop=1
2022-01-31T17:56:18.552Z,1643651778.552 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-01-31T17:56:18.553Z,1643651778.553 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-01-31T17:56:18.553Z,1643651778.553 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-01-31T17:56:18.553Z,1643651778.553 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-01-31T17:56:18.554Z,1643651778.554 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-01-31T17:56:18.554Z,1643651778.554 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-01-31T17:56:18.554Z,1643651778.554 [Default:A.Wait] Running Loop=1
2022-01-31T17:56:18.554Z,1643651778.554 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-01-31T17:56:21.362Z,1643651781.362 [NAL9602](INFO): SBD MO Status=1, MOMSN=35445, MT Status=0, MTMSN=0
2022-01-31T17:56:21.413Z,1643651781.413 [NAL9602](INFO): Sent 68 bytes from file Logs/20220131T175324/Courier0000.lzma
2022-01-31T17:56:21.414Z,1643651781.414 [NAL9602](INFO): Packets left to send: 0
2022-01-31T17:56:31.898Z,1643651791.898 [Default:A.Wait](INFO): Done Waiting.
2022-01-31T17:56:31.898Z,1643651791.898 [Default:A.Wait] Stopped
2022-01-31T17:56:31.899Z,1643651791.899 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-01-31T17:56:32.338Z,1643651792.338 [Default:CheckIn] Running Loop=1
2022-01-31T17:56:32.338Z,1643651792.338 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-31T17:56:32.339Z,1643651792.339 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-31T17:56:32.774Z,1643651792.774 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-01-31T17:56:34.291Z,1643651794.291 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175635.00,A,4128.97314,N,07052.67480,W,0.603,306.08,310122,,,A*7E
2022-01-31T17:56:34.293Z,1643651794.293 [NAL9602](INFO): GPS fix at 20220131T175635: (41.482886, -70.877913)
2022-01-31T17:56:34.306Z,1643651794.306 [Default:CheckIn:Read_GPS] Stopped
2022-01-31T17:56:34.306Z,1643651794.306 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-31T17:56:34.727Z,1643651794.727 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-01-31T17:56:46.551Z,1643651806.551 [NAL9602](INFO): SBD MO Status=1, MOMSN=35446, MT Status=0, MTMSN=0
2022-01-31T17:56:46.608Z,1643651806.608 [NAL9602](INFO): Sent 78 bytes from file Logs/20220131T175324/Courier0004.lzma
2022-01-31T17:56:46.608Z,1643651806.608 [NAL9602](INFO): Packets left to send: 0
2022-01-31T17:56:55.617Z,1643651815.617 [NAL9602](INFO): SBD MO Status=1, MOMSN=35447, MT Status=0, MTMSN=0
2022-01-31T17:56:55.672Z,1643651815.672 [NAL9602](INFO): Sent 184 bytes from file Logs/20220131T154732/Express0004.lzma
2022-01-31T17:56:55.673Z,1643651815.673 [NAL9602](INFO): Packets left to send: 1
2022-01-31T17:57:04.250Z,1643651824.250 [NAL9602](INFO): SBD MO Status=1, MOMSN=35448, MT Status=0, MTMSN=0
2022-01-31T17:57:04.320Z,1643651824.320 [NAL9602](INFO): Sent 139 bytes from file Logs/20220131T154732/Express0004.lzma
2022-01-31T17:57:04.320Z,1643651824.320 [NAL9602](INFO): Packets left to send: 0
2022-01-31T17:57:15.370Z,1643651835.370 [NAL9602](INFO): SBD MO Status=1, MOMSN=35449, MT Status=0, MTMSN=0
2022-01-31T17:57:15.428Z,1643651835.428 [NAL9602](INFO): Sent 184 bytes from file Logs/20220131T154732/Express0007.lzma
2022-01-31T17:57:15.428Z,1643651835.428 [NAL9602](INFO): Packets left to send: 1
2022-01-31T17:57:26.862Z,1643651846.862 [NAL9602](INFO): SBD MO Status=1, MOMSN=35450, MT Status=0, MTMSN=0
2022-01-31T17:57:26.912Z,1643651846.912 [NAL9602](INFO): Sent 161 bytes from file Logs/20220131T154732/Express0007.lzma
2022-01-31T17:57:26.912Z,1643651846.912 [NAL9602](INFO): Packets left to send: 0
2022-01-31T17:57:37.545Z,1643651857.545 [NAL9602](INFO): SBD MO Status=1, MOMSN=35451, MT Status=0, MTMSN=0
2022-01-31T17:57:37.601Z,1643651857.601 [NAL9602](INFO): Sent 184 bytes from file Logs/20220131T175324/Express0001.lzma
2022-01-31T17:57:37.601Z,1643651857.601 [NAL9602](INFO): Packets left to send: 6
2022-01-31T17:57:48.226Z,1643651868.226 [NAL9602](INFO): SBD MO Status=1, MOMSN=35452, MT Status=0, MTMSN=0
2022-01-31T17:57:48.285Z,1643651868.285 [NAL9602](INFO): Sent 184 bytes from file Logs/20220131T175324/Express0001.lzma
2022-01-31T17:57:48.285Z,1643651868.285 [NAL9602](INFO): Packets left to send: 5
2022-01-31T17:57:56.518Z,1643651876.518 [NAL9602](INFO): SBD MO Status=1, MOMSN=35453, MT Status=0, MTMSN=0
2022-01-31T17:57:56.572Z,1643651876.572 [NAL9602](INFO): Sent 184 bytes from file Logs/20220131T175324/Express0001.lzma
2022-01-31T17:57:56.572Z,1643651876.572 [NAL9602](INFO): Packets left to send: 4
2022-01-31T17:58:05.597Z,1643651885.597 [NAL9602](INFO): SBD MO Status=1, MOMSN=35454, MT Status=0, MTMSN=0
2022-01-31T17:58:05.648Z,1643651885.648 [NAL9602](INFO): Sent 184 bytes from file Logs/20220131T175324/Express0001.lzma
2022-01-31T17:58:05.648Z,1643651885.648 [NAL9602](INFO): Packets left to send: 3
2022-01-31T17:58:18.283Z,1643651898.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=35455, MT Status=2, MTMSN=0
2022-01-31T17:58:18.283Z,1643651898.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-01-31T17:58:38.721Z,1643651918.721 [NAL9602](INFO): SBD MO Status=1, MOMSN=35455, MT Status=0, MTMSN=0
2022-01-31T17:58:38.769Z,1643651918.769 [NAL9602](INFO): Sent 184 bytes from file Logs/20220131T175324/Express0001.lzma
2022-01-31T17:58:38.769Z,1643651918.769 [NAL9602](INFO): Packets left to send: 2
2022-01-31T17:58:46.249Z,1643651926.249 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004254
2022-01-31T17:58:50.187Z,1643651930.187 [NAL9602](INFO): SBD MO Status=1, MOMSN=35456, MT Status=0, MTMSN=0
2022-01-31T17:58:50.236Z,1643651930.236 [NAL9602](INFO): Sent 184 bytes from file Logs/20220131T175324/Express0001.lzma
2022-01-31T17:58:50.236Z,1643651930.236 [NAL9602](INFO): Packets left to send: 1
2022-01-31T17:58:50.266Z,1643651930.266 [DataOverHttps](INFO): Sending 1110 bytes from file Logs/20220131T175324/Express0001.lzma
2022-01-31T17:58:50.300Z,1643651930.300 [Default:CheckIn:Read_Iridium] Stopped
2022-01-31T17:58:50.300Z,1643651930.300 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-31T17:58:50.300Z,1643651930.300 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-31T17:58:51.266Z,1643651931.266 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Express0001.lzma.bak
2022-01-31T17:58:51.266Z,1643651931.266 [DataOverHttps](INFO): SBD MOMSN=1375241
2022-01-31T17:59:20.866Z,1643651960.866 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-31T18:00:57.441Z,1643652057.441 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-01-31T18:00:57.443Z,1643652057.443 [BPC1](INFO): Received data from all battery sticks.
2022-01-31T18:03:50.813Z,1643652230.813 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-31T18:03:50.813Z,1643652230.813 [Default:CheckIn:C.Wait] Stopped
2022-01-31T18:03:50.813Z,1643652230.813 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-31T18:03:50.813Z,1643652230.813 [Default:CheckIn:D] Running Loop=1
2022-01-31T18:03:51.169Z,1643652231.169 [Default:CheckIn:D] Stopped
2022-01-31T18:03:51.170Z,1643652231.170 [Default:CheckIn:E] Running Loop=1
2022-01-31T18:03:51.584Z,1643652231.584 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.543622 min 
2022-01-31T18:03:51.584Z,1643652231.584 [Default:CheckIn:E] Stopped
2022-01-31T18:03:51.584Z,1643652231.584 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-31T18:03:51.584Z,1643652231.584 [Default:CheckIn] Stopped
2022-01-31T18:03:51.585Z,1643652231.585 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-31T18:03:51.585Z,1643652231.585 [Default:CheckIn](INFO): Running loop #2
2022-01-31T18:03:51.585Z,1643652231.585 [Default:CheckIn] Running Loop=2
2022-01-31T18:03:51.585Z,1643652231.585 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-31T18:03:51.585Z,1643652231.585 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-31T18:03:53.619Z,1643652233.619 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180355.00,A,4128.96299,N,07052.77952,W,0.311,265.75,310122,,,A*79
2022-01-31T18:03:53.636Z,1643652233.636 [NAL9602](INFO): GPS fix at 20220131T180355: (41.482717, -70.879659)
2022-01-31T18:03:53.714Z,1643652233.714 [Default:CheckIn:Read_GPS] Stopped
2022-01-31T18:03:53.714Z,1643652233.714 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-31T18:03:57.598Z,1643652237.598 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20220131T175324/Courier0007.lzma
2022-01-31T18:03:58.593Z,1643652238.593 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Courier0007.lzma.bak
2022-01-31T18:03:58.594Z,1643652238.594 [DataOverHttps](INFO): SBD MOMSN=1375321
2022-01-31T18:04:06.919Z,1643652246.919 [NAL9602](INFO): SBD MO Status=0, MOMSN=35457, MT Status=0, MTMSN=0
2022-01-31T18:04:06.919Z,1643652246.919 [NAL9602](INFO): No messages in MT queue
2022-01-31T18:04:24.699Z,1643652264.699 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20220131T175324/Express0005.lzma
2022-01-31T18:04:26.706Z,1643652266.706 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Express0005.lzma.bak
2022-01-31T18:04:26.706Z,1643652266.706 [DataOverHttps](INFO): SBD MOMSN=1375325
2022-01-31T18:04:37.634Z,1643652277.634 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-31T18:04:45.783Z,1643652285.783 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20220131T175324/Express0008.lzma
2022-01-31T18:04:51.829Z,1643652291.829 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Express0008.lzma.bak
2022-01-31T18:04:51.829Z,1643652291.829 [DataOverHttps](INFO): SBD MOMSN=1375328
2022-01-31T18:04:53.396Z,1643652293.396 [Default:CheckIn:Read_Iridium] Stopped
2022-01-31T18:04:53.396Z,1643652293.396 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-31T18:04:53.396Z,1643652293.396 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-31T18:09:53.995Z,1643652593.995 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-31T18:09:53.995Z,1643652593.995 [Default:CheckIn:C.Wait] Stopped
2022-01-31T18:09:53.995Z,1643652593.995 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-31T18:09:53.995Z,1643652593.995 [Default:CheckIn:D] Running Loop=1
2022-01-31T18:09:54.385Z,1643652594.385 [Default:CheckIn:D] Stopped
2022-01-31T18:09:54.385Z,1643652594.385 [Default:CheckIn:E] Running Loop=1
2022-01-31T18:09:54.774Z,1643652594.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.597213 min 
2022-01-31T18:09:54.774Z,1643652594.774 [Default:CheckIn:E] Stopped
2022-01-31T18:09:54.774Z,1643652594.774 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-31T18:09:54.774Z,1643652594.774 [Default:CheckIn] Stopped
2022-01-31T18:09:54.774Z,1643652594.774 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-31T18:09:54.775Z,1643652594.775 [Default:CheckIn](INFO): Running loop #3
2022-01-31T18:09:54.775Z,1643652594.775 [Default:CheckIn] Running Loop=3
2022-01-31T18:09:54.775Z,1643652594.775 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-31T18:09:54.775Z,1643652594.775 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-31T18:09:56.785Z,1643652596.785 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180958.00,A,4128.95627,N,07052.86203,W,0.505,261.12,310122,,,A*7B
2022-01-31T18:09:56.787Z,1643652596.787 [NAL9602](INFO): GPS fix at 20220131T180958: (41.482605, -70.881034)
2022-01-31T18:09:56.800Z,1643652596.800 [Default:CheckIn:Read_GPS] Stopped
2022-01-31T18:09:56.800Z,1643652596.800 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-31T18:10:01.079Z,1643652601.079 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220131T175324/Courier0010.lzma
2022-01-31T18:10:02.081Z,1643652602.081 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Courier0010.lzma.bak
2022-01-31T18:10:02.082Z,1643652602.082 [DataOverHttps](INFO): SBD MOMSN=1375396
2022-01-31T18:10:11.119Z,1643652611.119 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220131T175324/Express0011.lzma
2022-01-31T18:10:12.121Z,1643652612.121 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Express0011.lzma.bak
2022-01-31T18:10:12.122Z,1643652612.122 [DataOverHttps](INFO): SBD MOMSN=1375399
2022-01-31T18:10:12.147Z,1643652612.147 [NAL9602](INFO): SBD MO Status=0, MOMSN=35458, MT Status=0, MTMSN=0
2022-01-31T18:10:12.147Z,1643652612.147 [NAL9602](INFO): No messages in MT queue
2022-01-31T18:10:13.798Z,1643652613.798 [Default:CheckIn:Read_Iridium] Stopped
2022-01-31T18:10:13.798Z,1643652613.798 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-31T18:10:13.798Z,1643652613.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-31T18:10:42.845Z,1643652642.845 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-31T18:15:14.349Z,1643652914.349 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-31T18:15:14.349Z,1643652914.349 [Default:CheckIn:C.Wait] Stopped
2022-01-31T18:15:14.349Z,1643652914.349 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-31T18:15:14.349Z,1643652914.349 [Default:CheckIn:D] Running Loop=1
2022-01-31T18:15:14.746Z,1643652914.746 [Default:CheckIn:D] Stopped
2022-01-31T18:15:14.746Z,1643652914.746 [Default:CheckIn:E] Running Loop=1
2022-01-31T18:15:15.153Z,1643652915.153 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.936566 min 
2022-01-31T18:15:15.153Z,1643652915.153 [Default:CheckIn:E] Stopped
2022-01-31T18:15:15.153Z,1643652915.153 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-31T18:15:15.153Z,1643652915.153 [Default:CheckIn] Stopped
2022-01-31T18:15:15.153Z,1643652915.153 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-31T18:15:15.153Z,1643652915.153 [Default:CheckIn](INFO): Running loop #4
2022-01-31T18:15:15.153Z,1643652915.153 [Default:CheckIn] Running Loop=4
2022-01-31T18:15:15.154Z,1643652915.154 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-31T18:15:15.154Z,1643652915.154 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-31T18:15:17.161Z,1643652917.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181519.00,A,4128.94929,N,07052.93285,W,0.447,274.69,310122,,,A*76
2022-01-31T18:15:17.163Z,1643652917.163 [NAL9602](INFO): GPS fix at 20220131T181519: (41.482488, -70.882214)
2022-01-31T18:15:17.191Z,1643652917.191 [Default:CheckIn:Read_GPS] Stopped
2022-01-31T18:15:17.191Z,1643652917.191 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-31T18:15:21.371Z,1643652921.371 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20220131T175324/Courier0013.lzma
2022-01-31T18:15:22.374Z,1643652922.374 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Courier0013.lzma.bak
2022-01-31T18:15:22.374Z,1643652922.374 [DataOverHttps](INFO): SBD MOMSN=1375446
2022-01-31T18:15:31.412Z,1643652931.412 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220131T175324/Express0014.lzma
2022-01-31T18:15:32.414Z,1643652932.414 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Express0014.lzma.bak
2022-01-31T18:15:32.414Z,1643652932.414 [DataOverHttps](INFO): SBD MOMSN=1375449
2022-01-31T18:15:34.188Z,1643652934.188 [Default:CheckIn:Read_Iridium] Stopped
2022-01-31T18:15:34.188Z,1643652934.188 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-31T18:15:34.189Z,1643652934.189 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-31T18:15:34.563Z,1643652934.563 [NAL9602](INFO): SBD MO Status=0, MOMSN=35459, MT Status=0, MTMSN=0
2022-01-31T18:15:34.563Z,1643652934.563 [NAL9602](INFO): No messages in MT queue
2022-01-31T18:16:05.265Z,1643652965.265 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-31T18:18:40.673Z,1643653120.673 [CommandExec](IMPORTANT): got command get platform_pitch_angle degree
2022-01-31T18:18:40.674Z,1643653120.674 [CommandExec](IMPORTANT): platform_pitch_angle -0.501658 arcdeg
2022-01-31T18:18:45.467Z,1643653125.467 [CommandExec](IMPORTANT): got command get platform_pitch_angle degree
2022-01-31T18:18:45.467Z,1643653125.467 [CommandExec](IMPORTANT): platform_pitch_angle -1.842065 arcdeg
2022-01-31T18:19:19.861Z,1643653159.861 [CommandExec](IMPORTANT): got command get depth
2022-01-31T18:19:19.862Z,1643653159.862 [CommandExec](IMPORTANT): depth 0.572866 m
2022-01-31T18:19:22.530Z,1643653162.530 [CommandExec](IMPORTANT): got command show best depth
2022-01-31T18:19:22.531Z,1643653162.531 [CommandExec](IMPORTANT): depth best is Depth_Keller.depth
2022-01-31T18:20:34.744Z,1643653234.744 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-31T18:20:34.744Z,1643653234.744 [Default:CheckIn:C.Wait] Stopped
2022-01-31T18:20:34.745Z,1643653234.745 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-31T18:20:34.745Z,1643653234.745 [Default:CheckIn:D] Running Loop=1
2022-01-31T18:20:35.157Z,1643653235.157 [Default:CheckIn:D] Stopped
2022-01-31T18:20:35.157Z,1643653235.157 [Default:CheckIn:E] Running Loop=1
2022-01-31T18:20:35.593Z,1643653235.593 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.276748 min 
2022-01-31T18:20:35.593Z,1643653235.593 [Default:CheckIn:E] Stopped
2022-01-31T18:20:35.593Z,1643653235.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-31T18:20:35.593Z,1643653235.593 [Default:CheckIn] Stopped
2022-01-31T18:20:35.594Z,1643653235.594 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-31T18:20:35.594Z,1643653235.594 [Default:CheckIn](INFO): Running loop #5
2022-01-31T18:20:35.594Z,1643653235.594 [Default:CheckIn] Running Loop=5
2022-01-31T18:20:35.594Z,1643653235.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-31T18:20:35.594Z,1643653235.594 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-31T18:20:37.567Z,1643653237.567 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182039.00,A,4128.94291,N,07053.00345,W,0.719,261.94,310122,,,A*72
2022-01-31T18:20:37.573Z,1643653237.573 [NAL9602](INFO): GPS fix at 20220131T182039: (41.482382, -70.883391)
2022-01-31T18:20:37.622Z,1643653237.622 [Default:CheckIn:Read_GPS] Stopped
2022-01-31T18:20:37.622Z,1643653237.622 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-31T18:20:41.703Z,1643653241.703 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20220131T175324/Courier0016.lzma
2022-01-31T18:20:42.706Z,1643653242.706 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Courier0016.lzma.bak
2022-01-31T18:20:42.706Z,1643653242.706 [DataOverHttps](INFO): SBD MOMSN=1375473
2022-01-31T18:20:51.744Z,1643653251.744 [DataOverHttps](INFO): Sending 296 bytes from file Logs/20220131T175324/Express0017.lzma
2022-01-31T18:20:52.745Z,1643653252.745 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Express0017.lzma.bak
2022-01-31T18:20:52.746Z,1643653252.746 [DataOverHttps](INFO): SBD MOMSN=1375476
2022-01-31T18:20:53.322Z,1643653253.322 [NAL9602](INFO): SBD MO Status=2, MOMSN=35460, MT Status=2, MTMSN=0
2022-01-31T18:20:53.323Z,1643653253.323 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-01-31T18:20:54.549Z,1643653254.549 [Default:CheckIn:Read_Iridium] Stopped
2022-01-31T18:20:54.549Z,1643653254.549 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-31T18:20:54.549Z,1643653254.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-31T18:21:03.417Z,1643653263.417 [NAL9602](INFO): SBD MO Status=0, MOMSN=35460, MT Status=0, MTMSN=0
2022-01-31T18:21:03.417Z,1643653263.417 [NAL9602](INFO): No messages in MT queue
2022-01-31T18:21:34.118Z,1643653294.118 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-31T18:25:09.814Z,1643653509.814 [DataOverHttps](IMPORTANT): SBD MTMSN=20220131T182512
2022-01-31T18:25:17.848Z,1643653517.848 [DataOverHttps](INFO): Received command: configSet PAR_Licor.loadAtStartup 0 bool persist;configSet RDI_Pathfinder.loadAtStartup 0 bool persist;configSet WetLabsSeaOWL_UV_A.loadAtStartup 0 bool persist;configSet CTD_Seabird.loadAtStartup 0 bool persist; 
2022-01-31T18:25:18.840Z,1643653518.840 [CommandExec](IMPORTANT): got command configSet PAR_Licor.loadAtStartup 0.000000 bool persist
2022-01-31T18:25:18.841Z,1643653518.841 [CommandExec](IMPORTANT): configSet PAR_Licor.loadAtStartup requires a restart to take effect.
2022-01-31T18:25:18.843Z,1643653518.843 [CommandExec](IMPORTANT): got command configSet RDI_Pathfinder.loadAtStartup 0.000000 bool persist
2022-01-31T18:25:18.844Z,1643653518.844 [CommandExec](IMPORTANT): configSet RDI_Pathfinder.loadAtStartup requires a restart to take effect.
2022-01-31T18:25:18.846Z,1643653518.846 [CommandExec](IMPORTANT): got command configSet WetLabsSeaOWL_UV_A.loadAtStartup 0.000000 bool persist
2022-01-31T18:25:18.847Z,1643653518.847 [CommandExec](IMPORTANT): configSet WetLabsSeaOWL_UV_A.loadAtStartup requires a restart to take effect.
2022-01-31T18:25:18.850Z,1643653518.850 [CommandExec](IMPORTANT): got command configSet CTD_Seabird.loadAtStartup 0.000000 bool persist
2022-01-31T18:25:18.851Z,1643653518.851 [CommandExec](IMPORTANT): configSet CTD_Seabird.loadAtStartup requires a restart to take effect.
2022-01-31T18:25:18.853Z,1643653518.853 [CommandExec](IMPORTANT): got command 
2022-01-31T18:25:18.853Z,1643653518.853 [CommandExec](FAULT): Syntax error. Try: help
2022-01-31T18:25:54.005Z,1643653554.005 [DataOverHttps](IMPORTANT): SBD MTMSN=20220131T182556
2022-01-31T18:25:55.157Z,1643653555.157 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-31T18:25:55.158Z,1643653555.158 [Default:CheckIn:C.Wait] Stopped
2022-01-31T18:25:55.158Z,1643653555.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-31T18:25:55.158Z,1643653555.158 [Default:CheckIn:D] Running Loop=1
2022-01-31T18:25:55.559Z,1643653555.559 [Default:CheckIn:D] Stopped
2022-01-31T18:25:55.560Z,1643653555.560 [Default:CheckIn:E] Running Loop=1
2022-01-31T18:25:56.034Z,1643653556.034 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.616785 min 
2022-01-31T18:25:56.034Z,1643653556.034 [Default:CheckIn:E] Stopped
2022-01-31T18:25:56.034Z,1643653556.034 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-31T18:25:56.034Z,1643653556.034 [Default:CheckIn] Stopped
2022-01-31T18:25:56.035Z,1643653556.035 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-31T18:25:56.035Z,1643653556.035 [Default:CheckIn](INFO): Running loop #6
2022-01-31T18:25:56.035Z,1643653556.035 [Default:CheckIn] Running Loop=6
2022-01-31T18:25:56.035Z,1643653556.035 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-31T18:25:56.035Z,1643653556.035 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-31T18:25:57.943Z,1643653557.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182600.00,A,4128.93638,N,07053.07145,W,0.778,262.88,310122,,,A*72
2022-01-31T18:25:57.946Z,1643653557.946 [NAL9602](INFO): GPS fix at 20220131T182600: (41.482273, -70.884524)
2022-01-31T18:25:57.977Z,1643653557.977 [Default:CheckIn:Read_GPS] Stopped
2022-01-31T18:25:57.977Z,1643653557.977 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-31T18:26:00.098Z,1643653560.098 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20220131T175324/Courier0019.lzma
2022-01-31T18:26:00.110Z,1643653560.110 [DataOverHttps](INFO): Received command: restart app
2022-01-31T18:26:00.145Z,1643653560.145 [CommandExec](IMPORTANT): got command restart application
2022-01-31T18:26:01.148Z,1643653561.148 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-01-31T18:26:01.148Z,1643653561.148 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:01.149Z,1643653561.149 [CommandExec](INFO): Uninitializing the command executive.
2022-01-31T18:26:01.149Z,1643653561.149 [CommandExec](INFO): Uninitializing the command scheduler.
2022-01-31T18:26:01.149Z,1643653561.149 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:01.232Z,1643653561.232 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-01-31T18:26:01.232Z,1643653561.232 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-01-31T18:26:01.232Z,1643653561.232 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:01.233Z,1643653561.233 [NavChartDb](INFO): Join timeout helper Thread ID is 6117
2022-01-31T18:26:01.384Z,1643653561.384 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:01.385Z,1643653561.385 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:01.412Z,1643653561.412 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2022-01-31T18:26:01.412Z,1643653561.412 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:01.412Z,1643653561.412 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 6118
2022-01-31T18:26:01.724Z,1643653561.724 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:01.724Z,1643653561.724 [WetLabsSeaOWL_UV_A](INFO): Powering down
2022-01-31T18:26:01.725Z,1643653561.725 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:01.744Z,1643653561.744 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-01-31T18:26:01.744Z,1643653561.744 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:01.745Z,1643653561.745 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6119
2022-01-31T18:26:02.116Z,1643653562.116 [CTD_Seabird](INFO): Powering down
2022-01-31T18:26:02.119Z,1643653562.119 [DataOverHttps](INFO): Moved sent file to Logs/20220131T175324/Courier0019.lzma.bak
2022-01-31T18:26:02.120Z,1643653562.120 [DataOverHttps](INFO): SBD MOMSN=1375535
2022-01-31T18:26:02.128Z,1643653562.128 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:02.128Z,1643653562.128 [CTD_Seabird](INFO): Powering down
2022-01-31T18:26:02.140Z,1643653562.140 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:02.144Z,1643653562.144 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-01-31T18:26:02.144Z,1643653562.144 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:02.145Z,1643653562.145 [Radio_Surface](INFO): Join timeout helper Thread ID is 6120
2022-01-31T18:26:02.520Z,1643653562.520 [Radio_Surface](INFO): Powering down
2022-01-31T18:26:02.521Z,1643653562.521 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:02.521Z,1643653562.521 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:02.540Z,1643653562.540 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-01-31T18:26:02.540Z,1643653562.540 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:02.540Z,1643653562.540 [Onboard](INFO): Join timeout helper Thread ID is 6121
2022-01-31T18:26:03.284Z,1643653563.284 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-01-31T18:26:03.596Z,1643653563.596 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:03.597Z,1643653563.597 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:03.614Z,1643653563.614 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-01-31T18:26:03.614Z,1643653563.614 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:03.615Z,1643653563.615 [DataOverHttps](INFO): Join timeout helper Thread ID is 6122
2022-01-31T18:26:04.124Z,1643653564.124 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:04.124Z,1643653564.124 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.129Z,1643653564.129 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-01-31T18:26:04.129Z,1643653564.129 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.129Z,1643653564.129 [BackseatComponent](INFO): Join timeout helper Thread ID is 6123
2022-01-31T18:26:04.148Z,1643653564.148 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:04.148Z,1643653564.148 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.168Z,1643653564.168 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-01-31T18:26:04.169Z,1643653564.169 [logger ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.169Z,1643653564.169 [logger](INFO): Join timeout helper Thread ID is 6124
2022-01-31T18:26:04.172Z,1643653564.172 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:04.172Z,1643653564.172 [logger ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.188Z,1643653564.188 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-01-31T18:26:04.189Z,1643653564.189 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.189Z,1643653564.189 [CommandLine](INFO): Join timeout helper Thread ID is 6125
2022-01-31T18:26:04.252Z,1643653564.252 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:04.252Z,1643653564.252 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.269Z,1643653564.269 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-01-31T18:26:04.269Z,1643653564.269 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.269Z,1643653564.269 [CommandExec](INFO): Join timeout helper Thread ID is 6126
2022-01-31T18:26:04.275Z,1643653564.275 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-01-31T18:26:04.275Z,1643653564.275 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.275Z,1643653564.275 [controlThread](INFO): Join timeout helper Thread ID is 6127
2022-01-31T18:26:04.632Z,1643653564.632 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-31T18:26:04.632Z,1643653564.632 [controlThread](DEBUG): Uninitializing ControlThread
2022-01-31T18:26:04.633Z,1643653564.633 [AHRS_M2](INFO): Powering down
2022-01-31T18:26:04.705Z,1643653564.705 [NAL9602](INFO): Powering down
2022-01-31T18:26:04.776Z,1643653564.776 [RDI_Pathfinder](INFO): Powering down
2022-01-31T18:26:04.777Z,1643653564.777 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-01-31T18:26:04.778Z,1643653564.778 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-01-31T18:26:04.779Z,1643653564.779 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-01-31T18:26:04.780Z,1643653564.780 [MissionManager](INFO): Uninitializing Mission Default
2022-01-31T18:26:04.780Z,1643653564.780 [Default] Stopped
2022-01-31T18:26:04.780Z,1643653564.780 [Default](DEBUG): Aggregate::uninitialize Default
2022-01-31T18:26:04.780Z,1643653564.780 [Default:B.GoToSurface] Stopped
2022-01-31T18:26:04.780Z,1643653564.780 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-01-31T18:26:04.780Z,1643653564.780 [Default:CheckIn] Stopped
2022-01-31T18:26:04.780Z,1643653564.780 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-31T18:26:04.780Z,1643653564.780 [Default:CheckIn:Read_Iridium] Stopped
2022-01-31T18:26:04.783Z,1643653564.783 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-01-31T18:26:04.783Z,1643653564.783 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-01-31T18:26:04.784Z,1643653564.784 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-01-31T18:26:04.784Z,1643653564.784 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-01-31T18:26:04.784Z,1643653564.784 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-01-31T18:26:04.785Z,1643653564.785 [BuoyancyServo](INFO): Powering down
2022-01-31T18:26:04.796Z,1643653564.796 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-01-31T18:26:04.796Z,1643653564.796 [ElevatorServo](INFO): Powering down
2022-01-31T18:26:04.797Z,1643653564.797 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-01-31T18:26:04.797Z,1643653564.797 [MassServo](INFO): Powering down
2022-01-31T18:26:04.798Z,1643653564.798 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-01-31T18:26:04.798Z,1643653564.798 [RudderServo](INFO): Powering down
2022-01-31T18:26:04.799Z,1643653564.799 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-01-31T18:26:04.799Z,1643653564.799 [ThrusterHE](INFO): Powering down
2022-01-31T18:26:04.800Z,1643653564.800 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-01-31T18:26:04.800Z,1643653564.800 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-01-31T18:26:04.800Z,1643653564.800 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-01-31T18:26:04.800Z,1643653564.800 [CBIT](DEBUG): Powering off loads.
2022-01-31T18:26:04.812Z,1643653564.812 [CBIT](DEBUG): Disabling WDT.
2022-01-31T18:26:04.824Z,1643653564.824 [CBIT](DEBUG): Opening all GF detection circuits.
2022-01-31T18:26:04.825Z,1643653564.825 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.836Z,1643653564.836 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.839Z,1643653564.839 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.845Z,1643653564.845 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.888Z,1643653564.888 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.935Z,1643653564.935 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.939Z,1643653564.939 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:04.996Z,1643653564.996 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-01-31T18:26:05.062Z,1643653565.062 [logger ThreadHandler](INFO): Thread cancelled.