2022-02-17T00:14:28.052Z,1645056868.052 [Supervisor](DEBUG): Initializing supervisor.
2022-02-17T00:14:28.056Z,1645056868.056 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-02-17T00:14:28.057Z,1645056868.057 [SyncHandler](INFO): Protected caller Thread ID is 7001
2022-02-17T00:14:28.057Z,1645056868.057 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-02-17T00:14:28.058Z,1645056868.058 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-02-17T00:14:28.059Z,1645056868.059 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7002
2022-02-17T00:14:28.063Z,1645056868.063 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-02-17T00:14:28.081Z,1645056868.081 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-02-17T00:14:28.082Z,1645056868.082 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-02-17T00:14:28.082Z,1645056868.082 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7003
2022-02-17T00:14:28.086Z,1645056868.086 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-02-17T00:14:28.087Z,1645056868.087 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-02-17T00:14:28.088Z,1645056868.088 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7004
2022-02-17T00:14:28.090Z,1645056868.090 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-02-17T00:14:28.091Z,1645056868.091 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-02-17T00:14:28.091Z,1645056868.091 [logger ThreadHandler](INFO): Protected caller Thread ID is 7005
2022-02-17T00:14:28.095Z,1645056868.095 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-02-17T00:14:28.095Z,1645056868.095 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-02-17T00:14:28.097Z,1645056868.097 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-02-17T00:14:28.510Z,1645056868.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-02-17T00:14:28.511Z,1645056868.511 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-02-17T00:14:28.720Z,1645056868.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-02-17T00:14:28.720Z,1645056868.720 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-02-17T00:14:28.904Z,1645056868.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-02-17T00:14:28.904Z,1645056868.904 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-02-17T00:14:29.008Z,1645056869.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-02-17T00:14:29.009Z,1645056869.009 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-02-17T00:14:29.363Z,1645056869.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-02-17T00:14:29.364Z,1645056869.364 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-02-17T00:14:29.684Z,1645056869.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-02-17T00:14:29.684Z,1645056869.684 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-02-17T00:14:29.778Z,1645056869.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-02-17T00:14:29.778Z,1645056869.778 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-02-17T00:14:29.923Z,1645056869.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-02-17T00:14:29.924Z,1645056869.924 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-02-17T00:14:30.446Z,1645056870.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-02-17T00:14:30.446Z,1645056870.446 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-02-17T00:14:30.969Z,1645056870.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-02-17T00:14:30.970Z,1645056870.970 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-02-17T00:14:31.100Z,1645056871.100 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-02-17T00:14:31.379Z,1645056871.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-02-17T00:14:31.379Z,1645056871.379 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-02-17T00:14:31.505Z,1645056871.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-02-17T00:14:31.505Z,1645056871.505 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-02-17T00:14:32.194Z,1645056872.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-02-17T00:14:32.195Z,1645056872.195 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-02-17T00:14:32.272Z,1645056872.272 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-02-17T00:14:32.549Z,1645056872.549 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-02-17T00:14:32.551Z,1645056872.551 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2022-02-17T00:14:32.552Z,1645056872.552 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2022-02-17T00:14:32.722Z,1645056872.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2022-02-17T00:14:32.812Z,1645056872.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2022-02-17T00:14:32.921Z,1645056872.921 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2022-02-17T00:14:33.018Z,1645056873.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2022-02-17T00:14:33.099Z,1645056873.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2022-02-17T00:14:33.182Z,1645056873.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2022-02-17T00:14:33.293Z,1645056873.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2022-02-17T00:14:33.390Z,1645056873.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2022-02-17T00:14:33.540Z,1645056873.540 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2022-02-17T00:14:33.797Z,1645056873.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-02-17T00:14:33.798Z,1645056873.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2022-02-17T00:14:33.890Z,1645056873.890 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-02-17T00:14:33.905Z,1645056873.905 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-02-17T00:14:34.228Z,1645056874.228 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-02-17T00:14:34.228Z,1645056874.228 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-02-17T00:14:34.342Z,1645056874.342 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-02-17T00:14:34.342Z,1645056874.342 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-02-17T00:14:34.379Z,1645056874.379 [DeadReckonUsingSpeedCalculator] Loaded
2022-02-17T00:14:34.379Z,1645056874.379 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2022-02-17T00:14:34.392Z,1645056874.392 [NavChart] Loaded
2022-02-17T00:14:34.393Z,1645056874.393 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-02-17T00:14:34.398Z,1645056874.398 [UniversalFixResidualReporter] Loaded
2022-02-17T00:14:34.398Z,1645056874.398 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-02-17T00:14:34.399Z,1645056874.399 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-02-17T00:14:34.399Z,1645056874.399 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-02-17T00:14:34.410Z,1645056874.410 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-02-17T00:14:34.411Z,1645056874.411 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-02-17T00:14:34.606Z,1645056874.606 [CTD_Seabird] Loaded
2022-02-17T00:14:34.606Z,1645056874.606 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-02-17T00:14:34.607Z,1645056874.607 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2022-02-17T00:14:34.608Z,1645056874.608 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7087
2022-02-17T00:14:34.627Z,1645056874.627 [PAR_Licor] Loaded
2022-02-17T00:14:34.627Z,1645056874.627 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-02-17T00:14:34.657Z,1645056874.657 [WetLabsBB2FL] Loaded
2022-02-17T00:14:34.658Z,1645056874.658 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-02-17T00:14:34.659Z,1645056874.659 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406914E0
2022-02-17T00:14:34.659Z,1645056874.659 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7088
2022-02-17T00:14:34.660Z,1645056874.660 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-02-17T00:14:34.660Z,1645056874.660 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-02-17T00:14:34.714Z,1645056874.714 [VerticalControl](DEBUG): Construct VerticalControl.
2022-02-17T00:14:34.774Z,1645056874.774 [VerticalControl] Loaded
2022-02-17T00:14:34.774Z,1645056874.774 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-02-17T00:14:34.777Z,1645056874.777 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-02-17T00:14:34.815Z,1645056874.815 [HorizontalControl] Loaded
2022-02-17T00:14:34.815Z,1645056874.815 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-02-17T00:14:34.817Z,1645056874.817 [SpeedControl](DEBUG): Construct SpeedControl.
2022-02-17T00:14:34.821Z,1645056874.821 [SpeedControl] Loaded
2022-02-17T00:14:34.821Z,1645056874.821 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-02-17T00:14:34.823Z,1645056874.823 [LoopControl](DEBUG): Construct LoopControl.
2022-02-17T00:14:34.824Z,1645056874.824 [LoopControl] Loaded
2022-02-17T00:14:34.824Z,1645056874.824 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-02-17T00:14:34.825Z,1645056874.825 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-02-17T00:14:34.825Z,1645056874.825 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-02-17T00:14:34.867Z,1645056874.867 [DepthRateCalculator] Loaded
2022-02-17T00:14:34.868Z,1645056874.868 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-02-17T00:14:34.872Z,1645056874.872 [PitchRateCalculator] Loaded
2022-02-17T00:14:34.873Z,1645056874.873 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-02-17T00:14:34.882Z,1645056874.882 [SpeedCalculator] Loaded
2022-02-17T00:14:34.882Z,1645056874.882 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-02-17T00:14:34.895Z,1645056874.895 [TempGradientCalculator] Loaded
2022-02-17T00:14:34.895Z,1645056874.895 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-02-17T00:14:34.899Z,1645056874.899 [YawRateCalculator] Loaded
2022-02-17T00:14:34.900Z,1645056874.900 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-02-17T00:14:34.917Z,1645056874.917 [ElevatorOffsetCalculator] Loaded
2022-02-17T00:14:34.917Z,1645056874.917 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-02-17T00:14:34.918Z,1645056874.918 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-02-17T00:14:34.918Z,1645056874.918 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-02-17T00:14:35.016Z,1645056875.016 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-02-17T00:14:35.016Z,1645056875.016 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-02-17T00:14:35.074Z,1645056875.074 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-02-17T00:14:35.075Z,1645056875.075 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-02-17T00:14:35.201Z,1645056875.201 [BuoyancyServo] Loaded
2022-02-17T00:14:35.201Z,1645056875.201 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-02-17T00:14:35.220Z,1645056875.220 [ElevatorServo] Loaded
2022-02-17T00:14:35.220Z,1645056875.220 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-02-17T00:14:35.239Z,1645056875.239 [MassServo] Loaded
2022-02-17T00:14:35.239Z,1645056875.239 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-02-17T00:14:35.257Z,1645056875.257 [RudderServo] Loaded
2022-02-17T00:14:35.258Z,1645056875.258 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-02-17T00:14:35.272Z,1645056875.272 [ThrusterHE] Loaded
2022-02-17T00:14:35.272Z,1645056875.272 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-02-17T00:14:35.273Z,1645056875.273 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-02-17T00:14:35.273Z,1645056875.273 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-02-17T00:14:35.376Z,1645056875.376 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-02-17T00:14:35.376Z,1645056875.376 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-02-17T00:14:35.782Z,1645056875.782 [AHRS_M2] Loaded
2022-02-17T00:14:35.782Z,1645056875.782 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-02-17T00:14:35.818Z,1645056875.818 [BackseatComponent] Loaded
2022-02-17T00:14:35.818Z,1645056875.818 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-02-17T00:14:35.819Z,1645056875.819 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0
2022-02-17T00:14:35.820Z,1645056875.820 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7089
2022-02-17T00:14:35.822Z,1645056875.822 [LcmUniversalReporter] Loaded
2022-02-17T00:14:35.823Z,1645056875.823 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-02-17T00:14:37.162Z,1645056877.162 [BPC1] Loaded
2022-02-17T00:14:37.162Z,1645056877.162 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-02-17T00:14:37.332Z,1645056877.332 [DataOverHttps] Loaded
2022-02-17T00:14:37.332Z,1645056877.332 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-02-17T00:14:37.333Z,1645056877.333 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AA74E0
2022-02-17T00:14:37.333Z,1645056877.333 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7090
2022-02-17T00:14:37.353Z,1645056877.353 [Depth_Keller] Loaded
2022-02-17T00:14:37.354Z,1645056877.354 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-02-17T00:14:37.414Z,1645056877.414 [NAL9602] Loaded
2022-02-17T00:14:37.415Z,1645056877.415 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-02-17T00:14:37.443Z,1645056877.443 [Onboard] Loaded
2022-02-17T00:14:37.444Z,1645056877.444 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-02-17T00:14:37.445Z,1645056877.445 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AD74E0
2022-02-17T00:14:37.445Z,1645056877.445 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7091
2022-02-17T00:14:37.457Z,1645056877.457 [Power24vConverter] Loaded
2022-02-17T00:14:37.457Z,1645056877.457 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-02-17T00:14:37.470Z,1645056877.470 [Radio_Surface] Loaded
2022-02-17T00:14:37.470Z,1645056877.470 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-02-17T00:14:37.471Z,1645056877.471 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B074E0
2022-02-17T00:14:37.471Z,1645056877.471 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7092
2022-02-17T00:14:37.555Z,1645056877.555 [DAT] Loaded
2022-02-17T00:14:37.555Z,1645056877.555 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-02-17T00:14:37.556Z,1645056877.556 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B374E0
2022-02-17T00:14:37.557Z,1645056877.557 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7093
2022-02-17T00:14:37.557Z,1645056877.557 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-02-17T00:14:37.557Z,1645056877.557 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-02-17T00:14:37.705Z,1645056877.705 [SBIT](DEBUG): Construct Startup Built In Test.
2022-02-17T00:14:37.714Z,1645056877.714 [SBIT] Loaded
2022-02-17T00:14:37.714Z,1645056877.714 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-02-17T00:14:37.717Z,1645056877.717 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-02-17T00:14:37.730Z,1645056877.730 [IBIT] Loaded
2022-02-17T00:14:37.730Z,1645056877.730 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-02-17T00:14:37.736Z,1645056877.736 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-02-17T00:14:37.838Z,1645056877.838 [CBIT] Loaded
2022-02-17T00:14:37.838Z,1645056877.838 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-02-17T00:14:37.838Z,1645056877.838 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-02-17T00:14:37.839Z,1645056877.839 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-02-17T00:14:37.867Z,1645056877.867 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-02-17T00:14:37.873Z,1645056877.873 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-02-17T00:14:37.876Z,1645056877.876 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-02-17T00:14:37.887Z,1645056877.887 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-02-17T00:14:37.888Z,1645056877.888 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC44E0
2022-02-17T00:14:37.888Z,1645056877.888 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7094
2022-02-17T00:14:37.893Z,1645056877.893 [Supervisor](INFO): Main Thread ID is 7000
2022-02-17T00:14:37.893Z,1645056877.893 [Supervisor](DEBUG): Running supervisor.
2022-02-17T00:14:37.893Z,1645056877.893 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7095
2022-02-17T00:14:37.894Z,1645056877.894 [CommandExec](INFO): Initializing the command executive.
2022-02-17T00:14:37.895Z,1645056877.895 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7096
2022-02-17T00:14:37.897Z,1645056877.897 [controlThread ThreadHandler](INFO): Handler Thread ID is 7097
2022-02-17T00:14:37.898Z,1645056877.898 [controlThread](DEBUG): Initializing ControlThread
2022-02-17T00:14:37.899Z,1645056877.899 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-02-17T00:14:37.900Z,1645056877.900 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-02-17T00:14:37.900Z,1645056877.900 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-02-17T00:14:37.904Z,1645056877.904 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-02-17T00:14:37.906Z,1645056877.906 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-02-17T00:14:37.906Z,1645056877.906 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-02-17T00:14:37.907Z,1645056877.907 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-02-17T00:14:37.907Z,1645056877.907 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-02-17T00:14:37.907Z,1645056877.907 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-02-17T00:14:37.908Z,1645056877.908 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-02-17T00:14:37.908Z,1645056877.908 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-02-17T00:14:37.909Z,1645056877.909 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-02-17T00:14:37.909Z,1645056877.909 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-02-17T00:14:37.912Z,1645056877.912 [SBIT](INFO): Initialize SBIT Component.
2022-02-17T00:14:37.913Z,1645056877.913 [SBIT](IMPORTANT): git: 2022-02-01-38-g2d821a05a
2022-02-17T00:14:37.913Z,1645056877.913 [SBIT](INFO): git hash: 2d821a05a5d7bd869793495f80484f76d730284c
2022-02-17T00:14:37.914Z,1645056877.914 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-02-17T00:14:37.915Z,1645056877.915 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-02-17T00:14:37.916Z,1645056877.916 [SBIT](INFO): Beginning SBIT in 38.000000 seconds.
2022-02-17T00:14:37.917Z,1645056877.917 [IBIT](INFO): Initialize IBIT Component.
2022-02-17T00:14:37.918Z,1645056877.918 [CBIT](DEBUG): Initialize CBIT Component.
2022-02-17T00:14:37.918Z,1645056877.918 [logger ThreadHandler](INFO): Handler Thread ID is 7098
2022-02-17T00:14:37.932Z,1645056877.932 [CBIT](DEBUG): Initialized mux pins.
2022-02-17T00:14:37.932Z,1645056877.932 [CBIT](DEBUG): Initializing the watchdog timer.
2022-02-17T00:14:37.936Z,1645056877.936 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7099
2022-02-17T00:14:37.937Z,1645056877.937 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-02-17T00:14:37.941Z,1645056877.941 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7101
2022-02-17T00:14:37.944Z,1645056877.944 [WetLabsBB2FL](INFO): Powering up
2022-02-17T00:14:37.946Z,1645056877.946 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7103
2022-02-17T00:14:37.952Z,1645056877.952 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7104
2022-02-17T00:14:37.953Z,1645056877.953 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-02-17T00:14:37.956Z,1645056877.956 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-02-17T00:14:37.956Z,1645056877.956 [CBIT](DEBUG): Initializing heartbeat.
2022-02-17T00:14:37.964Z,1645056877.964 [Onboard ThreadHandler](INFO): Handler Thread ID is 7105
2022-02-17T00:14:37.982Z,1645056877.982 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7106
2022-02-17T00:14:38.004Z,1645056878.004 [DAT ThreadHandler](INFO): Handler Thread ID is 7107
2022-02-17T00:14:38.005Z,1645056878.005 [DAT](INFO): Powering up
2022-02-17T00:14:38.005Z,1645056878.005 [DAT](DEBUG): Initializing DAT.
2022-02-17T00:14:38.009Z,1645056878.009 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7109
2022-02-17T00:14:38.012Z,1645056878.012 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-02-17T00:14:38.012Z,1645056878.012 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-02-17T00:14:38.013Z,1645056878.013 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-02-17T00:14:38.013Z,1645056878.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-02-17T00:14:38.013Z,1645056878.013 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-02-17T00:14:38.013Z,1645056878.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-02-17T00:14:38.013Z,1645056878.013 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-02-17T00:14:38.013Z,1645056878.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-02-17T00:14:38.014Z,1645056878.014 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-02-17T00:14:38.014Z,1645056878.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-02-17T00:14:38.014Z,1645056878.014 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-02-17T00:14:38.014Z,1645056878.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-02-17T00:14:38.014Z,1645056878.014 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-02-17T00:14:38.014Z,1645056878.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-02-17T00:14:38.015Z,1645056878.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-02-17T00:14:38.015Z,1645056878.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-02-17T00:14:38.028Z,1645056878.028 [CBIT](DEBUG): Deactivating GF circuits.
2022-02-17T00:14:38.028Z,1645056878.028 [CBIT](DEBUG): Deactivating emergency mode.
2022-02-17T00:14:38.064Z,1645056878.064 [CBIT](DEBUG): Backplane powered.
2022-02-17T00:14:38.065Z,1645056878.065 [MissionManager](INFO): Loading Mission from file: Missions/Startup_lab.xml
2022-02-17T00:14:38.076Z,1645056878.076 [Startup_lab:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-02-17T00:14:38.092Z,1645056878.092 [Startup_lab:B.Wait](DEBUG): Construct Wait.
2022-02-17T00:14:38.094Z,1645056878.094 [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_lab">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>
    
    <Guidance:Wait>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>3</Value></Setting>
    </Guidance:Wait>

</Mission>


2022-02-17T00:14:38.095Z,1645056878.095 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-02-17T00:14:38.171Z,1645056878.171 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-02-17T00:14:38.188Z,1645056878.188 [Default:A.Wait](DEBUG): Construct Wait.
2022-02-17T00:14:38.191Z,1645056878.191 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-02-17T00:14:38.232Z,1645056878.232 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-02-17T00:14:38.234Z,1645056878.234 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-02-17T00:14:38.252Z,1645056878.252 [Default:E.Execute](DEBUG): Construct Execute.
2022-02-17T00:14:38.264Z,1645056878.264 [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-02-17T00:14:38.269Z,1645056878.269 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-02-17T00:14:38.308Z,1645056878.308 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-02-17T00:14:38.371Z,1645056878.371 [Power24vConverter](INFO): Powering up.
2022-02-17T00:14:38.388Z,1645056878.388 [Radio_Surface](INFO): Powering up
2022-02-17T00:14:38.406Z,1645056878.406 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-02-17T00:14:38.413Z,1645056878.413 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-02-17T00:14:38.413Z,1645056878.413 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-02-17T00:14:38.420Z,1645056878.420 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-02-17T00:14:38.421Z,1645056878.421 [MassServo](DEBUG): Initializing EZServoServo.
2022-02-17T00:14:38.428Z,1645056878.428 [MassServo](DEBUG): Initializing MassServo.
2022-02-17T00:14:38.429Z,1645056878.429 [RudderServo](DEBUG): Initializing EZServoServo.
2022-02-17T00:14:38.436Z,1645056878.436 [RudderServo](DEBUG): Initializing RudderServo.
2022-02-17T00:14:38.437Z,1645056878.437 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-02-17T00:14:38.448Z,1645056878.448 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-02-17T00:14:40.556Z,1645056880.556 [WetLabsBB2FL](INFO): Powering down
2022-02-17T00:14:44.157Z,1645056884.157 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-02-17T00:14:44.969Z,1645056884.969 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-02-17T00:14:49.596Z,1645056889.596 [DAT](INFO): DAT read: 

2022-02-17T00:14:49.598Z,1645056889.598 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2022-02-17T00:14:51.112Z,1645056891.112 [DAT](INFO): DAT read: MF Frequency Band

2022-02-17T00:14:51.113Z,1645056891.113 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2022-02-17T00:14:51.114Z,1645056891.114 [DAT](INFO): DAT read: Feb 17 2022 00:14:46 

2022-02-17T00:14:52.369Z,1645056892.369 [DAT](INFO): DAT read: Features enabled [Bearing]

2022-02-17T00:14:52.370Z,1645056892.370 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2022-02-17T00:14:52.371Z,1645056892.371 [DAT](INFO): commRate: 800
2022-02-17T00:14:54.436Z,1645056894.436 [DAT](INFO): entering command mode
2022-02-17T00:14:54.636Z,1645056894.636 [DAT](INFO): DAT read: 

2022-02-17T00:14:54.637Z,1645056894.637 [DAT](INFO): DAT read: user:1>

2022-02-17T00:14:54.637Z,1645056894.637 [DAT](INFO): setting verbose to 3
2022-02-17T00:14:54.888Z,1645056894.888 [DAT](INFO): DAT read: user:1>

2022-02-17T00:14:54.889Z,1645056894.889 [DAT](INFO): DAT read: Verbose         | 3           

2022-02-17T00:14:54.890Z,1645056894.890 [DAT](INFO): set verbose to 3
2022-02-17T00:14:54.890Z,1645056894.890 [DAT](INFO): setting DatVerbose to 27440
2022-02-17T00:14:55.142Z,1645056895.142 [DAT](INFO): DAT read: user:2>

2022-02-17T00:14:55.146Z,1645056895.146 [DAT](INFO): DAT read: DatVerbose      | 27440       

2022-02-17T00:14:55.146Z,1645056895.146 [DAT](INFO): set DatVerbose to 27440
2022-02-17T00:14:55.147Z,1645056895.147 [DAT](INFO): setting transmit power to 8
2022-02-17T00:14:55.393Z,1645056895.393 [DAT](INFO): DAT read: user:3>

2022-02-17T00:14:55.394Z,1645056895.394 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2022-02-17T00:14:55.394Z,1645056895.394 [DAT](INFO): set transmit power to 8
2022-02-17T00:14:55.394Z,1645056895.394 [DAT](INFO): setting local address to 10
2022-02-17T00:14:55.645Z,1645056895.645 [DAT](INFO): DAT read: user:4>

2022-02-17T00:14:55.646Z,1645056895.646 [DAT](INFO): DAT read: LocalAddr       | 10          

2022-02-17T00:14:55.646Z,1645056895.646 [DAT](INFO): set local address to 10
2022-02-17T00:14:55.647Z,1645056895.647 [DAT](INFO): Setting time to: 0:14:55 And date to:2/17/2022
2022-02-17T00:14:55.896Z,1645056895.896 [DAT](INFO): DAT read: user:5>

2022-02-17T00:14:55.897Z,1645056895.897 [DAT](INFO): DAT read: Thu Feb 17, 2022  00:14:55  

2022-02-17T00:14:55.898Z,1645056895.898 [DAT](INFO): Local DAT time set to Thu Feb 17, 2022  00:14:55  

2022-02-17T00:15:07.780Z,1645056907.780 [NAL9602](INFO): Powering up NAL9602
2022-02-17T00:15:16.727Z,1645056916.727 [SBIT](IMPORTANT): Beginning Startup BIT
2022-02-17T00:15:16.731Z,1645056916.731 [CBIT](IMPORTANT): Beginning ground fault scan
2022-02-17T00:15:18.685Z,1645056918.685 [NAL9602](INFO): NAL9602 initialized
2022-02-17T00:15:27.661Z,1645056927.661 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000216
CHAN A1 (24V): 0.000795
CHAN A2 (12V): -0.005166
CHAN A3 (5V): -0.002511
CHAN B0 (3.3V): -0.000457
CHAN B1 (3.15aV): -0.001443
CHAN B2 (3.15bV): -0.001129
CHAN B3 (GND): -0.000657
OPEN: 0.004482
Full Scale: +/- 1 mA
2022-02-17T00:16:10.821Z,1645056970.821 [SBIT](IMPORTANT): SBIT PASSED
2022-02-17T00:16:10.822Z,1645056970.822 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-02-17T00:16:10.822Z,1645056970.822 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool;

2022-02-17T00:16:10.823Z,1645056970.823 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=6 none;

2022-02-17T00:16:10.823Z,1645056970.823 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere;

2022-02-17T00:16:10.823Z,1645056970.823 [SBIT](IMPORTANT): DAT.sbdAddress=21 enum;

2022-02-17T00:16:10.823Z,1645056970.823 [SBIT](IMPORTANT): DAT.surfaceThreshold=3 meter;

2022-02-17T00:16:10.823Z,1645056970.823 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2022-02-17T00:16:10.823Z,1645056970.823 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2022-02-17T00:16:10.823Z,1645056970.823 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 10.000000 gigabyte;

2022-02-17T00:16:10.823Z,1645056970.823 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 100.000000 count;

2022-02-17T00:16:10.824Z,1645056970.824 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_size 2.500000 millimeter;

2022-02-17T00:16:10.824Z,1645056970.824 [SBIT](IMPORTANT): Express linearApproximation _.eyeris_particle_counts 100.000000 count;

2022-02-17T00:16:10.824Z,1645056970.824 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour;

2022-02-17T00:16:10.824Z,1645056970.824 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt;

2022-02-17T00:16:10.824Z,1645056970.824 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;

2022-02-17T00:16:10.824Z,1645056970.824 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=115.741138 cubic_centimeter;

2022-02-17T00:16:10.825Z,1645056970.825 [SBIT](IMPORTANT): VerticalControl.massDefault=-4.597572 millimeter;

2022-02-17T00:16:11.218Z,1645056971.218 [MissionManager](IMPORTANT): Started mission Startup_lab
2022-02-17T00:16:11.218Z,1645056971.218 [Startup_lab] Running Loop=1
2022-02-17T00:16:11.218Z,1645056971.218 [Startup_lab](DEBUG): Aggregate::initialize Startup_lab
2022-02-17T00:16:11.218Z,1645056971.218 [Startup_lab:A.GoToSurface] Running Loop=1
2022-02-17T00:16:11.218Z,1645056971.218 [Startup_lab:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-02-17T00:16:11.219Z,1645056971.219 [Startup_lab:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-02-17T00:16:11.219Z,1645056971.219 [Startup_lab:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-02-17T00:16:11.247Z,1645056971.247 [Startup_lab:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-02-17T00:16:11.247Z,1645056971.247 [Startup_lab:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-02-17T00:16:11.248Z,1645056971.248 [Startup_lab:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-02-17T00:16:11.249Z,1645056971.249 [Startup_lab:B.Wait] Running Loop=1
2022-02-17T00:16:11.249Z,1645056971.249 [Startup_lab:B.Wait](DEBUG): Initialize Wait Component.
2022-02-17T00:16:14.850Z,1645056974.850 [Startup_lab:B.Wait](INFO): Done Waiting.
2022-02-17T00:16:14.850Z,1645056974.850 [Startup_lab:B.Wait] Stopped
2022-02-17T00:16:14.850Z,1645056974.850 [Startup_lab:B.Wait](DEBUG): Uninitialize Wait Component.
2022-02-17T00:16:14.851Z,1645056974.851 [Startup_lab](INFO): Completed Startup_lab
2022-02-17T00:16:14.851Z,1645056974.851 [MissionManager](INFO): Startup_lab is completed.
2022-02-17T00:16:14.851Z,1645056974.851 [MissionManager](INFO): Uninitializing Mission Startup_lab
2022-02-17T00:16:14.851Z,1645056974.851 [Startup_lab] Stopped
2022-02-17T00:16:14.851Z,1645056974.851 [Startup_lab](DEBUG): Aggregate::uninitialize Startup_lab
2022-02-17T00:16:14.852Z,1645056974.852 [Startup_lab:A.GoToSurface] Stopped
2022-02-17T00:16:14.852Z,1645056974.852 [Startup_lab:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-02-17T00:16:15.260Z,1645056975.260 [MissionManager](IMPORTANT): Started mission Default
2022-02-17T00:16:15.261Z,1645056975.261 [Default] Running Loop=1
2022-02-17T00:16:15.261Z,1645056975.261 [Default](DEBUG): Aggregate::initialize Default
2022-02-17T00:16:15.261Z,1645056975.261 [Default:B.GoToSurface] Running Loop=1
2022-02-17T00:16:15.261Z,1645056975.261 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-02-17T00:16:15.261Z,1645056975.261 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-02-17T00:16:15.261Z,1645056975.261 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-02-17T00:16:15.262Z,1645056975.262 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-02-17T00:16:15.262Z,1645056975.262 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-02-17T00:16:15.262Z,1645056975.262 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-02-17T00:16:15.263Z,1645056975.263 [Default:A.Wait] Running Loop=1
2022-02-17T00:16:15.263Z,1645056975.263 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-02-17T00:16:26.975Z,1645056986.975 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-02-17T00:16:27.832Z,1645056987.832 [CommandExec](IMPORTANT): got command failComponent
2022-02-17T00:16:27.833Z,1645056987.833 [CommandExec](IMPORTANT): Failed components:
2022-02-17T00:16:27.833Z,1645056987.833 [CommandExec](IMPORTANT): No failed Components.
2022-02-17T00:16:28.589Z,1645056988.589 [Default:A.Wait](INFO): Done Waiting.
2022-02-17T00:16:28.589Z,1645056988.589 [Default:A.Wait] Stopped
2022-02-17T00:16:28.589Z,1645056988.589 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-02-17T00:16:29.018Z,1645056989.018 [Default:CheckIn] Running Loop=1
2022-02-17T00:16:29.019Z,1645056989.019 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-17T00:16:29.019Z,1645056989.019 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-17T00:16:29.416Z,1645056989.416 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-02-17T00:16:30.017Z,1645056990.017 [CommandExec](IMPORTANT): got command quit
2022-02-17T00:16:31.020Z,1645056991.020 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-02-17T00:16:31.020Z,1645056991.020 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:31.020Z,1645056991.020 [CommandExec](INFO): Uninitializing the command executive.
2022-02-17T00:16:31.020Z,1645056991.020 [CommandExec](INFO): Uninitializing the command scheduler.
2022-02-17T00:16:31.020Z,1645056991.020 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:31.196Z,1645056991.196 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-02-17T00:16:31.196Z,1645056991.196 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-02-17T00:16:31.196Z,1645056991.196 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:31.197Z,1645056991.197 [NavChartDb](INFO): Join timeout helper Thread ID is 7125
2022-02-17T00:16:31.532Z,1645056991.532 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:31.532Z,1645056991.532 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:31.548Z,1645056991.548 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-02-17T00:16:31.548Z,1645056991.548 [DAT ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:31.549Z,1645056991.549 [DAT](INFO): Join timeout helper Thread ID is 7126
2022-02-17T00:16:31.704Z,1645056991.704 [DAT](INFO): Powering down
2022-02-17T00:16:31.772Z,1645056991.772 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:31.773Z,1645056991.773 [DAT](INFO): Powering down
2022-02-17T00:16:31.773Z,1645056991.773 [DAT ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:31.792Z,1645056991.792 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-02-17T00:16:31.792Z,1645056991.792 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:31.792Z,1645056991.792 [Radio_Surface](INFO): Join timeout helper Thread ID is 7127
2022-02-17T00:16:31.928Z,1645056991.928 [Radio_Surface](INFO): Powering down
2022-02-17T00:16:31.929Z,1645056991.929 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:31.929Z,1645056991.929 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:31.948Z,1645056991.948 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-02-17T00:16:31.948Z,1645056991.948 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:31.948Z,1645056991.948 [Onboard](INFO): Join timeout helper Thread ID is 7128
2022-02-17T00:16:33.056Z,1645056993.056 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:33.056Z,1645056993.056 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:33.072Z,1645056993.072 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-02-17T00:16:33.072Z,1645056993.072 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:33.072Z,1645056993.072 [DataOverHttps](INFO): Join timeout helper Thread ID is 7129
2022-02-17T00:16:33.436Z,1645056993.436 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:33.436Z,1645056993.436 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:33.441Z,1645056993.441 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-02-17T00:16:33.441Z,1645056993.441 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:33.441Z,1645056993.441 [BackseatComponent](INFO): Join timeout helper Thread ID is 7130
2022-02-17T00:16:33.548Z,1645056993.548 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:33.548Z,1645056993.548 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:33.564Z,1645056993.564 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-02-17T00:16:33.565Z,1645056993.565 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:33.565Z,1645056993.565 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7131
2022-02-17T00:16:33.896Z,1645056993.896 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:33.896Z,1645056993.896 [WetLabsBB2FL](INFO): Powering down
2022-02-17T00:16:33.897Z,1645056993.897 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:33.909Z,1645056993.909 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-02-17T00:16:33.909Z,1645056993.909 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:33.909Z,1645056993.909 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7132
2022-02-17T00:16:34.408Z,1645056994.408 [CTD_Seabird](INFO): Powering down
2022-02-17T00:16:34.422Z,1645056994.422 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:34.423Z,1645056994.423 [CTD_Seabird](INFO): Powering down
2022-02-17T00:16:34.437Z,1645056994.437 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.445Z,1645056994.445 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-02-17T00:16:34.445Z,1645056994.445 [logger ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.445Z,1645056994.445 [logger](INFO): Join timeout helper Thread ID is 7133
2022-02-17T00:16:34.445Z,1645056994.445 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:34.446Z,1645056994.446 [logger ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.450Z,1645056994.450 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-02-17T00:16:34.450Z,1645056994.450 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.451Z,1645056994.451 [CommandLine](INFO): Join timeout helper Thread ID is 7134
2022-02-17T00:16:34.536Z,1645056994.536 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:34.536Z,1645056994.536 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.548Z,1645056994.548 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-02-17T00:16:34.549Z,1645056994.549 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.549Z,1645056994.549 [CommandExec](INFO): Join timeout helper Thread ID is 7135
2022-02-17T00:16:34.550Z,1645056994.550 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-02-17T00:16:34.550Z,1645056994.550 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.551Z,1645056994.551 [controlThread](INFO): Join timeout helper Thread ID is 7136
2022-02-17T00:16:34.620Z,1645056994.620 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-02-17T00:16:34.620Z,1645056994.620 [controlThread](DEBUG): Uninitializing ControlThread
2022-02-17T00:16:34.621Z,1645056994.621 [AHRS_M2](INFO): Powering down
2022-02-17T00:16:34.693Z,1645056994.693 [NAL9602](INFO): Powering down
2022-02-17T00:16:34.695Z,1645056994.695 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-02-17T00:16:34.696Z,1645056994.696 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-02-17T00:16:34.696Z,1645056994.696 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-02-17T00:16:34.697Z,1645056994.697 [MissionManager](INFO): Uninitializing Mission Default
2022-02-17T00:16:34.697Z,1645056994.697 [Default] Stopped
2022-02-17T00:16:34.697Z,1645056994.697 [Default](DEBUG): Aggregate::uninitialize Default
2022-02-17T00:16:34.697Z,1645056994.697 [Default:B.GoToSurface] Stopped
2022-02-17T00:16:34.697Z,1645056994.697 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-02-17T00:16:34.697Z,1645056994.697 [Default:CheckIn] Stopped
2022-02-17T00:16:34.697Z,1645056994.697 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-17T00:16:34.698Z,1645056994.698 [Default:CheckIn:Read_GPS] Stopped
2022-02-17T00:16:34.700Z,1645056994.700 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-02-17T00:16:34.701Z,1645056994.701 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-02-17T00:16:34.701Z,1645056994.701 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-02-17T00:16:34.701Z,1645056994.701 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-02-17T00:16:34.702Z,1645056994.702 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-02-17T00:16:34.702Z,1645056994.702 [BuoyancyServo](INFO): Powering down
2022-02-17T00:16:34.716Z,1645056994.716 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-02-17T00:16:34.716Z,1645056994.716 [ElevatorServo](INFO): Powering down
2022-02-17T00:16:34.717Z,1645056994.717 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-02-17T00:16:34.717Z,1645056994.717 [MassServo](INFO): Powering down
2022-02-17T00:16:34.718Z,1645056994.718 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-02-17T00:16:34.718Z,1645056994.718 [RudderServo](INFO): Powering down
2022-02-17T00:16:34.719Z,1645056994.719 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-02-17T00:16:34.719Z,1645056994.719 [ThrusterHE](INFO): Powering down
2022-02-17T00:16:34.720Z,1645056994.720 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-02-17T00:16:34.720Z,1645056994.720 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-02-17T00:16:34.720Z,1645056994.720 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-02-17T00:16:34.720Z,1645056994.720 [CBIT](DEBUG): Powering off loads.
2022-02-17T00:16:34.732Z,1645056994.732 [CBIT](DEBUG): Disabling WDT.
2022-02-17T00:16:34.744Z,1645056994.744 [CBIT](DEBUG): Opening all GF detection circuits.
2022-02-17T00:16:34.745Z,1645056994.745 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.770Z,1645056994.770 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.774Z,1645056994.774 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.818Z,1645056994.818 [DAT ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.822Z,1645056994.822 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.824Z,1645056994.824 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.829Z,1645056994.829 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.874Z,1645056994.874 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.913Z,1645056994.913 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-02-17T00:16:34.977Z,1645056994.977 [logger ThreadHandler](INFO): Thread cancelled.