2022-10-25T22:05:38.024Z,1666735538.024 [Supervisor](DEBUG): Initializing supervisor.
2022-10-25T22:05:38.029Z,1666735538.029 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-10-25T22:05:38.029Z,1666735538.029 [SyncHandler](INFO): Protected caller Thread ID is 834
2022-10-25T22:05:38.030Z,1666735538.030 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-10-25T22:05:38.031Z,1666735538.031 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-10-25T22:05:38.031Z,1666735538.031 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835
2022-10-25T22:05:38.035Z,1666735538.035 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-10-25T22:05:38.052Z,1666735538.052 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-10-25T22:05:38.053Z,1666735538.053 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-10-25T22:05:38.053Z,1666735538.053 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836
2022-10-25T22:05:38.057Z,1666735538.057 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-10-25T22:05:38.058Z,1666735538.058 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-10-25T22:05:38.059Z,1666735538.059 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
2022-10-25T22:05:38.061Z,1666735538.061 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-10-25T22:05:38.062Z,1666735538.062 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-10-25T22:05:38.062Z,1666735538.062 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
2022-10-25T22:05:38.066Z,1666735538.066 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-10-25T22:05:38.066Z,1666735538.066 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-10-25T22:05:38.070Z,1666735538.070 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-10-25T22:05:38.476Z,1666735538.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-10-25T22:05:38.477Z,1666735538.477 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-10-25T22:05:38.696Z,1666735538.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-10-25T22:05:38.697Z,1666735538.697 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-10-25T22:05:38.875Z,1666735538.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-10-25T22:05:38.877Z,1666735538.877 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-10-25T22:05:38.981Z,1666735538.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-10-25T22:05:38.983Z,1666735538.983 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-10-25T22:05:39.326Z,1666735539.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-10-25T22:05:39.327Z,1666735539.327 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-10-25T22:05:39.651Z,1666735539.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-10-25T22:05:39.651Z,1666735539.651 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-10-25T22:05:39.746Z,1666735539.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-10-25T22:05:39.748Z,1666735539.748 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-10-25T22:05:39.956Z,1666735539.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-10-25T22:05:39.956Z,1666735539.956 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-10-25T22:05:40.445Z,1666735540.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-10-25T22:05:40.447Z,1666735540.447 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-10-25T22:05:40.789Z,1666735540.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-10-25T22:05:40.789Z,1666735540.789 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-10-25T22:05:40.871Z,1666735540.871 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-10-25T22:05:41.075Z,1666735541.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-10-25T22:05:41.077Z,1666735541.077 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-10-25T22:05:41.242Z,1666735541.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-10-25T22:05:41.243Z,1666735541.243 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-10-25T22:05:42.515Z,1666735542.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-10-25T22:05:42.515Z,1666735542.515 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-10-25T22:05:42.595Z,1666735542.595 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-10-25T22:05:42.875Z,1666735542.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-10-25T22:05:42.877Z,1666735542.877 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2022-10-25T22:05:42.880Z,1666735542.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2022-10-25T22:05:43.070Z,1666735543.070 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2022-10-25T22:05:43.163Z,1666735543.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2022-10-25T22:05:43.275Z,1666735543.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2022-10-25T22:05:43.375Z,1666735543.375 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2022-10-25T22:05:43.459Z,1666735543.459 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2022-10-25T22:05:43.543Z,1666735543.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2022-10-25T22:05:43.655Z,1666735543.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2022-10-25T22:05:43.753Z,1666735543.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2022-10-25T22:05:43.780Z,1666735543.780 [Config/Sensor](ERROR): Could not find unit: int32 following MultiRay.brightnessWhite = 20
2022-10-25T22:05:43.781Z,1666735543.781 [Config/Sensor](ERROR): Could not find unit: int32 following MultiRay.brightnessRed = 20
2022-10-25T22:05:43.904Z,1666735543.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2022-10-25T22:05:44.162Z,1666735544.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-10-25T22:05:44.163Z,1666735544.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2022-10-25T22:05:44.255Z,1666735544.255 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-10-25T22:05:44.274Z,1666735544.274 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-10-25T22:05:44.628Z,1666735544.628 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-10-25T22:05:44.630Z,1666735544.630 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-10-25T22:05:44.765Z,1666735544.765 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-10-25T22:05:44.766Z,1666735544.766 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-10-25T22:05:44.804Z,1666735544.804 [DeadReckonUsingSpeedCalculator] Loaded
2022-10-25T22:05:44.804Z,1666735544.804 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2022-10-25T22:05:44.818Z,1666735544.818 [NavChart] Loaded
2022-10-25T22:05:44.818Z,1666735544.818 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-10-25T22:05:44.823Z,1666735544.823 [UniversalFixResidualReporter] Loaded
2022-10-25T22:05:44.823Z,1666735544.823 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-10-25T22:05:44.824Z,1666735544.824 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-10-25T22:05:44.824Z,1666735544.824 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-10-25T22:05:44.840Z,1666735544.840 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-10-25T22:05:44.840Z,1666735544.840 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-10-25T22:05:45.041Z,1666735545.041 [PAR_Licor] Loaded
2022-10-25T22:05:45.041Z,1666735545.041 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-10-25T22:05:45.072Z,1666735545.072 [WetLabsBB2FL] Loaded
2022-10-25T22:05:45.072Z,1666735545.072 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-10-25T22:05:45.073Z,1666735545.073 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065A4E0
2022-10-25T22:05:45.074Z,1666735545.074 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 921
2022-10-25T22:05:45.075Z,1666735545.075 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-10-25T22:05:45.076Z,1666735545.076 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-10-25T22:05:45.143Z,1666735545.143 [VerticalControl](DEBUG): Construct VerticalControl.
2022-10-25T22:05:45.203Z,1666735545.203 [VerticalControl] Loaded
2022-10-25T22:05:45.203Z,1666735545.203 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-10-25T22:05:45.206Z,1666735545.206 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-10-25T22:05:45.247Z,1666735545.247 [HorizontalControl] Loaded
2022-10-25T22:05:45.247Z,1666735545.247 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-10-25T22:05:45.250Z,1666735545.250 [SpeedControl](DEBUG): Construct SpeedControl.
2022-10-25T22:05:45.253Z,1666735545.253 [SpeedControl] Loaded
2022-10-25T22:05:45.253Z,1666735545.253 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-10-25T22:05:45.256Z,1666735545.256 [LoopControl](DEBUG): Construct LoopControl.
2022-10-25T22:05:45.256Z,1666735545.256 [LoopControl] Loaded
2022-10-25T22:05:45.256Z,1666735545.256 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-10-25T22:05:45.257Z,1666735545.257 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-10-25T22:05:45.258Z,1666735545.258 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-10-25T22:05:45.326Z,1666735545.326 [DepthRateCalculator] Loaded
2022-10-25T22:05:45.327Z,1666735545.327 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-10-25T22:05:45.331Z,1666735545.331 [PitchRateCalculator] Loaded
2022-10-25T22:05:45.332Z,1666735545.332 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-10-25T22:05:45.341Z,1666735545.341 [SpeedCalculator] Loaded
2022-10-25T22:05:45.342Z,1666735545.342 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-10-25T22:05:45.346Z,1666735545.346 [YawRateCalculator] Loaded
2022-10-25T22:05:45.346Z,1666735545.346 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-10-25T22:05:45.364Z,1666735545.364 [ElevatorOffsetCalculator] Loaded
2022-10-25T22:05:45.364Z,1666735545.364 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-10-25T22:05:45.365Z,1666735545.365 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-10-25T22:05:45.367Z,1666735545.367 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-10-25T22:05:45.482Z,1666735545.482 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-10-25T22:05:45.483Z,1666735545.483 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-10-25T22:05:45.565Z,1666735545.565 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-10-25T22:05:45.566Z,1666735545.566 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-10-25T22:05:45.713Z,1666735545.713 [BuoyancyServo] Loaded
2022-10-25T22:05:45.714Z,1666735545.714 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-10-25T22:05:45.733Z,1666735545.733 [ElevatorServo] Loaded
2022-10-25T22:05:45.733Z,1666735545.733 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-10-25T22:05:45.752Z,1666735545.752 [MassServo] Loaded
2022-10-25T22:05:45.752Z,1666735545.752 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-10-25T22:05:45.770Z,1666735545.770 [RudderServo] Loaded
2022-10-25T22:05:45.771Z,1666735545.771 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-10-25T22:05:45.785Z,1666735545.785 [ThrusterHE] Loaded
2022-10-25T22:05:45.785Z,1666735545.785 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-10-25T22:05:45.785Z,1666735545.785 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-10-25T22:05:45.786Z,1666735545.786 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-10-25T22:05:45.968Z,1666735545.968 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-10-25T22:05:45.968Z,1666735545.968 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-10-25T22:05:46.664Z,1666735546.664 [AHRS_M2] Loaded
2022-10-25T22:05:46.664Z,1666735546.664 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-10-25T22:05:46.913Z,1666735546.913 [BackseatComponent] Loaded
2022-10-25T22:05:46.914Z,1666735546.914 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-10-25T22:05:46.915Z,1666735546.915 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A504E0
2022-10-25T22:05:46.915Z,1666735546.915 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922
2022-10-25T22:05:46.918Z,1666735546.918 [LcmUniversalReporter] Loaded
2022-10-25T22:05:46.918Z,1666735546.918 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-10-25T22:05:48.069Z,1666735548.069 [BPC1] Loaded
2022-10-25T22:05:48.070Z,1666735548.070 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-10-25T22:05:48.374Z,1666735548.374 [DataOverHttps] Loaded
2022-10-25T22:05:48.375Z,1666735548.375 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-10-25T22:05:48.376Z,1666735548.376 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A804E0
2022-10-25T22:05:48.376Z,1666735548.376 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923
2022-10-25T22:05:48.484Z,1666735548.484 [Depth_Keller] Loaded
2022-10-25T22:05:48.484Z,1666735548.484 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-10-25T22:05:48.491Z,1666735548.491 [DropWeight] Loaded
2022-10-25T22:05:48.491Z,1666735548.491 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-10-25T22:05:48.552Z,1666735548.552 [NAL9602] Loaded
2022-10-25T22:05:48.552Z,1666735548.552 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-10-25T22:05:48.582Z,1666735548.582 [Onboard] Loaded
2022-10-25T22:05:48.582Z,1666735548.582 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-10-25T22:05:48.583Z,1666735548.583 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2022-10-25T22:05:48.584Z,1666735548.584 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924
2022-10-25T22:05:48.590Z,1666735548.590 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2022-10-25T22:05:48.601Z,1666735548.601 [PowerOnly] Loaded
2022-10-25T22:05:48.601Z,1666735548.601 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-10-25T22:05:48.613Z,1666735548.613 [Power24vConverter] Loaded
2022-10-25T22:05:48.613Z,1666735548.613 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-10-25T22:05:48.626Z,1666735548.626 [Radio_Surface] Loaded
2022-10-25T22:05:48.626Z,1666735548.626 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-10-25T22:05:48.627Z,1666735548.627 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AE04E0
2022-10-25T22:05:48.628Z,1666735548.628 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925
2022-10-25T22:05:48.719Z,1666735548.719 [DAT] Loaded
2022-10-25T22:05:48.719Z,1666735548.719 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-10-25T22:05:48.720Z,1666735548.720 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B104E0
2022-10-25T22:05:48.721Z,1666735548.721 [DAT ThreadHandler](INFO): Protected caller Thread ID is 926
2022-10-25T22:05:48.721Z,1666735548.721 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-10-25T22:05:48.723Z,1666735548.723 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-10-25T22:05:48.887Z,1666735548.887 [SBIT](DEBUG): Construct Startup Built In Test.
2022-10-25T22:05:48.895Z,1666735548.895 [SBIT] Loaded
2022-10-25T22:05:48.896Z,1666735548.896 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-10-25T22:05:48.899Z,1666735548.899 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-10-25T22:05:48.911Z,1666735548.911 [IBIT] Loaded
2022-10-25T22:05:48.911Z,1666735548.911 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-10-25T22:05:48.918Z,1666735548.918 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-10-25T22:05:49.020Z,1666735549.020 [CBIT] Loaded
2022-10-25T22:05:49.020Z,1666735549.020 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-10-25T22:05:49.021Z,1666735549.021 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-10-25T22:05:49.022Z,1666735549.022 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-10-25T22:05:49.063Z,1666735549.063 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-10-25T22:05:49.069Z,1666735549.069 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-10-25T22:05:49.072Z,1666735549.072 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-10-25T22:05:49.083Z,1666735549.083 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-10-25T22:05:49.084Z,1666735549.084 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B9E4E0
2022-10-25T22:05:49.084Z,1666735549.084 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927
2022-10-25T22:05:49.089Z,1666735549.089 [Supervisor](INFO): Main Thread ID is 829
2022-10-25T22:05:49.089Z,1666735549.089 [Supervisor](DEBUG): Running supervisor.
2022-10-25T22:05:49.090Z,1666735549.090 [CommandExec ThreadHandler](INFO): Handler Thread ID is 928
2022-10-25T22:05:49.090Z,1666735549.090 [CommandExec](INFO): Initializing the command executive.
2022-10-25T22:05:49.092Z,1666735549.092 [CommandLine ThreadHandler](INFO): Handler Thread ID is 929
2022-10-25T22:05:49.094Z,1666735549.094 [controlThread ThreadHandler](INFO): Handler Thread ID is 930
2022-10-25T22:05:49.094Z,1666735549.094 [controlThread](DEBUG): Initializing ControlThread
2022-10-25T22:05:49.096Z,1666735549.096 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-10-25T22:05:49.096Z,1666735549.096 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-10-25T22:05:49.096Z,1666735549.096 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-10-25T22:05:49.101Z,1666735549.101 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-10-25T22:05:49.103Z,1666735549.103 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-10-25T22:05:49.104Z,1666735549.104 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-10-25T22:05:49.104Z,1666735549.104 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-10-25T22:05:49.105Z,1666735549.105 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-10-25T22:05:49.105Z,1666735549.105 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-10-25T22:05:49.105Z,1666735549.105 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-10-25T22:05:49.106Z,1666735549.106 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-10-25T22:05:49.106Z,1666735549.106 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-10-25T22:05:49.111Z,1666735549.111 [SBIT](INFO): Initialize SBIT Component.
2022-10-25T22:05:49.111Z,1666735549.111 [SBIT](IMPORTANT): git: 2022-10-11-14-gd6c25bdb6
2022-10-25T22:05:49.112Z,1666735549.112 [SBIT](INFO): git hash: d6c25bdb66481c96ce5e90d36d428e47d32f86f7
2022-10-25T22:05:49.112Z,1666735549.112 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-10-25T22:05:49.113Z,1666735549.113 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-10-25T22:05:49.114Z,1666735549.114 [SBIT](INFO): Beginning SBIT in 109.000000 seconds.
2022-10-25T22:05:49.115Z,1666735549.115 [IBIT](INFO): Initialize IBIT Component.
2022-10-25T22:05:49.116Z,1666735549.116 [CBIT](DEBUG): Initialize CBIT Component.
2022-10-25T22:05:49.117Z,1666735549.117 [logger ThreadHandler](INFO): Handler Thread ID is 931
2022-10-25T22:05:49.129Z,1666735549.129 [CBIT](DEBUG): Initialized mux pins.
2022-10-25T22:05:49.129Z,1666735549.129 [CBIT](DEBUG): Initializing the watchdog timer.
2022-10-25T22:05:49.133Z,1666735549.133 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 932
2022-10-25T22:05:49.138Z,1666735549.138 [WetLabsBB2FL](INFO): Powering up
2022-10-25T22:05:49.139Z,1666735549.139 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2022-10-25T22:05:49.149Z,1666735549.149 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2022-10-25T22:05:49.150Z,1666735549.150 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-10-25T22:05:49.153Z,1666735549.153 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-10-25T22:05:49.154Z,1666735549.154 [CBIT](DEBUG): Initializing heartbeat.
2022-10-25T22:05:49.161Z,1666735549.161 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2022-10-25T22:05:49.179Z,1666735549.179 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2022-10-25T22:05:49.201Z,1666735549.201 [DAT ThreadHandler](INFO): Handler Thread ID is 938
2022-10-25T22:05:49.202Z,1666735549.202 [DAT](INFO): Powering up
2022-10-25T22:05:49.202Z,1666735549.202 [DAT](DEBUG): Initializing DAT.
2022-10-25T22:05:49.206Z,1666735549.206 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940
2022-10-25T22:05:49.214Z,1666735549.214 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-10-25T22:05:49.214Z,1666735549.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-10-25T22:05:49.215Z,1666735549.215 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-10-25T22:05:49.215Z,1666735549.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-10-25T22:05:49.215Z,1666735549.215 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-10-25T22:05:49.215Z,1666735549.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-10-25T22:05:49.215Z,1666735549.215 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-10-25T22:05:49.215Z,1666735549.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-10-25T22:05:49.225Z,1666735549.225 [CBIT](DEBUG): Deactivating GF circuits.
2022-10-25T22:05:49.226Z,1666735549.226 [CBIT](DEBUG): Deactivating emergency mode.
2022-10-25T22:05:49.261Z,1666735549.261 [CBIT](DEBUG): Backplane powered.
2022-10-25T22:05:49.262Z,1666735549.262 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-10-25T22:05:49.292Z,1666735549.292 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-10-25T22:05:49.317Z,1666735549.317 [MissionManager](DEBUG):
2022-10-25T22:05:49.342Z,1666735549.342 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-10-25T22:05:49.412Z,1666735549.412 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-10-25T22:05:49.429Z,1666735549.429 [Default:A.Wait](DEBUG): Construct Wait.
2022-10-25T22:05:49.431Z,1666735549.431 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-10-25T22:05:49.471Z,1666735549.471 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-10-25T22:05:49.489Z,1666735549.489 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-10-25T22:05:49.495Z,1666735549.495 [Default:E.Execute](DEBUG): Construct Execute.
2022-10-25T22:05:49.499Z,1666735549.499 [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
2022-10-25T22:05:49.517Z,1666735549.517 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-10-25T22:05:49.525Z,1666735549.525 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-10-25T22:05:49.585Z,1666735549.585 [Radio_Surface](INFO): Powering up
2022-10-25T22:05:49.718Z,1666735549.718 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2022-10-25T22:05:49.719Z,1666735549.719 [Power24vConverter](INFO): Powering up.
2022-10-25T22:05:49.757Z,1666735549.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-10-25T22:05:49.795Z,1666735549.795 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-10-25T22:05:49.801Z,1666735549.801 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-10-25T22:05:49.802Z,1666735549.802 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-10-25T22:05:49.809Z,1666735549.809 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-10-25T22:05:49.810Z,1666735549.810 [MassServo](DEBUG): Initializing EZServoServo.
2022-10-25T22:05:49.818Z,1666735549.818 [MassServo](DEBUG): Initializing MassServo.
2022-10-25T22:05:49.819Z,1666735549.819 [RudderServo](DEBUG): Initializing EZServoServo.
2022-10-25T22:05:49.825Z,1666735549.825 [RudderServo](DEBUG): Initializing RudderServo.
2022-10-25T22:05:49.826Z,1666735549.826 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-10-25T22:05:49.833Z,1666735549.833 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-10-25T22:05:49.961Z,1666735549.961 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2022-10-25T22:05:49.961Z,1666735549.961 [DropWeight] Hardware Fault, FailCount= 1
2022-10-25T22:05:49.961Z,1666735549.961 [DropWeight](ERROR): Hardware Fault
2022-10-25T22:05:50.015Z,1666735550.015 [CommandExec](FAULT): Scheduling is paused
2022-10-25T22:05:50.016Z,1666735550.016 [CBIT](INFO): Critical error at 20221025T220549
2022-10-25T22:05:50.016Z,1666735550.016 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-10-25T22:05:50.027Z,1666735550.027 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-10-25T22:05:50.027Z,1666735550.027 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-10-25T22:05:50.665Z,1666735550.665 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-10-25T22:05:50.665Z,1666735550.665 [RudderServo](FAULT): Rudder failed to initialize
2022-10-25T22:05:50.665Z,1666735550.665 [RudderServo] Communications Fault, FailCount= 1
2022-10-25T22:05:50.665Z,1666735550.665 [RudderServo](ERROR): Communications Fault
2022-10-25T22:05:50.667Z,1666735550.667 [CBIT](INFO): Critical error at 20221025T220550
2022-10-25T22:05:50.670Z,1666735550.670 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-10-25T22:05:50.903Z,1666735550.903 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-10-25T22:05:50.903Z,1666735550.903 [RudderServo](INFO): Powering down
2022-10-25T22:05:51.566Z,1666735551.566 [RudderServo](DEBUG): Initializing EZServoServo.
2022-10-25T22:05:51.686Z,1666735551.686 [RudderServo](DEBUG): Initializing RudderServo.
2022-10-25T22:05:51.690Z,1666735551.690 [CBIT](INFO): Clearing failed state for component RudderServo
2022-10-25T22:05:51.690Z,1666735551.690 [RudderServo] No Fault, FailCount= 1
2022-10-25T22:05:51.749Z,1666735551.749 [WetLabsBB2FL](INFO): Powering down
2022-10-25T22:05:55.490Z,1666735555.490 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-10-25T22:06:04.575Z,1666735564.575 [DAT](INFO): commRate: 800
2022-10-25T22:06:06.641Z,1666735566.641 [DAT](INFO): entering command mode
2022-10-25T22:06:06.842Z,1666735566.842 [DAT](INFO): setting verbose to 3
2022-10-25T22:06:07.095Z,1666735567.095 [DAT](INFO): set verbose to 3
2022-10-25T22:06:07.095Z,1666735567.095 [DAT](INFO): setting DatVerbose to 27440
2022-10-25T22:06:07.347Z,1666735567.347 [DAT](INFO): set DatVerbose to 27440
2022-10-25T22:06:07.347Z,1666735567.347 [DAT](INFO): setting transmit power to 8
2022-10-25T22:06:07.598Z,1666735567.598 [DAT](INFO): set transmit power to 8
2022-10-25T22:06:07.599Z,1666735567.599 [DAT](INFO): setting local address to 10
2022-10-25T22:06:07.851Z,1666735567.851 [DAT](INFO): set local address to 10
2022-10-25T22:06:07.852Z,1666735567.852 [DAT](INFO): Setting time to: 22:6:7 And date to:10/25/2022
2022-10-25T22:06:08.103Z,1666735568.103 [DAT](INFO): Local DAT time set to Tue Oct 25, 2022 22:06:07
2022-10-25T22:06:18.623Z,1666735578.623 [NAL9602](INFO): Powering up NAL9602
2022-10-25T22:06:29.535Z,1666735589.535 [NAL9602](INFO): NAL9602 initialized
2022-10-25T22:07:32.981Z,1666735652.981 [CBIT](FAULT): WATER ALARM BOW.
2022-10-25T22:07:32.982Z,1666735652.982 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2022-10-25T22:07:33.389Z,1666735653.389 [CBIT](FAULT): WATER ALARM BOW.
2022-10-25T22:07:33.390Z,1666735653.390 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2022-10-25T22:07:34.588Z,1666735654.588 [CBIT](FAULT): WATER ALARM BOW.
2022-10-25T22:07:34.590Z,1666735654.590 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2022-10-25T22:07:35.004Z,1666735655.004 [CBIT](FAULT): WATER ALARM BOW.
2022-10-25T22:07:35.005Z,1666735655.005 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2022-10-25T22:07:35.426Z,1666735655.426 [CBIT](FAULT): WATER ALARM BOW.
2022-10-25T22:07:35.427Z,1666735655.427 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2022-10-25T22:07:35.834Z,1666735655.834 [CBIT](FAULT): WATER ALARM BOW.
2022-10-25T22:07:35.849Z,1666735655.849 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2022-10-25T22:07:35.849Z,1666735655.849 [CBIT](IMPORTANT): No ESP to power off.
2022-10-25T22:07:36.245Z,1666735656.245 [CBIT](INFO): Critical error at 20221025T220735
2022-10-25T22:07:38.648Z,1666735658.648 [SBIT](IMPORTANT): Beginning Startup BIT
2022-10-25T22:07:38.653Z,1666735658.653 [CBIT](IMPORTANT): Beginning ground fault scan
2022-10-25T22:07:49.582Z,1666735669.582 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.013504
CHAN A1 (24V): 0.000980
CHAN A2 (12V): -0.004987
CHAN A3 (5V): -0.002439
CHAN B0 (3.3V): -0.001401
CHAN B1 (3.15aV): -0.001197
CHAN B2 (3.15bV): -0.000996
CHAN B3 (GND): -0.000725
OPEN: 0.004959
Full Scale: +/- 1 mA
2022-10-25T22:08:12.103Z,1666735692.103 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004587
2022-10-25T22:08:23.534Z,1666735703.534 [CommandExec](IMPORTANT): got command burn off
2022-10-25T22:08:23.534Z,1666735703.534 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-10-25T22:08:28.009Z,1666735708.009 [CommandExec](IMPORTANT): got command failComponent
2022-10-25T22:08:28.010Z,1666735708.010 [CommandExec](IMPORTANT): Failed components:
2022-10-25T22:08:28.010Z,1666735708.010 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-10-25T22:08:32.911Z,1666735712.911 [SBIT](IMPORTANT): SBIT PASSED
2022-10-25T22:08:32.911Z,1666735712.911 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-10-25T22:08:32.912Z,1666735712.912 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool;
2022-10-25T22:08:32.912Z,1666735712.912 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=6 none;
2022-10-25T22:08:32.912Z,1666735712.912 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere;
2022-10-25T22:08:32.913Z,1666735712.913 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;
2022-10-25T22:08:32.913Z,1666735712.913 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;
2022-10-25T22:08:32.913Z,1666735712.913 [SBIT](IMPORTANT): DAT.surfaceThreshold=-1 meter;
2022-10-25T22:08:32.914Z,1666735712.914 [SBIT](IMPORTANT): Express none _.ayeris_disk_space;
2022-10-25T22:08:32.914Z,1666735712.914 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 250.000000 count;
2022-10-25T22:08:32.914Z,1666735712.914 [SBIT](IMPORTANT): Express none _.ayeris_particle_size;
2022-10-25T22:08:32.914Z,1666735712.914 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour;
2022-10-25T22:08:32.914Z,1666735712.914 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt;
2022-10-25T22:08:32.914Z,1666735712.914 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=210.367112 cubic_centimeter;
2022-10-25T22:08:32.914Z,1666735712.914 [SBIT](IMPORTANT): VerticalControl.massDefault=22.333859 millimeter;
2022-10-25T22:08:33.319Z,1666735713.319 [MissionManager](IMPORTANT): Started mission Startup
2022-10-25T22:08:33.320Z,1666735713.320 [Startup] Running Loop=1
2022-10-25T22:08:33.320Z,1666735713.320 [Startup](DEBUG): Aggregate::initialize Startup
2022-10-25T22:08:33.320Z,1666735713.320 [Startup:A.GoToSurface] Running Loop=1
2022-10-25T22:08:33.320Z,1666735713.320 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-10-25T22:08:33.322Z,1666735713.322 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-10-25T22:08:33.322Z,1666735713.322 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-10-25T22:08:33.322Z,1666735713.322 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-10-25T22:08:33.323Z,1666735713.323 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-10-25T22:08:33.323Z,1666735713.323 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-10-25T22:08:33.324Z,1666735713.324 [Startup:StartupSatComms] Running Loop=1
2022-10-25T22:08:33.325Z,1666735713.325 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-10-25T22:08:33.325Z,1666735713.325 [Startup:StartupSatComms:A] Running Loop=1
2022-10-25T22:08:33.734Z,1666735713.734 [CommandExec](IMPORTANT): got command gfscan
2022-10-25T22:08:33.789Z,1666735713.789 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-10-25T22:08:33.798Z,1666735713.798 [CBIT](IMPORTANT): Beginning ground fault scan
2022-10-25T22:08:44.668Z,1666735724.668 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.014469
CHAN A1 (24V): 0.001010
CHAN A2 (12V): -0.005331
CHAN A3 (5V): -0.002795
CHAN B0 (3.3V): -0.001213
CHAN B1 (3.15aV): -0.000670
CHAN B2 (3.15bV): -0.000819
CHAN B3 (GND): -0.000250
OPEN: 0.005170
Full Scale: +/- 1 mA
2022-10-25T22:08:49.462Z,1666735729.462 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-10-25T22:08:49.462Z,1666735729.462 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-25T22:08:49.463Z,1666735729.463 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2022-10-25T22:08:49.463Z,1666735729.463 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-10-25T22:08:49.512Z,1666735729.512 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-25T22:08:49.512Z,1666735729.512 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-10-25T22:08:49.897Z,1666735729.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-25T22:08:49.897Z,1666735729.897 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-10-25T22:08:49.897Z,1666735729.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-10-25T22:08:49.897Z,1666735729.897 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2022-10-25T22:08:50.275Z,1666735730.275 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-10-25T22:09:33.505Z,1666735773.505 [Startup:StartupSatComms:A](INFO): Timed out from 2022-10-25T22:08:33.3Z
2022-10-25T22:09:33.505Z,1666735773.505 [Startup:StartupSatComms:A] Stopped
2022-10-25T22:09:33.506Z,1666735773.506 [Startup:StartupSatComms:B] Running Loop=1
2022-10-25T22:09:33.919Z,1666735773.919 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-10-25T22:09:35.390Z,1666735775.390 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:09:35.391Z,1666735775.391 [DAT](INFO): setting remote address to 1
2022-10-25T22:09:35.643Z,1666735775.643 [DAT](INFO): set remote address to 1
2022-10-25T22:09:35.643Z,1666735775.643 [DAT](INFO): entering online mode
2022-10-25T22:09:35.895Z,1666735775.895 [DAT](INFO): commRate: 800
2022-10-25T22:09:35.895Z,1666735775.895 [DAT](INFO): online mode acknowledged
2022-10-25T22:09:35.896Z,1666735775.896 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:09:39.171Z,1666735779.171 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:09:41.605Z,1666735781.605 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20221025T212718/Courier0013.lzma
2022-10-25T22:09:42.607Z,1666735782.607 [DataOverHttps](INFO): Moved sent file to Logs/20221025T212718/Courier0013.lzma.bak
2022-10-25T22:09:42.607Z,1666735782.607 [DataOverHttps](INFO): SBD MOMSN=17262659
2022-10-25T22:09:54.298Z,1666735794.298 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:09:55.904Z,1666735795.904 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:09:55.910Z,1666735795.910 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:09:58.041Z,1666735798.041 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20221025T220537/Courier0000.lzma
2022-10-25T22:09:59.043Z,1666735799.043 [DataOverHttps](INFO): Moved sent file to Logs/20221025T220537/Courier0000.lzma.bak
2022-10-25T22:09:59.043Z,1666735799.043 [DataOverHttps](INFO): SBD MOMSN=17262664
2022-10-25T22:09:59.194Z,1666735799.194 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:10:15.810Z,1666735815.810 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20221025T212718/Express0011.lzma
2022-10-25T22:10:16.811Z,1666735816.811 [DataOverHttps](INFO): Moved sent file to Logs/20221025T212718/Express0011.lzma.bak
2022-10-25T22:10:16.811Z,1666735816.811 [DataOverHttps](INFO): SBD MOMSN=17262670
2022-10-25T22:10:18.362Z,1666735818.362 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:10:19.854Z,1666735819.854 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:10:19.856Z,1666735819.856 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:10:19.980Z,1666735819.980 [Startup:StartupSatComms:B] Stopped
2022-10-25T22:10:19.980Z,1666735819.980 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-10-25T22:10:19.980Z,1666735819.980 [Startup:StartupSatComms] Stopped
2022-10-25T22:10:19.980Z,1666735819.980 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-10-25T22:10:19.981Z,1666735819.981 [Startup](INFO): Completed Startup
2022-10-25T22:10:19.981Z,1666735819.981 [MissionManager](INFO): Startup is completed.
2022-10-25T22:10:19.981Z,1666735819.981 [MissionManager](INFO): Uninitializing Mission Startup
2022-10-25T22:10:19.981Z,1666735819.981 [Startup] Stopped
2022-10-25T22:10:19.981Z,1666735819.981 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-10-25T22:10:19.982Z,1666735819.982 [Startup:A.GoToSurface] Stopped
2022-10-25T22:10:19.982Z,1666735819.982 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-10-25T22:10:20.368Z,1666735820.368 [MissionManager](IMPORTANT): Started mission Default
2022-10-25T22:10:20.368Z,1666735820.368 [Default] Running Loop=1
2022-10-25T22:10:20.368Z,1666735820.368 [Default](DEBUG): Aggregate::initialize Default
2022-10-25T22:10:20.369Z,1666735820.369 [Default:B.GoToSurface] Running Loop=1
2022-10-25T22:10:20.369Z,1666735820.369 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-10-25T22:10:20.369Z,1666735820.369 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-10-25T22:10:20.369Z,1666735820.369 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-10-25T22:10:20.370Z,1666735820.370 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-10-25T22:10:20.370Z,1666735820.370 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-10-25T22:10:20.370Z,1666735820.370 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-10-25T22:10:20.371Z,1666735820.371 [Default:A.Wait] Running Loop=1
2022-10-25T22:10:20.371Z,1666735820.371 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-10-25T22:10:23.134Z,1666735823.134 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:10:33.711Z,1666735833.711 [Default:A.Wait](INFO): Done Waiting.
2022-10-25T22:10:33.711Z,1666735833.711 [Default:A.Wait] Stopped
2022-10-25T22:10:33.711Z,1666735833.711 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-10-25T22:10:34.115Z,1666735834.115 [Default:CheckIn] Running Loop=1
2022-10-25T22:10:34.115Z,1666735834.115 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-25T22:10:34.115Z,1666735834.115 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-25T22:10:34.667Z,1666735834.667 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-10-25T22:10:35.838Z,1666735835.838 [DataOverHttps](INFO): Sending 325 bytes from file Logs/20221025T212718/Express0014.lzma
2022-10-25T22:10:36.839Z,1666735836.839 [DataOverHttps](INFO): Moved sent file to Logs/20221025T212718/Express0014.lzma.bak
2022-10-25T22:10:36.839Z,1666735836.839 [DataOverHttps](INFO): SBD MOMSN=17262682
2022-10-25T22:10:42.301Z,1666735842.301 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:10:42.554Z,1666735842.554 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:10:42.806Z,1666735842.806 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:10:46.082Z,1666735846.082 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:11:05.242Z,1666735865.242 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2022-10-25T22:11:05.242Z,1666735865.242 [DAT](ERROR): Ack receipt timeout failure.
2022-10-25T22:11:05.494Z,1666735865.494 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:11:05.746Z,1666735865.746 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:11:06.003Z,1666735866.003 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:11:09.278Z,1666735869.278 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:11:28.433Z,1666735888.433 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:11:28.686Z,1666735888.686 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:11:28.938Z,1666735888.938 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:11:32.214Z,1666735892.214 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:11:33.076Z,1666735893.076 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-25T22:11:50.455Z,1666735910.455 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-10-25T22:11:50.455Z,1666735910.455 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-25T22:11:50.456Z,1666735910.456 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2022-10-25T22:11:50.456Z,1666735910.456 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-10-25T22:11:50.474Z,1666735910.474 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-25T22:11:50.475Z,1666735910.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-10-25T22:11:50.897Z,1666735910.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-25T22:11:50.898Z,1666735910.898 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-10-25T22:11:50.898Z,1666735910.898 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-10-25T22:11:50.898Z,1666735910.898 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2022-10-25T22:11:51.264Z,1666735911.264 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-10-25T22:11:51.378Z,1666735911.378 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:11:51.630Z,1666735911.630 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:11:51.882Z,1666735911.882 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:11:55.159Z,1666735915.159 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:12:14.314Z,1666735934.314 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:12:14.566Z,1666735934.566 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:12:14.818Z,1666735934.818 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:12:18.094Z,1666735938.094 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:12:37.258Z,1666735957.258 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2022-10-25T22:12:37.258Z,1666735957.258 [DAT](ERROR): Ack receipt timeout failure.
2022-10-25T22:12:37.510Z,1666735957.510 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:13:15.707Z,1666735995.707 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-10-25T22:13:15.711Z,1666735995.711 [BPC1](INFO): Received data from all battery sticks.
2022-10-25T22:13:50.744Z,1666736030.744 [CommandExec](IMPORTANT): got command get platform_orientation
2022-10-25T22:13:50.744Z,1666736030.744 [CommandExec](IMPORTANT): platform_orientation 5.036471 rad
2022-10-25T22:14:51.466Z,1666736091.466 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-10-25T22:14:51.466Z,1666736091.466 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-25T22:14:51.467Z,1666736091.467 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2022-10-25T22:14:51.467Z,1666736091.467 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-10-25T22:14:51.478Z,1666736091.478 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-25T22:14:51.478Z,1666736091.478 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-10-25T22:14:51.890Z,1666736091.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-25T22:14:51.890Z,1666736091.890 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-10-25T22:14:51.890Z,1666736091.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-10-25T22:14:51.890Z,1666736091.890 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2022-10-25T22:14:52.264Z,1666736092.264 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-10-25T22:15:34.280Z,1666736134.280 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-10-25T22:10:34.1Z
2022-10-25T22:15:34.280Z,1666736134.280 [Default:CheckIn:Read_GPS] Stopped
2022-10-25T22:15:34.280Z,1666736134.280 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-25T22:15:34.686Z,1666736134.686 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-10-25T22:15:35.694Z,1666736135.694 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:15:35.696Z,1666736135.696 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:15:38.975Z,1666736138.975 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:15:41.784Z,1666736141.784 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20221025T220537/Courier0004.lzma
2022-10-25T22:15:42.787Z,1666736142.787 [DataOverHttps](INFO): Moved sent file to Logs/20221025T220537/Courier0004.lzma.bak
2022-10-25T22:15:42.787Z,1666736142.787 [DataOverHttps](INFO): SBD MOMSN=17262756
2022-10-25T22:15:54.094Z,1666736154.094 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:15:55.007Z,1666736155.007 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:15:55.009Z,1666736155.009 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:15:55.347Z,1666736155.347 [Default:CheckIn:Read_Iridium] Stopped
2022-10-25T22:15:55.347Z,1666736155.347 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-25T22:15:55.347Z,1666736155.347 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-25T22:15:58.286Z,1666736158.286 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:15:59.567Z,1666736159.567 [DataOverHttps](INFO): Sending 1226 bytes from file Logs/20221025T220537/Express0001.lzma
2022-10-25T22:16:00.579Z,1666736160.579 [DataOverHttps](INFO): Moved sent file to Logs/20221025T220537/Express0001.lzma.bak
2022-10-25T22:16:00.579Z,1666736160.579 [DataOverHttps](INFO): SBD MOMSN=17262758
2022-10-25T22:16:13.405Z,1666736173.405 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:16:13.658Z,1666736173.658 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:16:13.910Z,1666736173.910 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:16:17.194Z,1666736177.194 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:16:31.239Z,1666736191.239 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-10-25T22:16:31.239Z,1666736191.239 [NAL9602] Data Fault, FailCount= 1
2022-10-25T22:16:31.239Z,1666736191.239 [NAL9602](ERROR): Data Fault
2022-10-25T22:16:31.256Z,1666736191.256 [CBIT](ERROR): Data Fault in component: NAL9602
2022-10-25T22:16:31.646Z,1666736191.646 [NAL9602](INFO): Powering down
2022-10-25T22:16:32.313Z,1666736192.313 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:16:32.483Z,1666736192.483 [CBIT](INFO): Clearing failed state for component NAL9602
2022-10-25T22:16:32.483Z,1666736192.483 [NAL9602] No Fault, FailCount= 1
2022-10-25T22:16:32.566Z,1666736192.566 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-10-25T22:16:32.818Z,1666736192.818 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-10-25T22:16:36.094Z,1666736196.094 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-10-25T22:16:51.222Z,1666736211.222 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2022-10-25T22:16:51.222Z,1666736211.222 [DAT](ERROR): Ack receipt timeout failure.
2022-10-25T22:16:51.474Z,1666736211.474 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER
2022-10-25T22:17:01.947Z,1666736221.947 [NAL9602](INFO): Powering up NAL9602
2022-10-25T22:17:12.851Z,1666736232.851 [NAL9602](INFO): NAL9602 initialized
2022-10-25T22:17:28.209Z,1666736248.209 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2022-10-25T22:17:28.209Z,1666736248.209 [AHRS_M2] Data Fault, FailCount= 1
2022-10-25T22:17:28.209Z,1666736248.209 [AHRS_M2](ERROR): Data Fault
2022-10-25T22:17:28.277Z,1666736248.277 [CBIT](ERROR): Data Fault in component: AHRS_M2
2022-10-25T22:17:28.598Z,1666736248.598 [AHRS_M2](INFO): Powering down
2022-10-25T22:17:29.430Z,1666736249.430 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-10-25T22:17:29.430Z,1666736249.430 [AHRS_M2] No Fault, FailCount= 1
2022-10-25T22:17:29.814Z,1666736249.814 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-10-25T22:17:33.365Z,1666736253.365 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:33.781Z,1666736253.781 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:34.221Z,1666736254.221 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:34.649Z,1666736254.649 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:35.077Z,1666736255.077 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:35.493Z,1666736255.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:35.917Z,1666736255.917 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:36.329Z,1666736256.329 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:36.757Z,1666736256.757 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:37.197Z,1666736257.197 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:37.625Z,1666736257.625 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:38.045Z,1666736258.045 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:38.457Z,1666736258.457 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:38.873Z,1666736258.873 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:39.289Z,1666736259.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:39.721Z,1666736259.721 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:40.161Z,1666736260.161 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:40.593Z,1666736260.593 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:41.009Z,1666736261.009 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:41.421Z,1666736261.421 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:41.841Z,1666736261.841 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:42.261Z,1666736262.261 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:42.685Z,1666736262.685 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:42.685Z,1666736262.685 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-10-25T22:17:42.685Z,1666736262.685 [AHRS_M2] Communications Fault, FailCount= 2
2022-10-25T22:17:42.685Z,1666736262.685 [AHRS_M2](ERROR): Communications Fault
2022-10-25T22:17:42.759Z,1666736262.759 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-10-25T22:17:42.792Z,1666736262.792 [AHRS_M2](INFO): Powering down
2022-10-25T22:17:43.600Z,1666736263.600 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-10-25T22:17:43.600Z,1666736263.600 [AHRS_M2] No Fault, FailCount= 2
2022-10-25T22:17:43.986Z,1666736263.986 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-10-25T22:17:47.541Z,1666736267.541 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:47.969Z,1666736267.969 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:48.401Z,1666736268.401 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:48.829Z,1666736268.829 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:49.249Z,1666736269.249 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:49.661Z,1666736269.661 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:50.073Z,1666736270.073 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:50.501Z,1666736270.501 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:50.949Z,1666736270.949 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:51.373Z,1666736271.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:51.785Z,1666736271.785 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:52.201Z,1666736272.201 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:52.621Z,1666736272.621 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:52.633Z,1666736272.633 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-10-25T22:17:52.633Z,1666736272.633 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-25T22:17:52.638Z,1666736272.638 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2022-10-25T22:17:52.638Z,1666736272.638 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-10-25T22:17:52.693Z,1666736272.693 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-25T22:17:52.694Z,1666736272.694 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-10-25T22:17:53.057Z,1666736273.057 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:53.111Z,1666736273.111 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-25T22:17:53.111Z,1666736273.111 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-10-25T22:17:53.111Z,1666736273.111 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-10-25T22:17:53.111Z,1666736273.111 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2022-10-25T22:17:53.473Z,1666736273.473 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:53.512Z,1666736273.512 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-10-25T22:17:53.917Z,1666736273.917 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:54.365Z,1666736274.365 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:54.813Z,1666736274.813 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:55.217Z,1666736275.217 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:55.633Z,1666736275.633 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:56.049Z,1666736276.049 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:56.465Z,1666736276.465 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:56.893Z,1666736276.893 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:17:56.893Z,1666736276.893 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-10-25T22:17:56.893Z,1666736276.893 [AHRS_M2] Communications Fault, FailCount= 3
2022-10-25T22:17:56.893Z,1666736276.893 [AHRS_M2](ERROR): Communications Fault
2022-10-25T22:17:56.954Z,1666736276.954 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-10-25T22:17:57.014Z,1666736277.014 [AHRS_M2](INFO): Powering down
2022-10-25T22:17:57.791Z,1666736277.791 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-10-25T22:17:57.791Z,1666736277.791 [AHRS_M2] No Fault, FailCount= 3
2022-10-25T22:17:58.178Z,1666736278.178 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-10-25T22:18:01.733Z,1666736281.733 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:02.153Z,1666736282.153 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:02.565Z,1666736282.565 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:03.001Z,1666736283.001 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:03.433Z,1666736283.433 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:03.869Z,1666736283.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:04.281Z,1666736284.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:04.697Z,1666736284.697 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:05.113Z,1666736285.113 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:05.553Z,1666736285.553 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:05.981Z,1666736285.981 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:06.409Z,1666736286.409 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:06.825Z,1666736286.825 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:07.241Z,1666736287.241 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:07.657Z,1666736287.657 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:08.081Z,1666736288.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:08.497Z,1666736288.497 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:08.937Z,1666736288.937 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:09.369Z,1666736289.369 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:09.793Z,1666736289.793 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:10.213Z,1666736290.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:10.633Z,1666736290.633 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:11.045Z,1666736291.045 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:11.045Z,1666736291.045 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-10-25T22:18:11.045Z,1666736291.045 [AHRS_M2] Communications Fault, FailCount= 4
2022-10-25T22:18:11.045Z,1666736291.045 [AHRS_M2](ERROR): Communications Fault
2022-10-25T22:18:11.098Z,1666736291.098 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-10-25T22:18:11.134Z,1666736291.134 [AHRS_M2](INFO): Powering down
2022-10-25T22:18:11.963Z,1666736291.963 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-10-25T22:18:11.963Z,1666736291.963 [AHRS_M2] No Fault, FailCount= 4
2022-10-25T22:18:12.322Z,1666736292.322 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-10-25T22:18:15.877Z,1666736295.877 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:16.293Z,1666736296.293 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:16.694Z,1666736296.694 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:17.113Z,1666736297.113 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:17.537Z,1666736297.537 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:17.973Z,1666736297.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:18.417Z,1666736298.417 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:18.829Z,1666736298.829 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:19.241Z,1666736299.241 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:19.665Z,1666736299.665 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:20.081Z,1666736300.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:20.501Z,1666736300.501 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:20.937Z,1666736300.937 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:21.377Z,1666736301.377 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:21.809Z,1666736301.809 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:22.221Z,1666736302.221 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:22.633Z,1666736302.633 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:23.049Z,1666736303.049 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:23.473Z,1666736303.473 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:23.905Z,1666736303.905 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:24.345Z,1666736304.345 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:24.781Z,1666736304.781 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:25.193Z,1666736305.193 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:18:25.193Z,1666736305.193 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-10-25T22:18:25.193Z,1666736305.193 [AHRS_M2] Communications Fault, FailCount= 5
2022-10-25T22:18:25.193Z,1666736305.193 [AHRS_M2](ERROR): Communications Fault
2022-10-25T22:18:25.241Z,1666736305.241 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-10-25T22:18:25.242Z,1666736305.242 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2022-10-25T22:18:25.282Z,1666736305.282 [AHRS_M2](INFO): Powering down
2022-10-25T22:18:25.399Z,1666736305.399 [CBIT](INFO): Critical error at 20221025T221825
2022-10-25T22:19:53.736Z,1666736393.736 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-10-25T22:19:53.736Z,1666736393.736 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-10-25T22:19:53.736Z,1666736393.736 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-25T22:19:53.738Z,1666736393.738 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-10-25T22:19:53.738Z,1666736393.738 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2022-10-25T22:19:53.738Z,1666736393.738 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-10-25T22:19:53.749Z,1666736393.749 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-25T22:19:53.749Z,1666736393.749 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-10-25T22:19:54.157Z,1666736394.157 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-25T22:19:54.157Z,1666736394.157 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-10-25T22:19:54.158Z,1666736394.158 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-10-25T22:19:54.158Z,1666736394.158 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2022-10-25T22:19:54.548Z,1666736394.548 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-10-25T22:20:50.310Z,1666736450.310 [CBIT](INFO): Clearing failed state for component DropWeight
2022-10-25T22:20:50.310Z,1666736450.310 [DropWeight] No Fault, FailCount= 1
2022-10-25T22:20:55.957Z,1666736455.957 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-25T22:20:55.957Z,1666736455.957 [Default:CheckIn:C.Wait] Stopped
2022-10-25T22:20:55.957Z,1666736455.957 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-25T22:20:55.957Z,1666736455.957 [Default:CheckIn:D] Running Loop=1
2022-10-25T22:20:56.379Z,1666736456.379 [Default:CheckIn:D] Stopped
2022-10-25T22:20:56.379Z,1666736456.379 [Default:CheckIn:E] Running Loop=1
2022-10-25T22:20:56.782Z,1666736456.782 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.600170 min
2022-10-25T22:20:56.782Z,1666736456.782 [Default:CheckIn:E] Stopped
2022-10-25T22:20:56.782Z,1666736456.782 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-25T22:20:56.782Z,1666736456.782 [Default:CheckIn] Stopped
2022-10-25T22:20:56.782Z,1666736456.782 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-25T22:20:56.783Z,1666736456.783 [Default:CheckIn](INFO): Running loop #2
2022-10-25T22:20:56.783Z,1666736456.783 [Default:CheckIn] Running Loop=2
2022-10-25T22:20:56.783Z,1666736456.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-25T22:20:56.783Z,1666736456.783 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-25T22:21:54.933Z,1666736514.933 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-10-25T22:21:54.933Z,1666736514.933 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-10-25T22:21:54.933Z,1666736514.933 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-25T22:21:54.935Z,1666736514.935 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-10-25T22:21:54.935Z,1666736514.935 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2022-10-25T22:21:54.935Z,1666736514.935 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-10-25T22:21:54.946Z,1666736514.946 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-25T22:21:54.946Z,1666736514.946 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-10-25T22:21:55.382Z,1666736515.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-25T22:21:55.382Z,1666736515.382 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-10-25T22:21:55.383Z,1666736515.383 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-10-25T22:21:55.383Z,1666736515.383 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2022-10-25T22:21:55.765Z,1666736515.765 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-10-25T22:23:25.508Z,1666736605.508 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-10-25T22:23:25.508Z,1666736605.508 [AHRS_M2] No Fault, FailCount= 5
2022-10-25T22:23:25.866Z,1666736605.866 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-10-25T22:23:29.417Z,1666736609.417 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:29.845Z,1666736609.845 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:30.289Z,1666736610.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:30.729Z,1666736610.729 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:31.145Z,1666736611.145 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:31.561Z,1666736611.561 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:31.965Z,1666736611.965 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:32.385Z,1666736612.385 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:32.813Z,1666736612.813 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:33.241Z,1666736613.241 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:33.669Z,1666736613.669 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:34.081Z,1666736614.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:34.493Z,1666736614.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:34.905Z,1666736614.905 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:35.325Z,1666736615.325 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:35.757Z,1666736615.757 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:36.201Z,1666736616.201 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:36.641Z,1666736616.641 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:37.045Z,1666736617.045 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:37.473Z,1666736617.473 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:37.901Z,1666736617.901 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:38.302Z,1666736618.302 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:38.741Z,1666736618.741 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:38.741Z,1666736618.741 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-10-25T22:23:38.741Z,1666736618.741 [AHRS_M2] Communications Fault, FailCount= 1
2022-10-25T22:23:38.741Z,1666736618.741 [AHRS_M2](ERROR): Communications Fault
2022-10-25T22:23:38.805Z,1666736618.805 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-10-25T22:23:38.842Z,1666736618.842 [AHRS_M2](INFO): Powering down
2022-10-25T22:23:39.648Z,1666736619.648 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-10-25T22:23:39.648Z,1666736619.648 [AHRS_M2] No Fault, FailCount= 1
2022-10-25T22:23:40.034Z,1666736620.034 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-10-25T22:23:43.585Z,1666736623.585 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:44.001Z,1666736624.001 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:44.417Z,1666736624.417 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:44.854Z,1666736624.854 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:45.313Z,1666736625.313 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:45.737Z,1666736625.737 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:46.165Z,1666736626.165 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:46.577Z,1666736626.577 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:47.001Z,1666736627.001 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:47.413Z,1666736627.413 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:47.845Z,1666736627.845 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:48.293Z,1666736628.293 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:48.733Z,1666736628.733 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:49.145Z,1666736629.145 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:49.557Z,1666736629.557 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:49.969Z,1666736629.969 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:50.381Z,1666736630.381 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:50.817Z,1666736630.817 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:51.261Z,1666736631.261 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:51.689Z,1666736631.689 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:52.105Z,1666736632.105 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:52.521Z,1666736632.521 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:52.937Z,1666736632.937 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:52.937Z,1666736632.937 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-10-25T22:23:52.937Z,1666736632.937 [AHRS_M2] Communications Fault, FailCount= 2
2022-10-25T22:23:52.937Z,1666736632.937 [AHRS_M2](ERROR): Communications Fault
2022-10-25T22:23:52.963Z,1666736632.963 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-10-25T22:23:53.030Z,1666736633.030 [AHRS_M2](INFO): Powering down
2022-10-25T22:23:53.828Z,1666736633.828 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-10-25T22:23:53.828Z,1666736633.828 [AHRS_M2] No Fault, FailCount= 2
2022-10-25T22:23:54.186Z,1666736634.186 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-10-25T22:23:55.817Z,1666736635.817 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-10-25T22:23:55.817Z,1666736635.817 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-10-25T22:23:55.817Z,1666736635.817 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-10-25T22:23:55.819Z,1666736635.819 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-10-25T22:23:55.819Z,1666736635.819 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2022-10-25T22:23:55.819Z,1666736635.819 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-10-25T22:23:55.829Z,1666736635.829 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-10-25T22:23:55.830Z,1666736635.830 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-10-25T22:23:56.235Z,1666736636.235 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-10-25T22:23:56.235Z,1666736636.235 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-10-25T22:23:56.235Z,1666736636.235 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-10-25T22:23:56.235Z,1666736636.235 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2022-10-25T22:23:56.613Z,1666736636.613 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-10-25T22:23:57.741Z,1666736637.741 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:58.157Z,1666736638.157 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:58.581Z,1666736638.581 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:58.607Z,1666736638.607 [CommandExec](IMPORTANT): got command quit
2022-10-25T22:23:59.021Z,1666736639.021 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:59.457Z,1666736639.457 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:59.609Z,1666736639.609 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:23:59.609Z,1666736639.609 [CommandExec](INFO): Uninitializing the command executive.
2022-10-25T22:23:59.609Z,1666736639.609 [CommandExec](INFO): Uninitializing the command scheduler.
2022-10-25T22:23:59.610Z,1666736639.610 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:23:59.610Z,1666736639.610 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2022-10-25T22:23:59.610Z,1666736639.610 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-10-25T22:23:59.610Z,1666736639.610 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:23:59.611Z,1666736639.611 [NavChartDb](INFO): Join timeout helper Thread ID is 983
2022-10-25T22:23:59.901Z,1666736639.901 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:23:59.941Z,1666736639.941 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:23:59.942Z,1666736639.942 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:23:59.949Z,1666736639.949 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-10-25T22:23:59.949Z,1666736639.949 [DAT ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:23:59.949Z,1666736639.949 [DAT](INFO): Join timeout helper Thread ID is 984
2022-10-25T22:23:59.974Z,1666736639.974 [DAT](INFO): Powering down
2022-10-25T22:24:00.046Z,1666736640.046 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:24:00.046Z,1666736640.046 [DAT](INFO): Powering down
2022-10-25T22:24:00.047Z,1666736640.047 [DAT ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:00.057Z,1666736640.057 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-10-25T22:24:00.057Z,1666736640.057 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:00.058Z,1666736640.058 [Radio_Surface](INFO): Join timeout helper Thread ID is 985
2022-10-25T22:24:00.317Z,1666736640.317 [Radio_Surface](INFO): Powering down
2022-10-25T22:24:00.318Z,1666736640.318 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:24:00.318Z,1666736640.318 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:00.321Z,1666736640.321 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-10-25T22:24:00.321Z,1666736640.321 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:00.322Z,1666736640.322 [Onboard](INFO): Join timeout helper Thread ID is 986
2022-10-25T22:24:00.333Z,1666736640.333 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:00.753Z,1666736640.753 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:00.977Z,1666736640.977 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-10-25T22:24:01.177Z,1666736641.177 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:01.605Z,1666736641.605 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:02.021Z,1666736642.021 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:02.457Z,1666736642.457 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:02.869Z,1666736642.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:03.273Z,1666736643.273 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:03.693Z,1666736643.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:04.113Z,1666736644.113 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:04.533Z,1666736644.533 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:04.977Z,1666736644.977 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:05.213Z,1666736645.213 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:24:05.213Z,1666736645.213 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:05.225Z,1666736645.225 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-10-25T22:24:05.225Z,1666736645.225 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:05.225Z,1666736645.225 [DataOverHttps](INFO): Join timeout helper Thread ID is 987
2022-10-25T22:24:05.397Z,1666736645.397 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:05.801Z,1666736645.801 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:05.997Z,1666736645.997 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:24:05.998Z,1666736645.998 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.018Z,1666736646.018 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-10-25T22:24:06.018Z,1666736646.018 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.018Z,1666736646.018 [BackseatComponent](INFO): Join timeout helper Thread ID is 988
2022-10-25T22:24:06.161Z,1666736646.161 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:24:06.161Z,1666736646.161 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.178Z,1666736646.178 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-10-25T22:24:06.178Z,1666736646.178 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.178Z,1666736646.178 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 989
2022-10-25T22:24:06.205Z,1666736646.205 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:06.385Z,1666736646.385 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:24:06.385Z,1666736646.385 [WetLabsBB2FL](INFO): Powering down
2022-10-25T22:24:06.386Z,1666736646.386 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.398Z,1666736646.398 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-10-25T22:24:06.398Z,1666736646.398 [logger ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.398Z,1666736646.398 [logger](INFO): Join timeout helper Thread ID is 990
2022-10-25T22:24:06.405Z,1666736646.405 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:24:06.405Z,1666736646.405 [logger ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.418Z,1666736646.418 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-10-25T22:24:06.418Z,1666736646.418 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.418Z,1666736646.418 [CommandLine](INFO): Join timeout helper Thread ID is 991
2022-10-25T22:24:06.437Z,1666736646.437 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:24:06.437Z,1666736646.437 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.458Z,1666736646.458 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-10-25T22:24:06.458Z,1666736646.458 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.458Z,1666736646.458 [CommandExec](INFO): Join timeout helper Thread ID is 992
2022-10-25T22:24:06.459Z,1666736646.459 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-10-25T22:24:06.459Z,1666736646.459 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.460Z,1666736646.460 [controlThread](INFO): Join timeout helper Thread ID is 993
2022-10-25T22:24:06.637Z,1666736646.637 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2022-10-25T22:24:06.657Z,1666736646.657 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-25T22:24:06.657Z,1666736646.657 [controlThread](DEBUG): Uninitializing ControlThread
2022-10-25T22:24:06.657Z,1666736646.657 [AHRS_M2](INFO): Powering down
2022-10-25T22:24:06.730Z,1666736646.730 [NAL9602](INFO): Powering down
2022-10-25T22:24:06.732Z,1666736646.732 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-10-25T22:24:06.732Z,1666736646.732 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-10-25T22:24:06.733Z,1666736646.733 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-10-25T22:24:06.734Z,1666736646.734 [MissionManager](INFO): Uninitializing Mission Default
2022-10-25T22:24:06.734Z,1666736646.734 [Default] Stopped
2022-10-25T22:24:06.734Z,1666736646.734 [Default](DEBUG): Aggregate::uninitialize Default
2022-10-25T22:24:06.734Z,1666736646.734 [Default:B.GoToSurface] Stopped
2022-10-25T22:24:06.734Z,1666736646.734 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-10-25T22:24:06.734Z,1666736646.734 [Default:CheckIn] Stopped
2022-10-25T22:24:06.734Z,1666736646.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-25T22:24:06.734Z,1666736646.734 [Default:CheckIn:Read_GPS] Stopped
2022-10-25T22:24:06.737Z,1666736646.737 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-10-25T22:24:06.737Z,1666736646.737 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-10-25T22:24:06.738Z,1666736646.738 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-10-25T22:24:06.738Z,1666736646.738 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-10-25T22:24:06.738Z,1666736646.738 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-10-25T22:24:06.738Z,1666736646.738 [BuoyancyServo](INFO): Powering down
2022-10-25T22:24:06.753Z,1666736646.753 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-10-25T22:24:06.753Z,1666736646.753 [ElevatorServo](INFO): Powering down
2022-10-25T22:24:06.754Z,1666736646.754 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-10-25T22:24:06.754Z,1666736646.754 [MassServo](INFO): Powering down
2022-10-25T22:24:06.755Z,1666736646.755 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-10-25T22:24:06.755Z,1666736646.755 [RudderServo](INFO): Powering down
2022-10-25T22:24:06.756Z,1666736646.756 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-10-25T22:24:06.756Z,1666736646.756 [ThrusterHE](INFO): Powering down
2022-10-25T22:24:06.757Z,1666736646.757 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-10-25T22:24:06.757Z,1666736646.757 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-10-25T22:24:06.758Z,1666736646.758 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-10-25T22:24:06.758Z,1666736646.758 [CBIT](DEBUG): Powering off loads.
2022-10-25T22:24:06.769Z,1666736646.769 [CBIT](DEBUG): Disabling WDT.
2022-10-25T22:24:06.781Z,1666736646.781 [CBIT](DEBUG): Opening all GF detection circuits.
2022-10-25T22:24:06.782Z,1666736646.782 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.819Z,1666736646.819 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.865Z,1666736646.865 [DAT ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.869Z,1666736646.869 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.872Z,1666736646.872 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.878Z,1666736646.878 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.921Z,1666736646.921 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:06.961Z,1666736646.961 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-10-25T22:24:07.020Z,1666736647.020 [logger ThreadHandler](INFO): Thread cancelled.