2023-04-11T17:42:45.968Z,1681234965.968 [Supervisor](DEBUG): Initializing supervisor.
2023-04-11T17:42:45.972Z,1681234965.972 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-04-11T17:42:45.973Z,1681234965.973 [SyncHandler](INFO): Protected caller Thread ID is 831
2023-04-11T17:42:45.973Z,1681234965.973 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-04-11T17:42:45.974Z,1681234965.974 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-04-11T17:42:45.974Z,1681234965.974 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 832
2023-04-11T17:42:45.978Z,1681234965.978 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-04-11T17:42:45.995Z,1681234965.995 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-04-11T17:42:45.996Z,1681234965.996 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-04-11T17:42:45.997Z,1681234965.997 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 833
2023-04-11T17:42:46.001Z,1681234966.001 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-04-11T17:42:46.002Z,1681234966.002 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-04-11T17:42:46.002Z,1681234966.002 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 834
2023-04-11T17:42:46.004Z,1681234966.004 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-04-11T17:42:46.005Z,1681234966.005 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-04-11T17:42:46.005Z,1681234966.005 [logger ThreadHandler](INFO): Protected caller Thread ID is 835
2023-04-11T17:42:46.009Z,1681234966.009 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-04-11T17:42:46.009Z,1681234966.009 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-04-11T17:42:46.014Z,1681234966.014 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-04-11T17:42:46.343Z,1681234966.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-04-11T17:42:46.344Z,1681234966.344 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-04-11T17:42:47.047Z,1681234967.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-04-11T17:42:47.048Z,1681234967.048 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-04-11T17:42:47.157Z,1681234967.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-04-11T17:42:47.159Z,1681234967.159 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-04-11T17:42:47.636Z,1681234967.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-04-11T17:42:47.638Z,1681234967.638 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-04-11T17:42:47.734Z,1681234967.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-04-11T17:42:47.736Z,1681234967.736 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-04-11T17:42:47.816Z,1681234967.816 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-04-11T17:42:48.207Z,1681234968.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-04-11T17:42:48.209Z,1681234968.209 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-04-11T17:42:48.426Z,1681234968.426 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-04-11T17:42:48.428Z,1681234968.428 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-04-11T17:42:48.564Z,1681234968.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-04-11T17:42:48.566Z,1681234968.566 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-04-11T17:42:48.793Z,1681234968.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-04-11T17:42:48.794Z,1681234968.794 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-04-11T17:42:49.337Z,1681234969.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-04-11T17:42:49.338Z,1681234969.338 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-04-11T17:42:50.429Z,1681234970.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-04-11T17:42:50.430Z,1681234970.430 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-04-11T17:42:50.538Z,1681234970.538 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-04-11T17:42:50.722Z,1681234970.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-04-11T17:42:50.723Z,1681234970.723 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-04-11T17:42:50.941Z,1681234970.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-04-11T17:42:50.942Z,1681234970.942 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-04-11T17:42:51.230Z,1681234971.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-04-11T17:42:51.232Z,1681234971.232 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2023-04-11T17:42:51.237Z,1681234971.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2023-04-11T17:42:51.344Z,1681234971.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2023-04-11T17:42:51.489Z,1681234971.489 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2023-04-11T17:42:51.574Z,1681234971.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2023-04-11T17:42:51.656Z,1681234971.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2023-04-11T17:42:51.763Z,1681234971.763 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2023-04-11T17:42:51.935Z,1681234971.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2023-04-11T17:42:52.194Z,1681234972.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-04-11T17:42:52.195Z,1681234972.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2023-04-11T17:42:52.327Z,1681234972.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2023-04-11T17:42:52.420Z,1681234972.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2023-04-11T17:42:52.524Z,1681234972.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2023-04-11T17:42:52.624Z,1681234972.624 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-04-11T17:42:52.645Z,1681234972.645 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-04-11T17:42:52.768Z,1681234972.768 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-04-11T17:42:52.768Z,1681234972.768 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-04-11T17:42:52.784Z,1681234972.784 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-04-11T17:42:52.786Z,1681234972.786 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-04-11T17:42:52.855Z,1681234972.855 [DepthRateCalculator] Loaded
2023-04-11T17:42:52.856Z,1681234972.856 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-04-11T17:42:52.861Z,1681234972.861 [PitchRateCalculator] Loaded
2023-04-11T17:42:52.861Z,1681234972.861 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-04-11T17:42:52.871Z,1681234972.871 [SpeedCalculator] Loaded
2023-04-11T17:42:52.872Z,1681234972.872 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-04-11T17:42:52.887Z,1681234972.887 [VerticalHomogeneityIndexCalculator] Loaded
2023-04-11T17:42:52.887Z,1681234972.887 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2023-04-11T17:42:52.892Z,1681234972.892 [YawRateCalculator] Loaded
2023-04-11T17:42:52.892Z,1681234972.892 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-04-11T17:42:52.913Z,1681234972.913 [ElevatorOffsetCalculator] Loaded
2023-04-11T17:42:52.913Z,1681234972.913 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-04-11T17:42:52.914Z,1681234972.914 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-04-11T17:42:52.916Z,1681234972.916 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-04-11T17:42:52.983Z,1681234972.983 [VerticalControl](DEBUG): Construct VerticalControl.
2023-04-11T17:42:53.038Z,1681234973.038 [VerticalControl] Loaded
2023-04-11T17:42:53.038Z,1681234973.038 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-04-11T17:42:53.041Z,1681234973.041 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-04-11T17:42:53.081Z,1681234973.081 [HorizontalControl] Loaded
2023-04-11T17:42:53.081Z,1681234973.081 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-04-11T17:42:53.084Z,1681234973.084 [SpeedControl](DEBUG): Construct SpeedControl.
2023-04-11T17:42:53.086Z,1681234973.086 [SpeedControl] Loaded
2023-04-11T17:42:53.087Z,1681234973.087 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-04-11T17:42:53.089Z,1681234973.089 [LoopControl](DEBUG): Construct LoopControl.
2023-04-11T17:42:53.090Z,1681234973.090 [LoopControl] Loaded
2023-04-11T17:42:53.090Z,1681234973.090 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-04-11T17:42:53.090Z,1681234973.090 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-04-11T17:42:53.091Z,1681234973.091 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-04-11T17:42:53.226Z,1681234973.226 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-04-11T17:42:53.227Z,1681234973.227 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-04-11T17:42:53.240Z,1681234973.240 [NavChart] Loaded
2023-04-11T17:42:53.240Z,1681234973.240 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-04-11T17:42:53.246Z,1681234973.246 [UniversalFixResidualReporter] Loaded
2023-04-11T17:42:53.246Z,1681234973.246 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-04-11T17:42:53.247Z,1681234973.247 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-04-11T17:42:53.248Z,1681234973.248 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-04-11T17:42:53.427Z,1681234973.427 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-04-11T17:42:53.428Z,1681234973.428 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-04-11T17:42:54.348Z,1681234974.348 [AHRS_M2] Loaded
2023-04-11T17:42:54.349Z,1681234974.349 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-04-11T17:42:54.610Z,1681234974.610 [BackseatComponent] Loaded
2023-04-11T17:42:54.611Z,1681234974.611 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-04-11T17:42:54.632Z,1681234974.632 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2023-04-11T17:42:54.632Z,1681234974.632 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 918
2023-04-11T17:42:54.635Z,1681234974.635 [LcmUniversalReporter] Loaded
2023-04-11T17:42:54.635Z,1681234974.635 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-04-11T17:42:55.818Z,1681234975.818 [BPC1] Loaded
2023-04-11T17:42:55.819Z,1681234975.819 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-04-11T17:42:55.959Z,1681234975.959 [DataOverHttps] Loaded
2023-04-11T17:42:55.959Z,1681234975.959 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-04-11T17:42:55.960Z,1681234975.960 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409264E0
2023-04-11T17:42:55.961Z,1681234975.961 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 919
2023-04-11T17:42:55.981Z,1681234975.981 [Depth_Keller] Loaded
2023-04-11T17:42:55.981Z,1681234975.981 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-04-11T17:42:55.986Z,1681234975.986 [DropWeight] Loaded
2023-04-11T17:42:55.986Z,1681234975.986 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-04-11T17:42:56.024Z,1681234976.024 [DVL_micro] Loaded
2023-04-11T17:42:56.024Z,1681234976.024 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-04-11T17:42:56.085Z,1681234976.085 [NAL9602] Loaded
2023-04-11T17:42:56.085Z,1681234976.085 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-04-11T17:42:56.115Z,1681234976.115 [Onboard] Loaded
2023-04-11T17:42:56.115Z,1681234976.115 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-04-11T17:42:56.116Z,1681234976.116 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409564E0
2023-04-11T17:42:56.116Z,1681234976.116 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 920
2023-04-11T17:42:56.128Z,1681234976.128 [Power24vConverter] Loaded
2023-04-11T17:42:56.129Z,1681234976.129 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-04-11T17:42:56.142Z,1681234976.142 [Radio_Surface] Loaded
2023-04-11T17:42:56.142Z,1681234976.142 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-04-11T17:42:56.143Z,1681234976.143 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409864E0
2023-04-11T17:42:56.143Z,1681234976.143 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 921
2023-04-11T17:42:56.235Z,1681234976.235 [DAT] Loaded
2023-04-11T17:42:56.235Z,1681234976.235 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-04-11T17:42:56.236Z,1681234976.236 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409B64E0
2023-04-11T17:42:56.236Z,1681234976.236 [DAT ThreadHandler](INFO): Protected caller Thread ID is 922
2023-04-11T17:42:56.237Z,1681234976.237 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-04-11T17:42:56.238Z,1681234976.238 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-04-11T17:42:56.395Z,1681234976.395 [BuoyancyServo] Loaded
2023-04-11T17:42:56.395Z,1681234976.395 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-04-11T17:42:56.415Z,1681234976.415 [ElevatorServo] Loaded
2023-04-11T17:42:56.416Z,1681234976.416 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-04-11T17:42:56.435Z,1681234976.435 [MassServo] Loaded
2023-04-11T17:42:56.435Z,1681234976.435 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-04-11T17:42:56.454Z,1681234976.454 [RudderServo] Loaded
2023-04-11T17:42:56.454Z,1681234976.454 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-04-11T17:42:56.468Z,1681234976.468 [ThrusterHE] Loaded
2023-04-11T17:42:56.469Z,1681234976.469 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-04-11T17:42:56.469Z,1681234976.469 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-04-11T17:42:56.470Z,1681234976.470 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-04-11T17:42:56.565Z,1681234976.565 [StratificationFrontDetector](IMPORTANT): thresholdVTHI set to: 0.050012 degC
2023-04-11T17:42:56.565Z,1681234976.565 [StratificationFrontDetector](INFO): (re)initializing
2023-04-11T17:42:56.565Z,1681234976.565 [StratificationFrontDetector] Loaded
2023-04-11T17:42:56.566Z,1681234976.566 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2023-04-11T17:42:56.566Z,1681234976.566 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-04-11T17:42:56.567Z,1681234976.567 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-04-11T17:42:56.610Z,1681234976.610 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-04-11T17:42:56.610Z,1681234976.610 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-04-11T17:42:56.969Z,1681234976.969 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-04-11T17:42:56.970Z,1681234976.970 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-04-11T17:42:57.184Z,1681234977.184 [CTD_Seabird] Loaded
2023-04-11T17:42:57.185Z,1681234977.185 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-04-11T17:42:57.186Z,1681234977.186 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B404E0
2023-04-11T17:42:57.186Z,1681234977.186 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 923
2023-04-11T17:42:57.219Z,1681234977.219 [ESPComponent] Loaded
2023-04-11T17:42:57.220Z,1681234977.220 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-04-11T17:42:57.241Z,1681234977.241 [PAR_Licor] Loaded
2023-04-11T17:42:57.241Z,1681234977.241 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-04-11T17:42:57.272Z,1681234977.272 [WetLabsBB2FL] Loaded
2023-04-11T17:42:57.272Z,1681234977.272 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-04-11T17:42:57.273Z,1681234977.273 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B704E0
2023-04-11T17:42:57.273Z,1681234977.273 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 924
2023-04-11T17:42:57.274Z,1681234977.274 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-04-11T17:42:57.276Z,1681234977.276 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-04-11T17:42:57.442Z,1681234977.442 [SBIT](DEBUG): Construct Startup Built In Test.
2023-04-11T17:42:57.451Z,1681234977.451 [SBIT] Loaded
2023-04-11T17:42:57.451Z,1681234977.451 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-04-11T17:42:57.454Z,1681234977.454 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-04-11T17:42:57.466Z,1681234977.466 [IBIT] Loaded
2023-04-11T17:42:57.467Z,1681234977.467 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-04-11T17:42:57.473Z,1681234977.473 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-04-11T17:42:57.591Z,1681234977.591 [CBIT] Loaded
2023-04-11T17:42:57.591Z,1681234977.591 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-04-11T17:42:57.592Z,1681234977.592 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-04-11T17:42:57.598Z,1681234977.598 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-04-11T17:42:57.601Z,1681234977.601 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-04-11T17:42:57.611Z,1681234977.611 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-04-11T17:42:57.612Z,1681234977.612 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BDE4E0
2023-04-11T17:42:57.613Z,1681234977.613 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 925
2023-04-11T17:42:57.617Z,1681234977.617 [Supervisor](INFO): Main Thread ID is 827
2023-04-11T17:42:57.617Z,1681234977.617 [Supervisor](DEBUG): Running supervisor.
2023-04-11T17:42:57.618Z,1681234977.618 [CommandExec ThreadHandler](INFO): Handler Thread ID is 926
2023-04-11T17:42:57.619Z,1681234977.619 [CommandExec](INFO): Initializing the command executive.
2023-04-11T17:42:57.620Z,1681234977.620 [CommandLine ThreadHandler](INFO): Handler Thread ID is 927
2023-04-11T17:42:57.622Z,1681234977.622 [controlThread ThreadHandler](INFO): Handler Thread ID is 928
2023-04-11T17:42:57.622Z,1681234977.622 [controlThread](DEBUG): Initializing ControlThread
2023-04-11T17:42:57.623Z,1681234977.623 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-04-11T17:42:57.624Z,1681234977.624 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-04-11T17:42:57.624Z,1681234977.624 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-04-11T17:42:57.624Z,1681234977.624 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2023-04-11T17:42:57.625Z,1681234977.625 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-04-11T17:42:57.626Z,1681234977.626 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-04-11T17:42:57.626Z,1681234977.626 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-04-11T17:42:57.628Z,1681234977.628 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-04-11T17:42:57.629Z,1681234977.629 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-04-11T17:42:57.629Z,1681234977.629 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-04-11T17:42:57.630Z,1681234977.630 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-04-11T17:42:57.630Z,1681234977.630 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-04-11T17:42:57.640Z,1681234977.640 [SBIT](INFO): Initialize SBIT Component.
2023-04-11T17:42:57.640Z,1681234977.640 [SBIT](IMPORTANT): git: 2023-04-11
2023-04-11T17:42:57.641Z,1681234977.641 [SBIT](INFO): git hash: 6d7eb32f2d4cf73a2f275df29fd39a45f67762a0
2023-04-11T17:42:57.641Z,1681234977.641 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-04-11T17:42:57.642Z,1681234977.642 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-04-11T17:42:57.643Z,1681234977.643 [SBIT](INFO): Beginning SBIT in 96.000000 seconds.
2023-04-11T17:42:57.644Z,1681234977.644 [IBIT](INFO): Initialize IBIT Component.
2023-04-11T17:42:57.645Z,1681234977.645 [CBIT](DEBUG): Initialize CBIT Component.
2023-04-11T17:42:57.645Z,1681234977.645 [logger ThreadHandler](INFO): Handler Thread ID is 929
2023-04-11T17:42:57.655Z,1681234977.655 [CBIT](DEBUG): Initialized mux pins.
2023-04-11T17:42:57.655Z,1681234977.655 [CBIT](DEBUG): Initializing the watchdog timer.
2023-04-11T17:42:57.664Z,1681234977.664 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 930
2023-04-11T17:42:57.676Z,1681234977.676 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 931
2023-04-11T17:42:57.677Z,1681234977.677 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-04-11T17:42:57.679Z,1681234977.679 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-04-11T17:42:57.680Z,1681234977.680 [CBIT](DEBUG): Initializing heartbeat.
2023-04-11T17:42:57.688Z,1681234977.688 [Onboard ThreadHandler](INFO): Handler Thread ID is 932
2023-04-11T17:42:57.705Z,1681234977.705 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 933
2023-04-11T17:42:57.724Z,1681234977.724 [DAT ThreadHandler](INFO): Handler Thread ID is 934
2023-04-11T17:42:57.725Z,1681234977.725 [DAT](INFO): Powering up
2023-04-11T17:42:57.725Z,1681234977.725 [DAT](DEBUG): Initializing DAT.
2023-04-11T17:42:57.729Z,1681234977.729 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 936
2023-04-11T17:42:57.729Z,1681234977.729 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-04-11T17:42:57.733Z,1681234977.733 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 938
2023-04-11T17:42:57.736Z,1681234977.736 [WetLabsBB2FL](INFO): Powering up
2023-04-11T17:42:57.737Z,1681234977.737 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940
2023-04-11T17:42:57.745Z,1681234977.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-04-11T17:42:57.745Z,1681234977.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-04-11T17:42:57.746Z,1681234977.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-04-11T17:42:57.746Z,1681234977.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-04-11T17:42:57.746Z,1681234977.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-04-11T17:42:57.746Z,1681234977.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-04-11T17:42:57.746Z,1681234977.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-04-11T17:42:57.746Z,1681234977.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-04-11T17:42:57.752Z,1681234977.752 [CBIT](DEBUG): Deactivating GF circuits.
2023-04-11T17:42:57.752Z,1681234977.752 [CBIT](DEBUG): Deactivating emergency mode.
2023-04-11T17:42:57.791Z,1681234977.791 [CBIT](DEBUG): Backplane powered.
2023-04-11T17:42:57.792Z,1681234977.792 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-04-11T17:42:57.832Z,1681234977.832 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-11T17:42:57.844Z,1681234977.844 [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>


2023-04-11T17:42:57.844Z,1681234977.844 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-04-11T17:42:57.925Z,1681234977.925 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-04-11T17:42:57.926Z,1681234977.926 [Default:A.Wait](DEBUG): Construct Wait.
2023-04-11T17:42:57.945Z,1681234977.945 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-11T17:42:57.968Z,1681234977.968 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-04-11T17:42:57.997Z,1681234977.997 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-04-11T17:42:58.002Z,1681234978.002 [Default:E.Execute](DEBUG): Construct Execute.
2023-04-11T17:42:58.006Z,1681234978.006 [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="P4H">
                <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>


2023-04-11T17:42:58.011Z,1681234978.011 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-11T17:42:58.029Z,1681234978.029 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-04-11T17:42:58.111Z,1681234978.111 [Radio_Surface](INFO): Powering up
2023-04-11T17:42:58.197Z,1681234978.197 [Power24vConverter](INFO): Powering up.
2023-04-11T17:42:58.269Z,1681234978.269 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-04-11T17:42:58.276Z,1681234978.276 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-04-11T17:42:58.277Z,1681234978.277 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-04-11T17:42:58.284Z,1681234978.284 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-04-11T17:42:58.285Z,1681234978.285 [MassServo](DEBUG): Initializing EZServoServo.
2023-04-11T17:42:58.292Z,1681234978.292 [MassServo](DEBUG): Initializing MassServo.
2023-04-11T17:42:58.292Z,1681234978.292 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-11T17:42:58.300Z,1681234978.300 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-11T17:42:58.301Z,1681234978.301 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-04-11T17:42:58.312Z,1681234978.312 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-04-11T17:43:00.347Z,1681234980.347 [WetLabsBB2FL](INFO): Powering down
2023-04-11T17:43:03.797Z,1681234983.797 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-04-11T17:43:09.568Z,1681234989.568 [DAT](INFO): DAT read: 

2023-04-11T17:43:09.569Z,1681234989.569 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-04-11T17:43:11.080Z,1681234991.080 [DAT](INFO): DAT read: MF Frequency Band

2023-04-11T17:43:11.081Z,1681234991.081 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-04-11T17:43:11.082Z,1681234991.082 [DAT](INFO): DAT read: Apr 11 2023 17:43:07 

2023-04-11T17:43:12.341Z,1681234992.341 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-04-11T17:43:12.342Z,1681234992.342 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-04-11T17:43:12.342Z,1681234992.342 [DAT](INFO): commRate: 800
2023-04-11T17:43:14.407Z,1681234994.407 [DAT](INFO): entering command mode
2023-04-11T17:43:14.608Z,1681234994.608 [DAT](INFO): DAT read: 

2023-04-11T17:43:14.608Z,1681234994.608 [DAT](INFO): DAT read: user:1>

2023-04-11T17:43:14.609Z,1681234994.609 [DAT](INFO): setting verbose to 3
2023-04-11T17:43:14.860Z,1681234994.860 [DAT](INFO): DAT read: user:1>

2023-04-11T17:43:14.861Z,1681234994.861 [DAT](INFO): DAT read: Verbose         | 3           

2023-04-11T17:43:14.861Z,1681234994.861 [DAT](INFO): set verbose to 3
2023-04-11T17:43:14.862Z,1681234994.862 [DAT](INFO): setting DatVerbose to 27440
2023-04-11T17:43:15.112Z,1681234995.112 [DAT](INFO): DAT read: user:2>

2023-04-11T17:43:15.113Z,1681234995.113 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-04-11T17:43:15.113Z,1681234995.113 [DAT](INFO): set DatVerbose to 27440
2023-04-11T17:43:15.114Z,1681234995.114 [DAT](INFO): setting transmit power to 8
2023-04-11T17:43:15.364Z,1681234995.364 [DAT](INFO): DAT read: user:3>

2023-04-11T17:43:15.365Z,1681234995.365 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-04-11T17:43:15.365Z,1681234995.365 [DAT](INFO): set transmit power to 8
2023-04-11T17:43:15.366Z,1681234995.366 [DAT](INFO): setting local address to 8
2023-04-11T17:43:15.616Z,1681234995.616 [DAT](INFO): DAT read: user:4>

2023-04-11T17:43:15.617Z,1681234995.617 [DAT](INFO): DAT read: LocalAddr       | 8           

2023-04-11T17:43:15.618Z,1681234995.618 [DAT](INFO): set local address to 8
2023-04-11T17:43:15.619Z,1681234995.619 [DAT](INFO): Setting time to: 17:43:15 And date to:4/11/2023
2023-04-11T17:43:15.868Z,1681234995.868 [DAT](INFO): DAT read: user:5>

2023-04-11T17:43:15.869Z,1681234995.869 [DAT](INFO): DAT read: Tue Apr 11, 2023  17:43:15  

2023-04-11T17:43:15.870Z,1681234995.870 [DAT](INFO): Local DAT time set to Tue Apr 11, 2023  17:43:15  

2023-04-11T17:43:26.301Z,1681235006.301 [NAL9602](INFO): Powering up NAL9602
2023-04-11T17:43:37.214Z,1681235017.214 [NAL9602](INFO): NAL9602 initialized
2023-04-11T17:43:48.528Z,1681235028.528 [DVL_micro](ERROR): Failed to parse:00000000000,35.0,+23.8,089.0,000
2023-04-11T17:44:34.209Z,1681235074.209 [SBIT](IMPORTANT): Beginning Startup BIT
2023-04-11T17:44:34.213Z,1681235074.213 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-11T17:44:45.224Z,1681235085.224 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007185
CHAN A1 (24V): 0.015562
CHAN A2 (12V): 0.002992
CHAN A3 (5V): -0.001974
CHAN B0 (3.3V): -0.001715
CHAN B1 (3.15aV): -0.001719
CHAN B2 (3.15bV): -0.001462
CHAN B3 (GND): -0.003421
OPEN: -0.003675
Full Scale: +/- 1 mA
2023-04-11T17:45:26.317Z,1681235126.317 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-11T17:45:26.317Z,1681235126.317 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2023-04-11T17:45:28.361Z,1681235128.361 [SBIT](IMPORTANT): SBIT PASSED
2023-04-11T17:45:28.361Z,1681235128.361 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-04-11T17:45:28.362Z,1681235128.362 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count;

2023-04-11T17:45:28.362Z,1681235128.362 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2023-04-11T17:45:28.362Z,1681235128.362 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar;

2023-04-11T17:45:28.362Z,1681235128.362 [SBIT](IMPORTANT): Express none CBIT.ampHoursUsed;

2023-04-11T17:45:28.362Z,1681235128.362 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_salinity_homogeneity_index 0.050000 practical_salinity_unit;

2023-04-11T17:45:28.362Z,1681235128.362 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius;

2023-04-11T17:45:28.362Z,1681235128.362 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-04-11T17:45:28.363Z,1681235128.363 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2023-04-11T17:45:28.363Z,1681235128.363 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;

2023-04-11T17:45:28.363Z,1681235128.363 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;

2023-04-11T17:45:28.363Z,1681235128.363 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;

2023-04-11T17:45:28.363Z,1681235128.363 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=304.139044 cubic_centimeter;

2023-04-11T17:45:28.363Z,1681235128.363 [SBIT](IMPORTANT): VerticalControl.massDefault=19.116968 millimeter;

2023-04-11T17:45:28.363Z,1681235128.363 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter;

2023-04-11T17:45:28.364Z,1681235128.364 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=32 millimeter;

2023-04-11T17:45:28.364Z,1681235128.364 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=1.5 meter;

2023-04-11T17:45:28.364Z,1681235128.364 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-04-11T17:45:28.364Z,1681235128.364 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-04-11T17:45:28.364Z,1681235128.364 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;

2023-04-11T17:45:28.750Z,1681235128.750 [MissionManager](IMPORTANT): Started mission Startup
2023-04-11T17:45:28.751Z,1681235128.751 [Startup] Running Loop=1
2023-04-11T17:45:28.751Z,1681235128.751 [Startup](DEBUG): Aggregate::initialize Startup
2023-04-11T17:45:28.751Z,1681235128.751 [Startup:A.GoToSurface] Running Loop=1
2023-04-11T17:45:28.751Z,1681235128.751 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-11T17:45:28.752Z,1681235128.752 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-11T17:45:28.752Z,1681235128.752 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-11T17:45:28.752Z,1681235128.752 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-11T17:45:28.753Z,1681235128.753 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-11T17:45:28.753Z,1681235128.753 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-11T17:45:28.754Z,1681235128.754 [Startup:StartupSatComms] Running Loop=1
2023-04-11T17:45:28.754Z,1681235128.754 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-04-11T17:45:28.755Z,1681235128.755 [Startup:StartupSatComms:A] Running Loop=1
2023-04-11T17:45:29.145Z,1681235129.145 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-04-11T17:45:57.817Z,1681235157.817 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-04-11T17:45:57.817Z,1681235157.817 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:45:57.870Z,1681235157.870 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:45:58.233Z,1681235158.233 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:45:58.233Z,1681235158.233 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-04-11T17:46:06.809Z,1681235166.809 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004354
2023-04-11T17:46:17.037Z,1681235177.037 [DAT](INFO): DAT read: user:6>Lowpower

2023-04-11T17:46:17.037Z,1681235177.037 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:6>Lowpower

2023-04-11T17:46:28.977Z,1681235188.977 [Startup:StartupSatComms:A](INFO): Timed out from 2023-04-11T17:45:28.8Z
2023-04-11T17:46:28.977Z,1681235188.977 [Startup:StartupSatComms:A] Stopped
2023-04-11T17:46:28.977Z,1681235188.977 [Startup:StartupSatComms:B] Running Loop=1
2023-04-11T17:46:29.323Z,1681235189.323 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-11T17:46:35.723Z,1681235195.723 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230411T163646/Courier0028.lzma
2023-04-11T17:46:36.726Z,1681235196.726 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Courier0028.lzma.bak
2023-04-11T17:46:36.726Z,1681235196.726 [DataOverHttps](INFO): SBD MOMSN=18203185
2023-04-11T17:46:52.311Z,1681235212.311 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230411T174245/Courier0000.lzma
2023-04-11T17:46:53.313Z,1681235213.313 [DataOverHttps](INFO): Moved sent file to Logs/20230411T174245/Courier0000.lzma.bak
2023-04-11T17:46:53.313Z,1681235213.313 [DataOverHttps](INFO): SBD MOMSN=18203187
2023-04-11T17:47:08.975Z,1681235228.975 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230411T163646/Express0029.lzma
2023-04-11T17:47:09.977Z,1681235229.977 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0029.lzma.bak
2023-04-11T17:47:09.978Z,1681235229.978 [DataOverHttps](INFO): SBD MOMSN=18203190
2023-04-11T17:47:25.589Z,1681235245.589 [DataOverHttps](INFO): Sending 1113 bytes from file Logs/20230411T174245/Express0001.lzma
2023-04-11T17:47:26.590Z,1681235246.590 [DataOverHttps](INFO): Moved sent file to Logs/20230411T174245/Express0001.lzma.bak
2023-04-11T17:47:26.590Z,1681235246.590 [DataOverHttps](INFO): SBD MOMSN=18203193
2023-04-11T17:47:27.908Z,1681235247.908 [Startup:StartupSatComms:B] Stopped
2023-04-11T17:47:27.908Z,1681235247.908 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-04-11T17:47:27.909Z,1681235247.909 [Startup:StartupSatComms] Stopped
2023-04-11T17:47:27.909Z,1681235247.909 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-04-11T17:47:27.909Z,1681235247.909 [Startup](INFO): Completed Startup
2023-04-11T17:47:27.910Z,1681235247.910 [MissionManager](INFO): Startup is completed.
2023-04-11T17:47:27.910Z,1681235247.910 [MissionManager](INFO): Uninitializing Mission Startup
2023-04-11T17:47:27.910Z,1681235247.910 [Startup] Stopped
2023-04-11T17:47:27.910Z,1681235247.910 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-04-11T17:47:27.910Z,1681235247.910 [Startup:A.GoToSurface] Stopped
2023-04-11T17:47:27.910Z,1681235247.910 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-11T17:47:28.331Z,1681235248.331 [MissionManager](IMPORTANT): Started mission Default
2023-04-11T17:47:28.332Z,1681235248.332 [Default] Running Loop=1
2023-04-11T17:47:28.332Z,1681235248.332 [Default](DEBUG): Aggregate::initialize Default
2023-04-11T17:47:28.332Z,1681235248.332 [Default:B.GoToSurface] Running Loop=1
2023-04-11T17:47:28.332Z,1681235248.332 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-11T17:47:28.332Z,1681235248.332 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-11T17:47:28.333Z,1681235248.333 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-11T17:47:28.333Z,1681235248.333 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-11T17:47:28.333Z,1681235248.333 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-11T17:47:28.333Z,1681235248.333 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-11T17:47:28.334Z,1681235248.334 [Default:A.Wait] Running Loop=1
2023-04-11T17:47:28.334Z,1681235248.334 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-04-11T17:47:41.650Z,1681235261.650 [Default:A.Wait](INFO): Done Waiting.
2023-04-11T17:47:41.651Z,1681235261.651 [Default:A.Wait] Stopped
2023-04-11T17:47:41.651Z,1681235261.651 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-04-11T17:47:42.060Z,1681235262.060 [Default:CheckIn] Running Loop=1
2023-04-11T17:47:42.060Z,1681235262.060 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-11T17:47:42.060Z,1681235262.060 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-11T17:47:42.452Z,1681235262.452 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-04-11T17:48:41.022Z,1681235321.022 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-11T17:48:45.613Z,1681235325.613 [DataOverHttps](IMPORTANT): SBD MTMSN=20230411T174844
2023-04-11T17:48:53.072Z,1681235333.072 [DataOverHttps](INFO): Received command: ibit
2023-04-11T17:48:53.140Z,1681235333.140 [CommandExec](IMPORTANT): got command ibit
2023-04-11T17:48:53.177Z,1681235333.177 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-04-11T17:48:53.177Z,1681235333.177 [IBIT](IMPORTANT): Beginning control surface checks.
2023-04-11T17:48:53.180Z,1681235333.180 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-11T17:48:58.820Z,1681235338.820 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-04-11T17:48:58.821Z,1681235338.821 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:48:58.881Z,1681235338.881 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:48:59.277Z,1681235339.277 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:48:59.277Z,1681235339.277 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-04-11T17:49:04.504Z,1681235344.504 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008267
CHAN A1 (24V): 0.016254
CHAN A2 (12V): 0.002285
CHAN A3 (5V): -0.001997
CHAN B0 (3.3V): -0.002038
CHAN B1 (3.15aV): -0.001459
CHAN B2 (3.15bV): -0.001742
CHAN B3 (GND): -0.003427
OPEN: 0.004840
Full Scale: +/- 1 mA
2023-04-11T17:49:06.064Z,1681235346.064 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:49:11.720Z,1681235351.720 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:49:11.735Z,1681235351.735 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-11T17:49:11.735Z,1681235351.735 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.999,+9999.99
2023-04-11T17:49:26.669Z,1681235366.669 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:49:41.620Z,1681235381.620 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:49:56.564Z,1681235396.564 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:50:11.916Z,1681235411.916 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:50:16.775Z,1681235416.775 [DVL_micro](ERROR): only read 2 of 4 data items
2023-04-11T17:50:16.779Z,1681235416.779 [DVL_micro](ERROR): Failed to parse:
:BI,+0030,+00858I
2023-04-11T17:50:26.865Z,1681235426.865 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:50:41.813Z,1681235441.813 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:50:44.297Z,1681235444.297 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2023-04-11T17:50:44.683Z,1681235444.683 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2023-04-11T17:50:45.092Z,1681235445.092 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-04-11T17:50:45.092Z,1681235445.092 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-04-11T17:50:45.092Z,1681235445.092 [IBIT](IMPORTANT): Pressure:9.862055 PSI
2023-04-11T17:50:45.093Z,1681235445.093 [IBIT](IMPORTANT): Humidity:16.450983 %
2023-04-11T17:50:45.476Z,1681235445.476 [IBIT](IMPORTANT): Vehicle Pitch:-0.329258 degrees
2023-04-11T17:50:45.477Z,1681235445.477 [IBIT](IMPORTANT): Vehicle  Roll:-4.582273 degrees
2023-04-11T17:50:45.477Z,1681235445.477 [IBIT](IMPORTANT): Vehicle  Heading:153.334366 degrees
2023-04-11T17:50:45.899Z,1681235445.899 [IBIT](IMPORTANT): surfaceThreshold: 1.500000 m
2023-04-11T17:50:45.899Z,1681235445.899 [IBIT](IMPORTANT): buoyancyNeutral: 304.139038 cc
2023-04-11T17:50:45.921Z,1681235445.921 [IBIT](IMPORTANT): massDefault: 1.911697 cm
2023-04-11T17:50:45.921Z,1681235445.921 [IBIT](IMPORTANT): stopDepth: 265.000000 m
2023-04-11T17:50:45.921Z,1681235445.921 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2023-04-11T17:50:45.921Z,1681235445.921 [IBIT](IMPORTANT): IBIT FAILED
2023-04-11T17:50:56.356Z,1681235456.356 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:51:06.877Z,1681235466.877 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175106.00,A,3648.14827,N,12147.24373,W,0.408,0.00,110423,,,A*70
2023-04-11T17:51:06.881Z,1681235466.881 [NAL9602](INFO): GPS fix at 20230411T175106: (36.802471, -121.787396)
2023-04-11T17:51:06.893Z,1681235466.893 [Default:CheckIn:Read_GPS] Stopped
2023-04-11T17:51:06.893Z,1681235466.893 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-11T17:51:07.326Z,1681235467.326 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-11T17:51:11.310Z,1681235471.310 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:51:14.675Z,1681235474.675 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20230411T174245/Courier0004.lzma
2023-04-11T17:51:15.677Z,1681235475.677 [DataOverHttps](INFO): Moved sent file to Logs/20230411T174245/Courier0004.lzma.bak
2023-04-11T17:51:15.677Z,1681235475.677 [DataOverHttps](INFO): SBD MOMSN=18203234
2023-04-11T17:51:25.855Z,1681235485.855 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:51:32.742Z,1681235492.742 [DataOverHttps](INFO): Sending 938 bytes from file Logs/20230411T174245/Express0005.lzma
2023-04-11T17:51:33.741Z,1681235493.741 [DataOverHttps](INFO): Moved sent file to Logs/20230411T174245/Express0005.lzma.bak
2023-04-11T17:51:33.742Z,1681235493.742 [DataOverHttps](INFO): SBD MOMSN=18203237
2023-04-11T17:51:39.061Z,1681235499.061 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality
2023-04-11T17:51:39.193Z,1681235499.193 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-11T17:51:39.232Z,1681235499.232 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-04-11T17:51:54.127Z,1681235514.127 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20230411T174245/Express0006.lzma
2023-04-11T17:51:55.129Z,1681235515.129 [DataOverHttps](INFO): Moved sent file to Logs/20230411T174245/Express0006.lzma.bak
2023-04-11T17:51:55.130Z,1681235515.130 [DataOverHttps](INFO): SBD MOMSN=18203262
2023-04-11T17:51:56.259Z,1681235516.259 [Default:CheckIn:Read_Iridium] Stopped
2023-04-11T17:51:56.259Z,1681235516.259 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-11T17:51:56.259Z,1681235516.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-11T17:52:39.177Z,1681235559.177 [DataOverHttps](IMPORTANT): SBD MTMSN=20230411T175238
2023-04-11T17:52:46.691Z,1681235566.691 [DataOverHttps](INFO): Received command: ! echo i > /dev/loadB6
2023-04-11T17:52:46.717Z,1681235566.717 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadB6
2023-04-11T17:53:45.924Z,1681235625.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-11T17:53:47.932Z,1681235627.932 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.238888
2023-04-11T17:54:21.072Z,1681235661.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-11T17:54:56.213Z,1681235696.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-11T17:55:01.325Z,1681235701.325 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-11T17:55:01.325Z,1681235701.325 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+23.7,009.0,000
2023-04-11T17:55:31.352Z,1681235731.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-11T17:56:06.492Z,1681235766.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-11T17:56:41.644Z,1681235801.644 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-11T17:56:56.862Z,1681235816.862 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-11T17:56:56.862Z,1681235816.862 [Default:CheckIn:C.Wait] Stopped
2023-04-11T17:56:56.862Z,1681235816.862 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-11T17:56:56.862Z,1681235816.862 [Default:CheckIn:D] Running Loop=1
2023-04-11T17:56:57.220Z,1681235817.220 [Default:CheckIn:D] Stopped
2023-04-11T17:56:57.220Z,1681235817.220 [Default:CheckIn:E] Running Loop=1
2023-04-11T17:56:57.634Z,1681235817.634 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.481471 min 
2023-04-11T17:56:57.634Z,1681235817.634 [Default:CheckIn:E] Stopped
2023-04-11T17:56:57.634Z,1681235817.634 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-11T17:56:57.634Z,1681235817.634 [Default:CheckIn] Stopped
2023-04-11T17:56:57.634Z,1681235817.634 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-11T17:56:57.635Z,1681235817.635 [Default:CheckIn](INFO): Running loop #2
2023-04-11T17:56:57.635Z,1681235817.635 [Default:CheckIn] Running Loop=2
2023-04-11T17:56:57.635Z,1681235817.635 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-11T17:56:57.635Z,1681235817.635 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-11T17:56:59.631Z,1681235819.631 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175658.00,A,3648.17205,N,12147.27876,W,0.039,0.00,110423,,,A*7E
2023-04-11T17:56:59.633Z,1681235819.633 [NAL9602](INFO): GPS fix at 20230411T175658: (36.802867, -121.787979)
2023-04-11T17:56:59.665Z,1681235819.665 [Default:CheckIn:Read_GPS] Stopped
2023-04-11T17:56:59.665Z,1681235819.665 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-11T17:57:16.788Z,1681235836.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-11T17:57:30.338Z,1681235850.338 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2023-04-11T17:57:30.415Z,1681235850.415 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-04-11T17:57:36.457Z,1681235856.457 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-04-11T17:57:51.819Z,1681235871.819 [NAL9602](INFO): SBD MO Status=2, MOMSN=14799, MT Status=2, MTMSN=0
2023-04-11T17:57:51.819Z,1681235871.819 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-11T17:57:51.932Z,1681235871.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-11T17:57:56.304Z,1681235876.304 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-04-11T17:58:03.602Z,1681235883.602 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-04-11T17:58:14.910Z,1681235894.910 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-11T17:58:14.910Z,1681235894.910 [DVL_micro](ERROR): Failed to parse:
:BI,+01121,-01488,+01009,+

2023-04-11T17:58:17.739Z,1681235897.739 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14799, MT Status=1, MTMSN=1064
2023-04-11T17:58:17.796Z,1681235897.796 [NAL9602](INFO): Sent 73 bytes from file Logs/20230411T174245/Courier0009.lzma
2023-04-11T17:58:17.796Z,1681235897.796 [NAL9602](INFO): Packets left to send: 0
2023-04-11T17:58:18.312Z,1681235898.312 [NAL9602](INFO): Received command: restart app
2023-04-11T17:58:18.329Z,1681235898.329 [CommandExec](IMPORTANT): got command restart application
2023-04-11T17:58:19.331Z,1681235899.331 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-04-11T17:58:19.332Z,1681235899.332 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:19.332Z,1681235899.332 [CommandExec](INFO): Uninitializing the command executive.
2023-04-11T17:58:19.332Z,1681235899.332 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-11T17:58:19.332Z,1681235899.332 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:19.475Z,1681235899.475 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-04-11T17:58:19.476Z,1681235899.476 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-11T17:58:19.476Z,1681235899.476 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:19.476Z,1681235899.476 [NavChartDb](INFO): Join timeout helper Thread ID is 1023
2023-04-11T17:58:19.840Z,1681235899.840 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:19.840Z,1681235899.840 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:19.847Z,1681235899.847 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-11T17:58:19.848Z,1681235899.848 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:19.848Z,1681235899.848 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1024
2023-04-11T17:58:20.256Z,1681235900.256 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:20.256Z,1681235900.256 [WetLabsBB2FL](INFO): Powering down
2023-04-11T17:58:20.257Z,1681235900.257 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:20.259Z,1681235900.259 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-11T17:58:20.260Z,1681235900.260 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:20.260Z,1681235900.260 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1025
2023-04-11T17:58:20.732Z,1681235900.732 [CTD_Seabird](INFO): Powering down
2023-04-11T17:58:20.744Z,1681235900.744 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:20.744Z,1681235900.744 [CTD_Seabird](INFO): Powering down
2023-04-11T17:58:20.755Z,1681235900.755 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:20.759Z,1681235900.759 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-04-11T17:58:20.760Z,1681235900.760 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:20.760Z,1681235900.760 [DAT](INFO): Join timeout helper Thread ID is 1026
2023-04-11T17:58:20.960Z,1681235900.960 [DAT](INFO): Powering down
2023-04-11T17:58:21.032Z,1681235901.032 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:21.032Z,1681235901.032 [DAT](INFO): Powering down
2023-04-11T17:58:21.033Z,1681235901.033 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:21.052Z,1681235901.052 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-11T17:58:21.052Z,1681235901.052 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:21.052Z,1681235901.052 [Radio_Surface](INFO): Join timeout helper Thread ID is 1027
2023-04-11T17:58:21.375Z,1681235901.375 [Radio_Surface](INFO): Powering down
2023-04-11T17:58:21.376Z,1681235901.376 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:21.377Z,1681235901.377 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:21.389Z,1681235901.389 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-11T17:58:21.389Z,1681235901.389 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:21.389Z,1681235901.389 [Onboard](INFO): Join timeout helper Thread ID is 1028
2023-04-11T17:58:22.255Z,1681235902.255 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-04-11T17:58:24.608Z,1681235904.608 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:24.608Z,1681235904.608 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:24.624Z,1681235904.624 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-11T17:58:24.624Z,1681235904.624 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:24.625Z,1681235904.625 [DataOverHttps](INFO): Join timeout helper Thread ID is 1029
2023-04-11T17:58:25.080Z,1681235905.080 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:25.080Z,1681235905.080 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.084Z,1681235905.084 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-04-11T17:58:25.084Z,1681235905.084 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.085Z,1681235905.085 [BackseatComponent](INFO): Join timeout helper Thread ID is 1030
2023-04-11T17:58:25.256Z,1681235905.256 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:25.256Z,1681235905.256 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.268Z,1681235905.268 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-04-11T17:58:25.268Z,1681235905.268 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.269Z,1681235905.269 [logger](INFO): Join timeout helper Thread ID is 1031
2023-04-11T17:58:25.308Z,1681235905.308 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:25.308Z,1681235905.308 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.328Z,1681235905.328 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-04-11T17:58:25.328Z,1681235905.328 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.329Z,1681235905.329 [CommandLine](INFO): Join timeout helper Thread ID is 1032
2023-04-11T17:58:25.344Z,1681235905.344 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:25.344Z,1681235905.344 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.348Z,1681235905.348 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-04-11T17:58:25.348Z,1681235905.348 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.349Z,1681235905.349 [CommandExec](INFO): Join timeout helper Thread ID is 1033
2023-04-11T17:58:25.350Z,1681235905.350 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-04-11T17:58:25.350Z,1681235905.350 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.350Z,1681235905.350 [controlThread](INFO): Join timeout helper Thread ID is 1034
2023-04-11T17:58:25.444Z,1681235905.444 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:58:25.444Z,1681235905.444 [controlThread](DEBUG): Uninitializing ControlThread
2023-04-11T17:58:25.445Z,1681235905.445 [AHRS_M2](INFO): Powering down
2023-04-11T17:58:25.588Z,1681235905.588 [DVL_micro](INFO): Powering down
2023-04-11T17:58:25.589Z,1681235905.589 [NAL9602](INFO): Powering down
2023-04-11T17:58:25.592Z,1681235905.592 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-04-11T17:58:25.593Z,1681235905.593 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-04-11T17:58:25.593Z,1681235905.593 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-04-11T17:58:25.594Z,1681235905.594 [MissionManager](INFO): Uninitializing Mission Default
2023-04-11T17:58:25.594Z,1681235905.594 [Default] Stopped
2023-04-11T17:58:25.594Z,1681235905.594 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-11T17:58:25.594Z,1681235905.594 [Default:B.GoToSurface] Stopped
2023-04-11T17:58:25.594Z,1681235905.594 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-11T17:58:25.594Z,1681235905.594 [Default:CheckIn] Stopped
2023-04-11T17:58:25.594Z,1681235905.594 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-11T17:58:25.594Z,1681235905.594 [Default:CheckIn:Read_Iridium] Stopped
2023-04-11T17:58:25.597Z,1681235905.597 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-04-11T17:58:25.598Z,1681235905.598 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-04-11T17:58:25.598Z,1681235905.598 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-04-11T17:58:25.598Z,1681235905.598 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-04-11T17:58:25.598Z,1681235905.598 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-04-11T17:58:25.599Z,1681235905.599 [BuoyancyServo](INFO): Powering down
2023-04-11T17:58:25.612Z,1681235905.612 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-04-11T17:58:25.612Z,1681235905.612 [ElevatorServo](INFO): Powering down
2023-04-11T17:58:25.613Z,1681235905.613 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-04-11T17:58:25.613Z,1681235905.613 [MassServo](INFO): Powering down
2023-04-11T17:58:25.614Z,1681235905.614 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-11T17:58:25.614Z,1681235905.614 [RudderServo](INFO): Powering down
2023-04-11T17:58:25.614Z,1681235905.614 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-11T17:58:25.615Z,1681235905.615 [ThrusterHE](INFO): Powering down
2023-04-11T17:58:25.616Z,1681235905.616 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-04-11T17:58:25.616Z,1681235905.616 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-04-11T17:58:25.616Z,1681235905.616 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-04-11T17:58:25.616Z,1681235905.616 [CBIT](DEBUG): Powering off loads.
2023-04-11T17:58:25.627Z,1681235905.627 [CBIT](DEBUG): Disabling WDT.
2023-04-11T17:58:25.639Z,1681235905.639 [CBIT](DEBUG): Opening all GF detection circuits.
2023-04-11T17:58:25.640Z,1681235905.640 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.686Z,1681235905.686 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.691Z,1681235905.691 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.693Z,1681235905.693 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.701Z,1681235905.701 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.744Z,1681235905.744 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.798Z,1681235905.798 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.803Z,1681235905.803 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.825Z,1681235905.825 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:58:25.884Z,1681235905.884 [logger ThreadHandler](INFO): Thread cancelled.