2023-05-27T00:28:02.372Z,1685147282.372 [Supervisor](DEBUG): Initializing supervisor.
2023-05-27T00:28:02.376Z,1685147282.376 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-27T00:28:02.377Z,1685147282.377 [SyncHandler](INFO): Protected caller Thread ID is 427
2023-05-27T00:28:02.377Z,1685147282.377 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-27T00:28:02.378Z,1685147282.378 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-27T00:28:02.379Z,1685147282.379 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 428
2023-05-27T00:28:02.383Z,1685147282.383 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-27T00:28:02.401Z,1685147282.401 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-27T00:28:02.402Z,1685147282.402 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-27T00:28:02.402Z,1685147282.402 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 429
2023-05-27T00:28:02.406Z,1685147282.406 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-27T00:28:02.407Z,1685147282.407 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-27T00:28:02.407Z,1685147282.407 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 430
2023-05-27T00:28:02.409Z,1685147282.409 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-27T00:28:02.410Z,1685147282.410 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-27T00:28:02.411Z,1685147282.411 [logger ThreadHandler](INFO): Protected caller Thread ID is 431
2023-05-27T00:28:02.414Z,1685147282.414 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-27T00:28:02.415Z,1685147282.415 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-27T00:28:02.420Z,1685147282.420 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-27T00:28:02.582Z,1685147282.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-27T00:28:02.584Z,1685147282.584 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-27T00:28:03.243Z,1685147283.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-27T00:28:03.245Z,1685147283.245 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-27T00:28:03.467Z,1685147283.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-27T00:28:03.468Z,1685147283.468 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-27T00:28:03.550Z,1685147283.550 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-27T00:28:03.678Z,1685147283.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-27T00:28:03.679Z,1685147283.679 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-27T00:28:03.762Z,1685147283.762 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-27T00:28:03.870Z,1685147283.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-27T00:28:03.871Z,1685147283.871 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-27T00:28:04.241Z,1685147284.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-27T00:28:04.242Z,1685147284.242 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-27T00:28:04.708Z,1685147284.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-27T00:28:04.710Z,1685147284.710 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-27T00:28:04.927Z,1685147284.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-27T00:28:04.928Z,1685147284.928 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-27T00:28:05.113Z,1685147285.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-27T00:28:05.115Z,1685147285.115 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-27T00:28:05.610Z,1685147285.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-27T00:28:05.610Z,1685147285.610 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-27T00:28:05.892Z,1685147285.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-27T00:28:05.893Z,1685147285.893 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-27T00:28:06.905Z,1685147286.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-27T00:28:06.906Z,1685147286.906 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-27T00:28:07.269Z,1685147287.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-27T00:28:07.271Z,1685147287.271 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-27T00:28:07.605Z,1685147287.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-27T00:28:07.608Z,1685147287.608 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-05-27T00:28:07.610Z,1685147287.610 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-05-27T00:28:07.696Z,1685147287.696 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-05-27T00:28:07.849Z,1685147287.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-05-27T00:28:07.952Z,1685147287.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-05-27T00:28:08.039Z,1685147288.039 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-05-27T00:28:08.134Z,1685147288.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-05-27T00:28:08.310Z,1685147288.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-05-27T00:28:08.570Z,1685147288.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-27T00:28:08.571Z,1685147288.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-05-27T00:28:08.666Z,1685147288.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-05-27T00:28:08.775Z,1685147288.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-05-27T00:28:08.915Z,1685147288.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-05-27T00:28:09.016Z,1685147289.016 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-27T00:28:09.026Z,1685147289.026 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-27T00:28:09.145Z,1685147289.145 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-27T00:28:09.147Z,1685147289.147 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-27T00:28:09.213Z,1685147289.213 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-27T00:28:09.272Z,1685147289.272 [VerticalControl] Loaded
2023-05-27T00:28:09.272Z,1685147289.272 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-27T00:28:09.275Z,1685147289.275 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-27T00:28:09.315Z,1685147289.315 [HorizontalControl] Loaded
2023-05-27T00:28:09.315Z,1685147289.315 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-27T00:28:09.317Z,1685147289.317 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-27T00:28:09.320Z,1685147289.320 [SpeedControl] Loaded
2023-05-27T00:28:09.320Z,1685147289.320 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-27T00:28:09.323Z,1685147289.323 [LoopControl](DEBUG): Construct LoopControl.
2023-05-27T00:28:09.323Z,1685147289.323 [LoopControl] Loaded
2023-05-27T00:28:09.324Z,1685147289.324 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-27T00:28:09.324Z,1685147289.324 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-27T00:28:09.326Z,1685147289.326 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-27T00:28:09.393Z,1685147289.393 [DepthRateCalculator] Loaded
2023-05-27T00:28:09.393Z,1685147289.393 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-27T00:28:09.398Z,1685147289.398 [PitchRateCalculator] Loaded
2023-05-27T00:28:09.398Z,1685147289.398 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-27T00:28:09.408Z,1685147289.408 [SpeedCalculator] Loaded
2023-05-27T00:28:09.408Z,1685147289.408 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-27T00:28:09.412Z,1685147289.412 [YawRateCalculator] Loaded
2023-05-27T00:28:09.412Z,1685147289.412 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-27T00:28:09.430Z,1685147289.430 [ElevatorOffsetCalculator] Loaded
2023-05-27T00:28:09.430Z,1685147289.430 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-27T00:28:09.431Z,1685147289.431 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-27T00:28:09.432Z,1685147289.432 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-27T00:28:09.475Z,1685147289.475 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-27T00:28:09.475Z,1685147289.475 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-27T00:28:09.555Z,1685147289.555 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-27T00:28:09.556Z,1685147289.556 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-27T00:28:09.902Z,1685147289.902 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-27T00:28:09.903Z,1685147289.903 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-27T00:28:10.078Z,1685147290.078 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-27T00:28:10.079Z,1685147290.079 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-27T00:28:10.838Z,1685147290.838 [AHRS_M2] Loaded
2023-05-27T00:28:10.839Z,1685147290.839 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-27T00:28:11.089Z,1685147291.089 [BackseatComponent] Loaded
2023-05-27T00:28:11.089Z,1685147291.089 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-27T00:28:11.090Z,1685147291.091 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409824E0
2023-05-27T00:28:11.091Z,1685147291.091 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 513
2023-05-27T00:28:11.094Z,1685147291.094 [LcmUniversalReporter] Loaded
2023-05-27T00:28:11.094Z,1685147291.094 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-27T00:28:12.161Z,1685147292.161 [BPC1] Loaded
2023-05-27T00:28:12.161Z,1685147292.161 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-27T00:28:12.447Z,1685147292.447 [DataOverHttps] Loaded
2023-05-27T00:28:12.448Z,1685147292.448 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-27T00:28:12.449Z,1685147292.449 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409B24E0
2023-05-27T00:28:12.449Z,1685147292.449 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 514
2023-05-27T00:28:12.469Z,1685147292.469 [Depth_Keller] Loaded
2023-05-27T00:28:12.470Z,1685147292.470 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-27T00:28:12.474Z,1685147292.474 [DropWeight] Loaded
2023-05-27T00:28:12.474Z,1685147292.474 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-05-27T00:28:12.536Z,1685147292.536 [NAL9602] Loaded
2023-05-27T00:28:12.536Z,1685147292.536 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-27T00:28:12.565Z,1685147292.565 [Onboard] Loaded
2023-05-27T00:28:12.566Z,1685147292.566 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-27T00:28:12.567Z,1685147292.567 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409E24E0
2023-05-27T00:28:12.567Z,1685147292.567 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 515
2023-05-27T00:28:12.579Z,1685147292.579 [Power24vConverter] Loaded
2023-05-27T00:28:12.579Z,1685147292.579 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-27T00:28:12.592Z,1685147292.592 [Radio_Surface] Loaded
2023-05-27T00:28:12.592Z,1685147292.592 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-27T00:28:12.593Z,1685147292.593 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A124E0
2023-05-27T00:28:12.593Z,1685147292.593 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 516
2023-05-27T00:28:12.637Z,1685147292.637 [RDI_Pathfinder] Loaded
2023-05-27T00:28:12.637Z,1685147292.637 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-05-27T00:28:12.728Z,1685147292.728 [DAT] Loaded
2023-05-27T00:28:12.728Z,1685147292.728 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-05-27T00:28:12.730Z,1685147292.730 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A424E0
2023-05-27T00:28:12.731Z,1685147292.731 [DAT ThreadHandler](INFO): Protected caller Thread ID is 517
2023-05-27T00:28:12.731Z,1685147292.731 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-27T00:28:12.732Z,1685147292.732 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-27T00:28:12.862Z,1685147292.862 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-27T00:28:12.863Z,1685147292.863 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-27T00:28:12.877Z,1685147292.877 [NavChart] Loaded
2023-05-27T00:28:12.877Z,1685147292.877 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-27T00:28:12.882Z,1685147292.882 [UniversalFixResidualReporter] Loaded
2023-05-27T00:28:12.883Z,1685147292.883 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-27T00:28:12.883Z,1685147292.883 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-27T00:28:12.885Z,1685147292.885 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-27T00:28:13.048Z,1685147293.048 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-27T00:28:13.057Z,1685147293.057 [SBIT] Loaded
2023-05-27T00:28:13.057Z,1685147293.057 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-27T00:28:13.060Z,1685147293.060 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-27T00:28:13.072Z,1685147293.072 [IBIT] Loaded
2023-05-27T00:28:13.073Z,1685147293.073 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-27T00:28:13.078Z,1685147293.078 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-27T00:28:13.180Z,1685147293.180 [CBIT] Loaded
2023-05-27T00:28:13.180Z,1685147293.180 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-27T00:28:13.180Z,1685147293.180 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-27T00:28:13.181Z,1685147293.181 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-27T00:28:13.338Z,1685147293.338 [BuoyancyServo] Loaded
2023-05-27T00:28:13.338Z,1685147293.338 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-27T00:28:13.359Z,1685147293.359 [ElevatorServo] Loaded
2023-05-27T00:28:13.359Z,1685147293.359 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-27T00:28:13.378Z,1685147293.378 [MassServo] Loaded
2023-05-27T00:28:13.378Z,1685147293.378 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-27T00:28:13.396Z,1685147293.396 [RudderServo] Loaded
2023-05-27T00:28:13.397Z,1685147293.397 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-27T00:28:13.413Z,1685147293.413 [ThrusterServo] Loaded
2023-05-27T00:28:13.414Z,1685147293.414 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-05-27T00:28:13.414Z,1685147293.414 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-27T00:28:13.415Z,1685147293.415 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-27T00:28:13.430Z,1685147293.430 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-27T00:28:13.431Z,1685147293.431 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-27T00:28:13.647Z,1685147293.647 [CTD_Seabird] Loaded
2023-05-27T00:28:13.647Z,1685147293.647 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-27T00:28:13.648Z,1685147293.648 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B8A4E0
2023-05-27T00:28:13.649Z,1685147293.649 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 518
2023-05-27T00:28:13.669Z,1685147293.669 [PAR_Licor] Loaded
2023-05-27T00:28:13.669Z,1685147293.669 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-27T00:28:13.699Z,1685147293.699 [WetLabsBB2FL] Loaded
2023-05-27T00:28:13.699Z,1685147293.699 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-27T00:28:13.700Z,1685147293.700 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BBA4E0
2023-05-27T00:28:13.701Z,1685147293.701 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 519
2023-05-27T00:28:13.720Z,1685147293.720 [WetLabsUBAT] Loaded
2023-05-27T00:28:13.720Z,1685147293.720 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-05-27T00:28:13.721Z,1685147293.721 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BEA4E0
2023-05-27T00:28:13.721Z,1685147293.721 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 520
2023-05-27T00:28:13.722Z,1685147293.722 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-27T00:28:13.728Z,1685147293.728 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-27T00:28:13.731Z,1685147293.731 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-27T00:28:13.742Z,1685147293.742 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-27T00:28:13.743Z,1685147293.743 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C1A4E0
2023-05-27T00:28:13.743Z,1685147293.743 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 521
2023-05-27T00:28:13.748Z,1685147293.748 [Supervisor](INFO): Main Thread ID is 426
2023-05-27T00:28:13.748Z,1685147293.748 [Supervisor](DEBUG): Running supervisor.
2023-05-27T00:28:13.749Z,1685147293.749 [CommandExec ThreadHandler](INFO): Handler Thread ID is 522
2023-05-27T00:28:13.749Z,1685147293.749 [CommandExec](INFO): Initializing the command executive.
2023-05-27T00:28:13.750Z,1685147293.750 [CommandLine ThreadHandler](INFO): Handler Thread ID is 523
2023-05-27T00:28:13.753Z,1685147293.753 [controlThread ThreadHandler](INFO): Handler Thread ID is 524
2023-05-27T00:28:13.753Z,1685147293.753 [controlThread](DEBUG): Initializing ControlThread
2023-05-27T00:28:13.754Z,1685147293.754 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-27T00:28:13.756Z,1685147293.756 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-27T00:28:13.756Z,1685147293.756 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-27T00:28:13.757Z,1685147293.757 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-27T00:28:13.757Z,1685147293.757 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-27T00:28:13.757Z,1685147293.757 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-27T00:28:13.758Z,1685147293.758 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-27T00:28:13.758Z,1685147293.758 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-27T00:28:13.758Z,1685147293.758 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-27T00:28:13.766Z,1685147293.766 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-27T00:28:13.766Z,1685147293.766 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-27T00:28:13.767Z,1685147293.767 [SBIT](INFO): Initialize SBIT Component.
2023-05-27T00:28:13.767Z,1685147293.767 [SBIT](IMPORTANT): git: 2023-05-23
2023-05-27T00:28:13.767Z,1685147293.767 [SBIT](INFO): git hash: b3953f130bbf661f8bdb6215fe799a82fab83260
2023-05-27T00:28:13.768Z,1685147293.768 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-27T00:28:13.769Z,1685147293.769 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-05-27T00:28:13.770Z,1685147293.770 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2023-05-27T00:28:13.770Z,1685147293.770 [IBIT](INFO): Initialize IBIT Component.
2023-05-27T00:28:13.771Z,1685147293.771 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-27T00:28:13.772Z,1685147293.772 [logger ThreadHandler](INFO): Handler Thread ID is 525
2023-05-27T00:28:13.783Z,1685147293.783 [CBIT](DEBUG): Initialized mux pins.
2023-05-27T00:28:13.784Z,1685147293.784 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-27T00:28:13.792Z,1685147293.792 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 526
2023-05-27T00:28:13.805Z,1685147293.805 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 527
2023-05-27T00:28:13.806Z,1685147293.806 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-27T00:28:13.808Z,1685147293.808 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-27T00:28:13.808Z,1685147293.808 [CBIT](DEBUG): Initializing heartbeat.
2023-05-27T00:28:13.816Z,1685147293.816 [Onboard ThreadHandler](INFO): Handler Thread ID is 528
2023-05-27T00:28:13.846Z,1685147293.846 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 529
2023-05-27T00:28:13.864Z,1685147293.864 [DAT ThreadHandler](INFO): Handler Thread ID is 530
2023-05-27T00:28:13.864Z,1685147293.864 [DAT](INFO): Powering up
2023-05-27T00:28:13.865Z,1685147293.865 [DAT](DEBUG): Initializing DAT.
2023-05-27T00:28:13.869Z,1685147293.869 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 532
2023-05-27T00:28:13.870Z,1685147293.870 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-27T00:28:13.880Z,1685147293.880 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-27T00:28:13.880Z,1685147293.880 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-27T00:28:13.884Z,1685147293.884 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 534
2023-05-27T00:28:13.888Z,1685147293.888 [WetLabsBB2FL](INFO): Powering up
2023-05-27T00:28:13.889Z,1685147293.889 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 536
2023-05-27T00:28:13.892Z,1685147293.892 [WetLabsUBAT](INFO): Powering up
2023-05-27T00:28:13.893Z,1685147293.893 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 538
2023-05-27T00:28:13.902Z,1685147293.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-27T00:28:13.902Z,1685147293.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-27T00:28:13.902Z,1685147293.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-27T00:28:13.902Z,1685147293.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-27T00:28:13.902Z,1685147293.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-27T00:28:13.903Z,1685147293.903 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-27T00:28:13.903Z,1685147293.903 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-27T00:28:13.903Z,1685147293.903 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-27T00:28:13.919Z,1685147293.919 [CBIT](DEBUG): Backplane powered.
2023-05-27T00:28:13.924Z,1685147293.924 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-27T00:28:13.960Z,1685147293.960 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-27T00:28:13.981Z,1685147293.981 [MissionManager](DEBUG):
2023-05-27T00:28:13.981Z,1685147293.981 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-27T00:28:14.081Z,1685147294.081 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-27T00:28:14.082Z,1685147294.082 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-27T00:28:14.085Z,1685147294.085 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-27T00:28:14.121Z,1685147294.121 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-27T00:28:14.139Z,1685147294.139 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-27T00:28:14.145Z,1685147294.145 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-27T00:28:14.161Z,1685147294.161 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2023-05-27T00:28:14.166Z,1685147294.166 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-27T00:28:14.191Z,1685147294.191 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-27T00:28:14.247Z,1685147294.247 [Radio_Surface](INFO): Powering up
2023-05-27T00:28:14.340Z,1685147294.340 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-05-27T00:28:14.341Z,1685147294.341 [Power24vConverter](INFO): Powering up.
2023-05-27T00:28:14.342Z,1685147294.342 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-05-27T00:28:14.354Z,1685147294.354 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-05-27T00:28:14.393Z,1685147294.393 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-27T00:28:14.400Z,1685147294.400 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-27T00:28:14.401Z,1685147294.401 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-27T00:28:14.408Z,1685147294.408 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-27T00:28:14.409Z,1685147294.409 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-27T00:28:14.416Z,1685147294.416 [MassServo](DEBUG): Initializing MassServo.
2023-05-27T00:28:14.417Z,1685147294.417 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-27T00:28:14.424Z,1685147294.424 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-27T00:28:14.425Z,1685147294.425 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-05-27T00:28:14.432Z,1685147294.432 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-05-27T00:28:14.617Z,1685147294.617 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2023-05-27T00:28:14.617Z,1685147294.617 [DropWeight] Hardware Fault, FailCount= 1
2023-05-27T00:28:14.617Z,1685147294.617 [DropWeight](ERROR): Hardware Fault
2023-05-27T00:28:14.646Z,1685147294.646 [CommandExec](FAULT): Scheduling is paused
2023-05-27T00:28:14.647Z,1685147294.647 [CBIT](INFO): Critical error at 20230527T002814
2023-05-27T00:28:14.647Z,1685147294.647 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-05-27T00:28:14.658Z,1685147294.658 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-05-27T00:28:14.658Z,1685147294.658 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-05-27T00:28:15.002Z,1685147295.002 [CBIT](INFO): Critical error at 20230527T002814
2023-05-27T00:28:16.500Z,1685147296.500 [WetLabsBB2FL](INFO): Powering down
2023-05-27T00:28:23.137Z,1685147303.137 [WetLabsUBAT](INFO): Powering down
2023-05-27T00:28:25.968Z,1685147305.968 [DAT](INFO): DAT read:
2023-05-27T00:28:25.970Z,1685147305.970 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2023-05-27T00:28:27.732Z,1685147307.732 [DAT](INFO): DAT read: MF Frequency Band
2023-05-27T00:28:27.734Z,1685147307.734 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2023-05-27T00:28:27.734Z,1685147307.734 [DAT](INFO): DAT read: May 26 2023 22:18:16
2023-05-27T00:28:27.860Z,1685147307.860 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.012516
2023-05-27T00:28:28.741Z,1685147308.741 [DAT](INFO): DAT read: Features enabled [Bearing]
2023-05-27T00:28:28.742Z,1685147308.742 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2023-05-27T00:28:28.742Z,1685147308.742 [DAT](INFO): commRate: 800
2023-05-27T00:28:30.807Z,1685147310.807 [DAT](INFO): entering command mode
2023-05-27T00:28:31.008Z,1685147311.008 [DAT](INFO): DAT read:
2023-05-27T00:28:31.009Z,1685147311.009 [DAT](INFO): DAT read: user:1>
2023-05-27T00:28:31.009Z,1685147311.009 [DAT](INFO): setting verbose to 3
2023-05-27T00:28:31.260Z,1685147311.260 [DAT](INFO): DAT read: user:1>
2023-05-27T00:28:31.261Z,1685147311.261 [DAT](INFO): DAT read: Verbose | 3
2023-05-27T00:28:31.262Z,1685147311.262 [DAT](INFO): set verbose to 3
2023-05-27T00:28:31.262Z,1685147311.262 [DAT](INFO): setting DatVerbose to 27440
2023-05-27T00:28:31.512Z,1685147311.512 [DAT](INFO): DAT read: user:2>
2023-05-27T00:28:31.513Z,1685147311.513 [DAT](INFO): DAT read: DatVerbose | 27440
2023-05-27T00:28:31.513Z,1685147311.513 [DAT](INFO): set DatVerbose to 27440
2023-05-27T00:28:31.514Z,1685147311.514 [DAT](INFO): setting transmit power to 8
2023-05-27T00:28:31.764Z,1685147311.764 [DAT](INFO): DAT read: user:3>
2023-05-27T00:28:31.765Z,1685147311.765 [DAT](INFO): DAT read: TxPower | 8 (Max)
2023-05-27T00:28:31.765Z,1685147311.765 [DAT](INFO): set transmit power to 8
2023-05-27T00:28:31.766Z,1685147311.766 [DAT](INFO): setting local address to 10
2023-05-27T00:28:32.016Z,1685147312.016 [DAT](INFO): DAT read: user:4>
2023-05-27T00:28:32.017Z,1685147312.017 [DAT](INFO): DAT read: LocalAddr | 10
2023-05-27T00:28:32.018Z,1685147312.018 [DAT](INFO): set local address to 10
2023-05-27T00:28:32.019Z,1685147312.019 [DAT](INFO): Setting time to: 0:28:32 And date to:5/27/2023
2023-05-27T00:28:32.268Z,1685147312.268 [DAT](INFO): DAT read: user:5>
2023-05-27T00:28:32.269Z,1685147312.269 [DAT](INFO): DAT read: Sat May 27, 2023 00:28:32
2023-05-27T00:28:32.270Z,1685147312.270 [DAT](INFO): Local DAT time set to Sat May 27, 2023 00:28:32
2023-05-27T00:28:42.861Z,1685147322.861 [NAL9602](INFO): Powering up NAL9602
2023-05-27T00:28:50.284Z,1685147330.284 [CommandExec](IMPORTANT): got command failComponent
2023-05-27T00:28:50.284Z,1685147330.284 [CommandExec](IMPORTANT): Failed components:
2023-05-27T00:28:50.285Z,1685147330.285 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-05-27T00:28:53.769Z,1685147333.769 [NAL9602](INFO): NAL9602 initialized
2023-05-27T00:29:03.503Z,1685147343.503 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-27T00:29:03.538Z,1685147343.538 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-27T00:29:14.281Z,1685147354.281 [CommandExec](IMPORTANT): got command get AHRS_M2.loadControl
2023-05-27T00:29:14.281Z,1685147354.281 [CommandExec](IMPORTANT): AHRS_M2.loadControl /dev/loadC5
2023-05-27T00:29:14.887Z,1685147354.887 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.021479
CHAN A1 (24V): 0.028289
CHAN A2 (12V): 0.006264
CHAN A3 (5V): 0.002928
CHAN B0 (3.3V): 0.004057
CHAN B1 (3.15aV): 0.003271
CHAN B2 (3.15bV): 0.003247
CHAN B3 (GND): 0.002583
OPEN: 0.007030
Full Scale: +/- 1 mA
2023-05-27T00:29:19.690Z,1685147359.690 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadC5
2023-05-27T00:29:29.727Z,1685147369.727 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-05-27T00:29:29.728Z,1685147369.728 [AHRS_M2] Data Fault, FailCount= 1
2023-05-27T00:29:29.728Z,1685147369.728 [AHRS_M2](ERROR): Data Fault
2023-05-27T00:29:29.862Z,1685147369.862 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-05-27T00:29:30.120Z,1685147370.120 [AHRS_M2](INFO): Powering down
2023-05-27T00:29:30.525Z,1685147370.525 [AHRS_M2](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-05-27T00:29:30.525Z,1685147370.525 [AHRS_M2] Hardware Fault, FailCount= 1
2023-05-27T00:29:30.525Z,1685147370.525 [AHRS_M2](ERROR): Hardware Fault
2023-05-27T00:29:30.829Z,1685147370.829 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadC5
2023-05-27T00:29:31.006Z,1685147371.006 [CBIT](INFO): Clearing failed state for component AHRS_M2
2023-05-27T00:29:31.006Z,1685147371.006 [AHRS_M2] No Fault, FailCount= 1
2023-05-27T00:29:31.332Z,1685147371.332 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-27T00:29:32.598Z,1685147372.598 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadC5
2023-05-27T00:29:43.413Z,1685147383.413 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadC5
2023-05-27T00:29:44.739Z,1685147384.739 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-05-27T00:29:44.739Z,1685147384.739 [AHRS_M2] Data Fault, FailCount= 2
2023-05-27T00:29:44.739Z,1685147384.739 [AHRS_M2](ERROR): Data Fault
2023-05-27T00:29:44.788Z,1685147384.788 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-05-27T00:29:45.068Z,1685147385.068 [AHRS_M2](INFO): Powering down
2023-05-27T00:29:46.093Z,1685147386.093 [CBIT](INFO): Clearing failed state for component AHRS_M2
2023-05-27T00:29:46.093Z,1685147386.093 [AHRS_M2] No Fault, FailCount= 2
2023-05-27T00:29:46.280Z,1685147386.280 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-27T00:29:46.433Z,1685147386.433 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadC5
2023-05-27T00:29:56.105Z,1685147396.105 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadC5
2023-05-27T00:29:57.698Z,1685147397.698 [SBIT](IMPORTANT): SBIT PASSED
2023-05-27T00:29:57.698Z,1685147397.698 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-27T00:29:57.700Z,1685147397.700 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;
2023-05-27T00:29:57.700Z,1685147397.700 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;
2023-05-27T00:29:57.700Z,1685147397.700 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;
2023-05-27T00:29:57.700Z,1685147397.700 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=274.761614 cubic_centimeter;
2023-05-27T00:29:57.700Z,1685147397.700 [SBIT](IMPORTANT): VerticalControl.massDefault=7.429581 millimeter;
2023-05-27T00:29:57.700Z,1685147397.700 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;
2023-05-27T00:29:58.121Z,1685147398.121 [MissionManager](IMPORTANT): Started mission Startup
2023-05-27T00:29:58.122Z,1685147398.122 [Startup] Running Loop=1
2023-05-27T00:29:58.122Z,1685147398.122 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-27T00:29:58.122Z,1685147398.122 [Startup:A.GoToSurface] Running Loop=1
2023-05-27T00:29:58.122Z,1685147398.122 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-27T00:29:58.123Z,1685147398.123 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-27T00:29:58.139Z,1685147398.139 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-27T00:29:58.140Z,1685147398.140 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-27T00:29:58.140Z,1685147398.140 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-27T00:29:58.141Z,1685147398.141 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-27T00:29:58.142Z,1685147398.142 [Startup:StartupSatComms] Running Loop=1
2023-05-27T00:29:58.142Z,1685147398.142 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-27T00:29:58.142Z,1685147398.142 [Startup:StartupSatComms:A] Running Loop=1
2023-05-27T00:29:58.476Z,1685147398.476 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-27T00:29:59.623Z,1685147399.623 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-05-27T00:29:59.623Z,1685147399.623 [AHRS_M2] Data Fault, FailCount= 3
2023-05-27T00:29:59.623Z,1685147399.623 [AHRS_M2](ERROR): Data Fault
2023-05-27T00:29:59.702Z,1685147399.702 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-05-27T00:30:00.016Z,1685147400.016 [AHRS_M2](INFO): Powering down
2023-05-27T00:30:00.852Z,1685147400.852 [CBIT](INFO): Clearing failed state for component AHRS_M2
2023-05-27T00:30:00.852Z,1685147400.852 [AHRS_M2] No Fault, FailCount= 3
2023-05-27T00:30:01.228Z,1685147401.228 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-27T00:30:01.620Z,1685147401.620 [CommandExec](IMPORTANT): got command failComponent
2023-05-27T00:30:01.620Z,1685147401.620 [CommandExec](IMPORTANT): Failed components:
2023-05-27T00:30:01.620Z,1685147401.620 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-05-27T00:30:09.052Z,1685147409.052 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2
2023-05-27T00:30:09.052Z,1685147409.052 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault
2023-05-27T00:30:14.571Z,1685147414.571 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-05-27T00:30:14.572Z,1685147414.572 [AHRS_M2] Data Fault, FailCount= 4
2023-05-27T00:30:14.572Z,1685147414.572 [AHRS_M2](ERROR): Data Fault
2023-05-27T00:30:14.597Z,1685147414.597 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-05-27T00:30:14.964Z,1685147414.964 [AHRS_M2](INFO): Powering down
2023-05-27T00:30:15.797Z,1685147415.797 [CBIT](INFO): Clearing failed state for component AHRS_M2
2023-05-27T00:30:15.797Z,1685147415.797 [AHRS_M2] No Fault, FailCount= 4
2023-05-27T00:30:16.176Z,1685147416.176 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-27T00:30:18.429Z,1685147418.429 [CommandExec](IMPORTANT): got command get platform_pitch_angle degree
2023-05-27T00:30:18.429Z,1685147418.429 [CommandExec](FAULT): Element has no value
2023-05-27T00:30:29.372Z,1685147429.372 [CommandExec](IMPORTANT): got command failComponent
2023-05-27T00:30:29.372Z,1685147429.372 [CommandExec](IMPORTANT): Failed components:
2023-05-27T00:30:29.372Z,1685147429.372 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-05-27T00:30:29.519Z,1685147429.519 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-05-27T00:30:29.519Z,1685147429.519 [AHRS_M2] Data Fault, FailCount= 5
2023-05-27T00:30:29.519Z,1685147429.519 [AHRS_M2](ERROR): Data Fault
2023-05-27T00:30:29.572Z,1685147429.572 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-05-27T00:30:29.573Z,1685147429.573 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2023-05-27T00:30:29.912Z,1685147429.912 [AHRS_M2](INFO): Powering down
2023-05-27T00:30:30.028Z,1685147430.028 [CBIT](INFO): Critical error at 20230527T003029
2023-05-27T00:30:34.792Z,1685147434.792 [CommandExec](IMPORTANT): got command failComponent
2023-05-27T00:30:34.792Z,1685147434.792 [CommandExec](IMPORTANT): Failed components:
2023-05-27T00:30:34.793Z,1685147434.793 [CommandExec](IMPORTANT): AHRS_M2: Data Fault
2023-05-27T00:30:34.793Z,1685147434.793 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-05-27T00:30:37.400Z,1685147437.400 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2
2023-05-27T00:30:37.400Z,1685147437.400 [AHRS_M2] No Fault, FailCount= 5
2023-05-27T00:30:37.400Z,1685147437.400 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault
2023-05-27T00:30:37.588Z,1685147437.588 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-27T00:30:47.354Z,1685147447.354 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-27T00:30:50.931Z,1685147450.931 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-05-27T00:30:50.931Z,1685147450.931 [AHRS_M2] Data Fault, FailCount= 6
2023-05-27T00:30:50.931Z,1685147450.931 [AHRS_M2](ERROR): Data Fault
2023-05-27T00:30:50.957Z,1685147450.957 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-05-27T00:30:50.957Z,1685147450.957 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2023-05-27T00:30:51.324Z,1685147451.324 [AHRS_M2](INFO): Powering down
2023-05-27T00:30:51.418Z,1685147451.418 [CBIT](INFO): Critical error at 20230527T003050
2023-05-27T00:30:55.413Z,1685147455.413 [CommandExec](IMPORTANT): got command restart application
2023-05-27T00:30:56.416Z,1685147456.416 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:56.416Z,1685147456.416 [CommandExec](INFO): Uninitializing the command executive.
2023-05-27T00:30:56.416Z,1685147456.416 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-27T00:30:56.416Z,1685147456.416 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:56.463Z,1685147456.463 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2023-05-27T00:30:56.464Z,1685147456.464 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-27T00:30:56.464Z,1685147456.464 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:56.464Z,1685147456.464 [NavChartDb](INFO): Join timeout helper Thread ID is 566
2023-05-27T00:30:56.529Z,1685147456.529 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:56.529Z,1685147456.529 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:56.547Z,1685147456.547 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-05-27T00:30:56.548Z,1685147456.548 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:56.548Z,1685147456.548 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 567
2023-05-27T00:30:56.560Z,1685147456.560 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:56.560Z,1685147456.560 [WetLabsUBAT](INFO): Powering down
2023-05-27T00:30:56.561Z,1685147456.561 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:56.588Z,1685147456.588 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-27T00:30:56.588Z,1685147456.588 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:56.588Z,1685147456.588 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 568
2023-05-27T00:30:56.764Z,1685147456.764 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:56.764Z,1685147456.764 [WetLabsBB2FL](INFO): Powering down
2023-05-27T00:30:56.765Z,1685147456.765 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:56.784Z,1685147456.784 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-27T00:30:56.784Z,1685147456.784 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:56.784Z,1685147456.784 [CTD_Seabird](INFO): Join timeout helper Thread ID is 569
2023-05-27T00:30:57.520Z,1685147457.520 [CTD_Seabird](INFO): Powering down
2023-05-27T00:30:57.531Z,1685147457.531 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:57.532Z,1685147457.532 [CTD_Seabird](INFO): Powering down
2023-05-27T00:30:57.543Z,1685147457.543 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:57.562Z,1685147457.562 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-05-27T00:30:57.562Z,1685147457.562 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:57.563Z,1685147457.563 [DAT](INFO): Join timeout helper Thread ID is 570
2023-05-27T00:30:57.788Z,1685147457.788 [DAT](INFO): Powering down
2023-05-27T00:30:57.861Z,1685147457.861 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:57.861Z,1685147457.861 [DAT](INFO): Powering down
2023-05-27T00:30:57.862Z,1685147457.862 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:57.877Z,1685147457.877 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-27T00:30:57.877Z,1685147457.877 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:57.878Z,1685147457.878 [Radio_Surface](INFO): Join timeout helper Thread ID is 571
2023-05-27T00:30:57.944Z,1685147457.944 [Radio_Surface](INFO): Powering down
2023-05-27T00:30:57.944Z,1685147457.944 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:57.945Z,1685147457.945 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:57.956Z,1685147457.956 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-27T00:30:57.956Z,1685147457.956 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:57.957Z,1685147457.957 [Onboard](INFO): Join timeout helper Thread ID is 572
2023-05-27T00:30:58.198Z,1685147458.198 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-27T00:30:58.256Z,1685147458.256 [Startup:StartupSatComms:A](INFO): Timed out from 2023-05-27T00:29:58.1Z
2023-05-27T00:30:58.256Z,1685147458.256 [Startup:StartupSatComms:A] Stopped
2023-05-27T00:30:58.256Z,1685147458.256 [Startup:StartupSatComms:B] Running Loop=1
2023-05-27T00:30:58.629Z,1685147458.629 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-27T00:30:58.947Z,1685147458.947 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:58.948Z,1685147458.948 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:58.964Z,1685147458.964 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-27T00:30:58.964Z,1685147458.964 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:58.965Z,1685147458.965 [DataOverHttps](INFO): Join timeout helper Thread ID is 573
2023-05-27T00:30:59.200Z,1685147459.200 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:59.200Z,1685147459.200 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.212Z,1685147459.212 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-27T00:30:59.212Z,1685147459.212 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.213Z,1685147459.213 [BackseatComponent](INFO): Join timeout helper Thread ID is 574
2023-05-27T00:30:59.252Z,1685147459.252 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:59.252Z,1685147459.252 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.264Z,1685147459.264 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-27T00:30:59.264Z,1685147459.264 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.265Z,1685147459.265 [logger](INFO): Join timeout helper Thread ID is 575
2023-05-27T00:30:59.275Z,1685147459.275 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:59.276Z,1685147459.276 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.284Z,1685147459.284 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-27T00:30:59.284Z,1685147459.284 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.285Z,1685147459.285 [CommandLine](INFO): Join timeout helper Thread ID is 576
2023-05-27T00:30:59.304Z,1685147459.304 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:59.304Z,1685147459.304 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.331Z,1685147459.331 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-27T00:30:59.331Z,1685147459.331 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.331Z,1685147459.331 [CommandExec](INFO): Join timeout helper Thread ID is 577
2023-05-27T00:30:59.332Z,1685147459.332 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-27T00:30:59.333Z,1685147459.333 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.333Z,1685147459.333 [controlThread](INFO): Join timeout helper Thread ID is 578
2023-05-27T00:30:59.400Z,1685147459.400 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-27T00:30:59.400Z,1685147459.400 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-27T00:30:59.400Z,1685147459.400 [AHRS_M2](INFO): Powering down
2023-05-27T00:30:59.402Z,1685147459.402 [NAL9602](INFO): Powering down
2023-05-27T00:30:59.476Z,1685147459.476 [RDI_Pathfinder](INFO): Powering down
2023-05-27T00:30:59.477Z,1685147459.477 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-27T00:30:59.478Z,1685147459.478 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-27T00:30:59.478Z,1685147459.478 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-27T00:30:59.479Z,1685147459.479 [MissionManager](INFO): Uninitializing Mission Default
2023-05-27T00:30:59.481Z,1685147459.481 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-27T00:30:59.481Z,1685147459.481 [Startup] Stopped
2023-05-27T00:30:59.482Z,1685147459.482 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-27T00:30:59.482Z,1685147459.482 [Startup:A.GoToSurface] Stopped
2023-05-27T00:30:59.482Z,1685147459.482 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-27T00:30:59.482Z,1685147459.482 [Startup:StartupSatComms] Stopped
2023-05-27T00:30:59.482Z,1685147459.482 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-27T00:30:59.482Z,1685147459.482 [Startup:StartupSatComms:B] Stopped
2023-05-27T00:30:59.483Z,1685147459.483 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-27T00:30:59.484Z,1685147459.484 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-27T00:30:59.484Z,1685147459.484 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-27T00:30:59.484Z,1685147459.484 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-27T00:30:59.485Z,1685147459.485 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-27T00:30:59.485Z,1685147459.485 [BuoyancyServo](INFO): Powering down
2023-05-27T00:30:59.500Z,1685147459.500 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-27T00:30:59.500Z,1685147459.500 [ElevatorServo](INFO): Powering down
2023-05-27T00:30:59.501Z,1685147459.501 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-27T00:30:59.501Z,1685147459.501 [MassServo](INFO): Powering down
2023-05-27T00:30:59.501Z,1685147459.501 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-27T00:30:59.502Z,1685147459.502 [RudderServo](INFO): Powering down
2023-05-27T00:30:59.502Z,1685147459.502 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-05-27T00:30:59.502Z,1685147459.502 [ThrusterServo](INFO): Powering down
2023-05-27T00:30:59.503Z,1685147459.503 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-27T00:30:59.504Z,1685147459.504 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-27T00:30:59.504Z,1685147459.504 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-27T00:30:59.504Z,1685147459.504 [CBIT](DEBUG): Powering off loads.
2023-05-27T00:30:59.515Z,1685147459.515 [CBIT](DEBUG): Disabling WDT.
2023-05-27T00:30:59.527Z,1685147459.527 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-27T00:30:59.528Z,1685147459.528 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.578Z,1685147459.578 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.585Z,1685147459.585 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.586Z,1685147459.586 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.592Z,1685147459.592 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.634Z,1685147459.634 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.694Z,1685147459.694 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.696Z,1685147459.696 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.699Z,1685147459.699 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.707Z,1685147459.707 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-27T00:30:59.770Z,1685147459.770 [logger ThreadHandler](INFO): Thread cancelled.