2022-05-12T20:22:51.318Z,1652386971.318 [Supervisor](DEBUG): Initializing supervisor.
2022-05-12T20:22:51.322Z,1652386971.322 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-05-12T20:22:51.323Z,1652386971.323 [SyncHandler](INFO): Protected caller Thread ID is 9370
2022-05-12T20:22:51.323Z,1652386971.323 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-05-12T20:22:51.324Z,1652386971.324 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-05-12T20:22:51.324Z,1652386971.324 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9371
2022-05-12T20:22:51.329Z,1652386971.329 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-05-12T20:22:51.347Z,1652386971.347 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-05-12T20:22:51.348Z,1652386971.348 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-05-12T20:22:51.349Z,1652386971.349 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9372
2022-05-12T20:22:51.353Z,1652386971.353 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-05-12T20:22:51.354Z,1652386971.354 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-05-12T20:22:51.354Z,1652386971.354 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9373
2022-05-12T20:22:51.356Z,1652386971.356 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-05-12T20:22:51.357Z,1652386971.357 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-05-12T20:22:51.358Z,1652386971.358 [logger ThreadHandler](INFO): Protected caller Thread ID is 9374
2022-05-12T20:22:51.361Z,1652386971.361 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-05-12T20:22:51.362Z,1652386971.362 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-05-12T20:22:51.363Z,1652386971.363 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-05-12T20:22:51.460Z,1652386971.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-05-12T20:22:51.461Z,1652386971.461 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-05-12T20:22:51.682Z,1652386971.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-05-12T20:22:51.682Z,1652386971.682 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-05-12T20:22:51.951Z,1652386971.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-05-12T20:22:51.951Z,1652386971.951 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-05-12T20:22:52.059Z,1652386972.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-05-12T20:22:52.060Z,1652386972.060 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-05-12T20:22:52.730Z,1652386972.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-05-12T20:22:52.730Z,1652386972.730 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-05-12T20:22:53.273Z,1652386973.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-05-12T20:22:53.274Z,1652386973.274 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-05-12T20:22:53.852Z,1652386973.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-05-12T20:22:53.852Z,1652386973.852 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-05-12T20:22:54.247Z,1652386974.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-05-12T20:22:54.248Z,1652386974.248 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-05-12T20:22:54.585Z,1652386974.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-05-12T20:22:54.586Z,1652386974.586 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-05-12T20:22:54.964Z,1652386974.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-05-12T20:22:54.964Z,1652386974.964 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-05-12T20:22:55.188Z,1652386975.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-05-12T20:22:55.189Z,1652386975.189 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-05-12T20:22:55.320Z,1652386975.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-05-12T20:22:55.320Z,1652386975.320 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-05-12T20:22:55.401Z,1652386975.401 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-05-12T20:22:55.481Z,1652386975.481 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-05-12T20:22:55.673Z,1652386975.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-05-12T20:22:55.673Z,1652386975.673 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-05-12T20:22:55.895Z,1652386975.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-05-12T20:22:55.897Z,1652386975.897 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2022-05-12T20:22:55.898Z,1652386975.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2022-05-12T20:22:55.981Z,1652386975.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2022-05-12T20:22:56.674Z,1652386976.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-05-12T20:22:56.675Z,1652386976.675 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2022-05-12T20:22:56.776Z,1652386976.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2022-05-12T20:22:56.945Z,1652386976.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2022-05-12T20:22:57.133Z,1652386977.133 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2022-05-12T20:22:57.218Z,1652386977.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2022-05-12T20:22:57.328Z,1652386977.328 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2022-05-12T20:22:57.434Z,1652386977.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2022-05-12T20:22:57.599Z,1652386977.599 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2022-05-12T20:22:57.703Z,1652386977.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2022-05-12T20:22:57.805Z,1652386977.805 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2022-05-12T20:22:57.805Z,1652386977.805 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-05-12T20:22:57.818Z,1652386977.818 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-05-12T20:22:57.926Z,1652386977.926 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-05-12T20:22:57.927Z,1652386977.927 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-05-12T20:22:58.052Z,1652386978.052 [BuoyancyServo] Loaded
2022-05-12T20:22:58.052Z,1652386978.052 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-05-12T20:22:58.073Z,1652386978.073 [ElevatorServo] Loaded
2022-05-12T20:22:58.073Z,1652386978.073 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-05-12T20:22:58.093Z,1652386978.093 [MassServo] Loaded
2022-05-12T20:22:58.093Z,1652386978.093 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-05-12T20:22:58.113Z,1652386978.113 [RudderServo] Loaded
2022-05-12T20:22:58.113Z,1652386978.113 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-05-12T20:22:58.129Z,1652386978.129 [ThrusterHE] Loaded
2022-05-12T20:22:58.129Z,1652386978.129 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-05-12T20:22:58.130Z,1652386978.130 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-05-12T20:22:58.130Z,1652386978.130 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-05-12T20:22:58.270Z,1652386978.270 [SBIT](DEBUG): Construct Startup Built In Test.
2022-05-12T20:22:58.284Z,1652386978.284 [SBIT] Loaded
2022-05-12T20:22:58.284Z,1652386978.284 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-05-12T20:22:58.287Z,1652386978.287 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-05-12T20:22:58.308Z,1652386978.308 [IBIT] Loaded
2022-05-12T20:22:58.308Z,1652386978.308 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-05-12T20:22:58.314Z,1652386978.314 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-05-12T20:22:58.529Z,1652386978.529 [CBIT] Loaded
2022-05-12T20:22:58.530Z,1652386978.530 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-05-12T20:22:58.530Z,1652386978.530 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-05-12T20:22:58.531Z,1652386978.531 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-05-12T20:22:58.698Z,1652386978.698 [CTD_Seabird] Loaded
2022-05-12T20:22:58.698Z,1652386978.698 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-05-12T20:22:58.699Z,1652386978.699 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2022-05-12T20:22:58.700Z,1652386978.700 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9457
2022-05-12T20:22:58.731Z,1652386978.731 [ESPComponent] Loaded
2022-05-12T20:22:58.731Z,1652386978.731 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-05-12T20:22:58.750Z,1652386978.750 [PAR_Licor] Loaded
2022-05-12T20:22:58.750Z,1652386978.750 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-05-12T20:22:58.781Z,1652386978.781 [WetLabsBB2FL] Loaded
2022-05-12T20:22:58.782Z,1652386978.782 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-05-12T20:22:58.783Z,1652386978.783 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406914E0
2022-05-12T20:22:58.783Z,1652386978.783 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9458
2022-05-12T20:22:58.784Z,1652386978.784 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-05-12T20:22:58.784Z,1652386978.784 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-05-12T20:22:58.834Z,1652386978.834 [DepthRateCalculator] Loaded
2022-05-12T20:22:58.834Z,1652386978.834 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-05-12T20:22:58.839Z,1652386978.839 [PitchRateCalculator] Loaded
2022-05-12T20:22:58.839Z,1652386978.839 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-05-12T20:22:58.850Z,1652386978.850 [SpeedCalculator] Loaded
2022-05-12T20:22:58.850Z,1652386978.850 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-05-12T20:22:58.855Z,1652386978.855 [YawRateCalculator] Loaded
2022-05-12T20:22:58.855Z,1652386978.855 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-05-12T20:22:58.874Z,1652386978.874 [ElevatorOffsetCalculator] Loaded
2022-05-12T20:22:58.874Z,1652386978.874 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-05-12T20:22:58.891Z,1652386978.891 [ShortestDistanceToPolygonSidesCalculator] Loaded
2022-05-12T20:22:58.891Z,1652386978.891 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2022-05-12T20:22:58.891Z,1652386978.891 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-05-12T20:22:58.892Z,1652386978.892 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-05-12T20:22:58.986Z,1652386978.986 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-05-12T20:22:58.987Z,1652386978.987 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-05-12T20:22:59.013Z,1652386979.013 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-05-12T20:22:59.014Z,1652386979.014 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-05-12T20:22:59.223Z,1652386979.223 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-05-12T20:22:59.225Z,1652386979.225 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-05-12T20:22:59.238Z,1652386979.238 [NavChart] Loaded
2022-05-12T20:22:59.239Z,1652386979.239 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-05-12T20:22:59.245Z,1652386979.245 [UniversalFixResidualReporter] Loaded
2022-05-12T20:22:59.245Z,1652386979.245 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-05-12T20:22:59.245Z,1652386979.245 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-05-12T20:22:59.246Z,1652386979.246 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-05-12T20:22:59.640Z,1652386979.640 [AHRS_M2] Loaded
2022-05-12T20:22:59.640Z,1652386979.640 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-05-12T20:22:59.674Z,1652386979.674 [BackseatComponent] Loaded
2022-05-12T20:22:59.675Z,1652386979.675 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-05-12T20:22:59.676Z,1652386979.676 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0
2022-05-12T20:22:59.676Z,1652386979.676 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9459
2022-05-12T20:22:59.679Z,1652386979.679 [LcmUniversalReporter] Loaded
2022-05-12T20:22:59.679Z,1652386979.679 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-05-12T20:22:59.757Z,1652386979.757 [DataOverHttps] Loaded
2022-05-12T20:22:59.757Z,1652386979.757 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-05-12T20:22:59.758Z,1652386979.758 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A3D4E0
2022-05-12T20:22:59.759Z,1652386979.759 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9460
2022-05-12T20:22:59.779Z,1652386979.779 [Depth_Keller] Loaded
2022-05-12T20:22:59.779Z,1652386979.779 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-05-12T20:22:59.784Z,1652386979.784 [DropWeight] Loaded
2022-05-12T20:22:59.784Z,1652386979.784 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-05-12T20:22:59.820Z,1652386979.820 [DVL_micro] Loaded
2022-05-12T20:22:59.821Z,1652386979.821 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2022-05-12T20:22:59.879Z,1652386979.879 [NAL9602] Loaded
2022-05-12T20:22:59.879Z,1652386979.879 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-05-12T20:22:59.905Z,1652386979.905 [Onboard] Loaded
2022-05-12T20:22:59.905Z,1652386979.905 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-05-12T20:22:59.906Z,1652386979.906 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A6D4E0
2022-05-12T20:22:59.907Z,1652386979.907 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9461
2022-05-12T20:22:59.919Z,1652386979.919 [Power24vConverter] Loaded
2022-05-12T20:22:59.919Z,1652386979.919 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-05-12T20:22:59.932Z,1652386979.932 [Radio_Surface] Loaded
2022-05-12T20:22:59.933Z,1652386979.933 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-05-12T20:22:59.933Z,1652386979.933 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A9D4E0
2022-05-12T20:22:59.934Z,1652386979.934 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9462
2022-05-12T20:22:59.935Z,1652386979.935 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-05-12T20:22:59.935Z,1652386979.935 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-05-12T20:22:59.994Z,1652386979.994 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-05-12T20:22:59.995Z,1652386979.995 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-05-12T20:23:00.046Z,1652386980.046 [VerticalControl](DEBUG): Construct VerticalControl.
2022-05-12T20:23:00.097Z,1652386980.097 [VerticalControl] Loaded
2022-05-12T20:23:00.097Z,1652386980.097 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-05-12T20:23:00.100Z,1652386980.100 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-05-12T20:23:00.136Z,1652386980.136 [HorizontalControl] Loaded
2022-05-12T20:23:00.136Z,1652386980.136 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-05-12T20:23:00.139Z,1652386980.139 [SpeedControl](DEBUG): Construct SpeedControl.
2022-05-12T20:23:00.141Z,1652386980.141 [SpeedControl] Loaded
2022-05-12T20:23:00.141Z,1652386980.141 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-05-12T20:23:00.144Z,1652386980.144 [LoopControl](DEBUG): Construct LoopControl.
2022-05-12T20:23:00.144Z,1652386980.144 [LoopControl] Loaded
2022-05-12T20:23:00.144Z,1652386980.144 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-05-12T20:23:00.145Z,1652386980.145 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-05-12T20:23:00.145Z,1652386980.145 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-05-12T20:23:00.156Z,1652386980.156 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-05-12T20:23:00.156Z,1652386980.156 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-05-12T20:23:00.465Z,1652386980.465 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-05-12T20:23:00.471Z,1652386980.471 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-05-12T20:23:00.474Z,1652386980.474 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-05-12T20:23:00.485Z,1652386980.485 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-05-12T20:23:00.486Z,1652386980.486 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA74E0
2022-05-12T20:23:00.486Z,1652386980.486 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9463
2022-05-12T20:23:00.491Z,1652386980.491 [Supervisor](INFO): Main Thread ID is 9369
2022-05-12T20:23:00.491Z,1652386980.491 [Supervisor](DEBUG): Running supervisor.
2022-05-12T20:23:00.492Z,1652386980.492 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9464
2022-05-12T20:23:00.492Z,1652386980.492 [CommandExec](INFO): Initializing the command executive.
2022-05-12T20:23:00.494Z,1652386980.494 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9465
2022-05-12T20:23:00.496Z,1652386980.496 [controlThread ThreadHandler](INFO): Handler Thread ID is 9466
2022-05-12T20:23:00.496Z,1652386980.496 [controlThread](DEBUG): Initializing ControlThread
2022-05-12T20:23:00.498Z,1652386980.498 [SBIT](INFO): Initialize SBIT Component.
2022-05-12T20:23:00.498Z,1652386980.498 [SBIT](IMPORTANT): git: 2022-04-25-37-g2a944135f
2022-05-12T20:23:00.499Z,1652386980.499 [SBIT](INFO): git hash: 2a944135f4713de004d013a6b17eee164aec7a5a
2022-05-12T20:23:00.499Z,1652386980.499 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-05-12T20:23:00.500Z,1652386980.500 [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-05-12T20:23:00.501Z,1652386980.501 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2022-05-12T20:23:00.502Z,1652386980.502 [IBIT](INFO): Initialize IBIT Component.
2022-05-12T20:23:00.503Z,1652386980.503 [CBIT](DEBUG): Initialize CBIT Component.
2022-05-12T20:23:00.503Z,1652386980.503 [logger ThreadHandler](INFO): Handler Thread ID is 9467
2022-05-12T20:23:00.516Z,1652386980.516 [CBIT](DEBUG): Initialized mux pins.
2022-05-12T20:23:00.516Z,1652386980.516 [CBIT](DEBUG): Initializing the watchdog timer.
2022-05-12T20:23:00.524Z,1652386980.524 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9468
2022-05-12T20:23:00.525Z,1652386980.525 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-12T20:23:00.529Z,1652386980.529 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9470
2022-05-12T20:23:00.532Z,1652386980.532 [WetLabsBB2FL](INFO): Powering up
2022-05-12T20:23:00.534Z,1652386980.534 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9472
2022-05-12T20:23:00.540Z,1652386980.540 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-05-12T20:23:00.540Z,1652386980.540 [CBIT](DEBUG): Initializing heartbeat.
2022-05-12T20:23:00.541Z,1652386980.541 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9473
2022-05-12T20:23:00.542Z,1652386980.542 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-05-12T20:23:00.564Z,1652386980.564 [Onboard ThreadHandler](INFO): Handler Thread ID is 9474
2022-05-12T20:23:00.582Z,1652386980.582 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9475
2022-05-12T20:23:00.606Z,1652386980.606 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9476
2022-05-12T20:23:00.608Z,1652386980.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-05-12T20:23:00.608Z,1652386980.608 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-05-12T20:23:00.609Z,1652386980.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-05-12T20:23:00.609Z,1652386980.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-05-12T20:23:00.609Z,1652386980.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-05-12T20:23:00.609Z,1652386980.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-05-12T20:23:00.609Z,1652386980.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-05-12T20:23:00.609Z,1652386980.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-05-12T20:23:00.610Z,1652386980.610 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2022-05-12T20:23:00.610Z,1652386980.610 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2022-05-12T20:23:00.612Z,1652386980.612 [CBIT](DEBUG): Deactivating GF circuits.
2022-05-12T20:23:00.612Z,1652386980.612 [CBIT](DEBUG): Deactivating emergency mode.
2022-05-12T20:23:00.648Z,1652386980.648 [CBIT](DEBUG): Backplane powered.
2022-05-12T20:23:00.652Z,1652386980.652 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-05-12T20:23:00.653Z,1652386980.653 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-05-12T20:23:00.653Z,1652386980.653 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-05-12T20:23:00.653Z,1652386980.653 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-05-12T20:23:00.654Z,1652386980.654 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-05-12T20:23:00.654Z,1652386980.654 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2022-05-12T20:23:00.656Z,1652386980.656 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-05-12T20:23:00.656Z,1652386980.656 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-05-12T20:23:00.662Z,1652386980.662 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-05-12T20:23:00.664Z,1652386980.664 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-05-12T20:23:00.665Z,1652386980.665 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-05-12T20:23:00.665Z,1652386980.665 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-05-12T20:23:00.666Z,1652386980.666 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-05-12T20:23:00.695Z,1652386980.695 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-12T20:23:00.731Z,1652386980.731 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2022-05-12T20:23:00.740Z,1652386980.740 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-05-12T20:23:00.799Z,1652386980.799 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-05-12T20:23:00.825Z,1652386980.825 [Default:A.Wait](DEBUG): Construct Wait.
2022-05-12T20:23:00.827Z,1652386980.827 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-12T20:23:00.847Z,1652386980.847 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-05-12T20:23:00.873Z,1652386980.873 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-05-12T20:23:00.879Z,1652386980.879 [Default:E.Execute](DEBUG): Construct Execute.
2022-05-12T20:23:00.895Z,1652386980.895 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2022-05-12T20:23:00.900Z,1652386980.900 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-05-12T20:23:00.910Z,1652386980.910 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-12T20:23:00.953Z,1652386980.953 [Depth_Keller](ERROR): Pressure reading out of range: 1896.040405 decibar
2022-05-12T20:23:00.961Z,1652386980.961 [Power24vConverter](INFO): Powering up.
2022-05-12T20:23:00.977Z,1652386980.977 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-05-12T20:23:00.988Z,1652386980.988 [Radio_Surface](INFO): Powering up
2022-05-12T20:23:01.007Z,1652386981.007 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-05-12T20:23:01.013Z,1652386981.013 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-05-12T20:23:01.014Z,1652386981.014 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-05-12T20:23:01.023Z,1652386981.023 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-05-12T20:23:01.024Z,1652386981.024 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-12T20:23:01.040Z,1652386981.040 [MassServo](DEBUG): Initializing MassServo.
2022-05-12T20:23:01.041Z,1652386981.041 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-12T20:23:01.048Z,1652386981.048 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-12T20:23:01.049Z,1652386981.049 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-05-12T20:23:01.056Z,1652386981.056 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-05-12T20:23:04.456Z,1652386984.456 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:04.860Z,1652386984.860 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:05.428Z,1652386985.428 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:05.820Z,1652386985.820 [WetLabsBB2FL] Communications Fault, FailCount= 1
2022-05-12T20:23:05.820Z,1652386985.820 [WetLabsBB2FL](ERROR): Communications Fault
2022-05-12T20:23:05.836Z,1652386985.836 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:05.889Z,1652386985.889 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-05-12T20:23:06.240Z,1652386986.240 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:06.320Z,1652386986.320 [WetLabsBB2FL](INFO): Powering down
2022-05-12T20:23:06.676Z,1652386986.676 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:07.252Z,1652386987.252 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:07.556Z,1652386987.556 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-05-12T20:23:07.556Z,1652386987.556 [WetLabsBB2FL] No Fault, FailCount= 1
2022-05-12T20:23:07.888Z,1652386987.888 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:07.952Z,1652386987.952 [WetLabsBB2FL](INFO): Powering up
2022-05-12T20:23:08.316Z,1652386988.316 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:08.752Z,1652386988.752 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:09.164Z,1652386989.164 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:09.584Z,1652386989.584 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:09.000Z,1652386990.000 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:10.420Z,1652386990.420 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:10.836Z,1652386990.836 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:11.268Z,1652386991.268 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:11.684Z,1652386991.684 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:12.112Z,1652386992.112 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:12.544Z,1652386992.544 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:12.972Z,1652386992.972 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:13.228Z,1652386993.228 [WetLabsBB2FL] Communications Fault, FailCount= 2
2022-05-12T20:23:13.228Z,1652386993.228 [WetLabsBB2FL](ERROR): Communications Fault
2022-05-12T20:23:13.400Z,1652386993.400 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:13.493Z,1652386993.493 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-05-12T20:23:13.582Z,1652386993.582 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:13.582Z,1652386993.582 [CTD_Seabird] Communications Fault, FailCount= 1
2022-05-12T20:23:13.583Z,1652386993.583 [CTD_Seabird](ERROR): Communications Fault
2022-05-12T20:23:13.728Z,1652386993.728 [WetLabsBB2FL](INFO): Powering down
2022-05-12T20:23:13.872Z,1652386993.872 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:13.872Z,1652386993.872 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:13.872Z,1652386993.872 [AHRS_M2] Communications Fault, FailCount= 1
2022-05-12T20:23:13.872Z,1652386993.872 [AHRS_M2](ERROR): Communications Fault
2022-05-12T20:23:13.933Z,1652386993.933 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-12T20:23:13.933Z,1652386993.933 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-05-12T20:23:13.960Z,1652386993.960 [AHRS_M2](INFO): Powering down
2022-05-12T20:23:13.989Z,1652386993.989 [CTD_Seabird](INFO): Powering down
2022-05-12T20:23:14.772Z,1652386994.772 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-05-12T20:23:14.772Z,1652386994.772 [WetLabsBB2FL] No Fault, FailCount= 2
2022-05-12T20:23:14.773Z,1652386994.773 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-05-12T20:23:14.773Z,1652386994.773 [AHRS_M2] No Fault, FailCount= 1
2022-05-12T20:23:14.941Z,1652386994.941 [WetLabsBB2FL](INFO): Powering up
2022-05-12T20:23:15.165Z,1652386995.165 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-12T20:23:15.222Z,1652386995.222 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-12T20:23:15.222Z,1652386995.222 [CTD_Seabird] No Fault, FailCount= 1
2022-05-12T20:23:15.332Z,1652386995.332 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-12T20:23:18.708Z,1652386998.708 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:18.710Z,1652386998.710 [DVL_micro](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:18.710Z,1652386998.710 [DVL_micro] Communications Fault, FailCount= 1
2022-05-12T20:23:18.710Z,1652386998.710 [DVL_micro](ERROR): Communications Fault
2022-05-12T20:23:18.725Z,1652386998.725 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-05-12T20:23:19.112Z,1652386999.112 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:19.188Z,1652386999.188 [DVL_micro](INFO): Powering down
2022-05-12T20:23:19.596Z,1652386999.596 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:20.020Z,1652387000.020 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:20.065Z,1652387000.065 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-05-12T20:23:20.065Z,1652387000.065 [DVL_micro] No Fault, FailCount= 1
2022-05-12T20:23:20.236Z,1652387000.236 [WetLabsBB2FL] Communications Fault, FailCount= 3
2022-05-12T20:23:20.236Z,1652387000.236 [WetLabsBB2FL](ERROR): Communications Fault
2022-05-12T20:23:20.440Z,1652387000.440 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:20.489Z,1652387000.489 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-05-12T20:23:20.736Z,1652387000.736 [WetLabsBB2FL](INFO): Powering down
2022-05-12T20:23:20.884Z,1652387000.884 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:21.312Z,1652387001.312 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:21.335Z,1652387001.335 [SBIT](IMPORTANT): Beginning Startup BIT
2022-05-12T20:23:21.371Z,1652387001.371 [CBIT](IMPORTANT): Beginning ground fault scan
2022-05-12T20:23:21.728Z,1652387001.728 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:21.837Z,1652387001.837 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-05-12T20:23:21.837Z,1652387001.837 [WetLabsBB2FL] No Fault, FailCount= 3
2022-05-12T20:23:21.949Z,1652387001.949 [WetLabsBB2FL](INFO): Powering up
2022-05-12T20:23:22.180Z,1652387002.180 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:22.596Z,1652387002.596 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:23.016Z,1652387003.016 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:23.588Z,1652387003.588 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:24.068Z,1652387004.068 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:24.504Z,1652387004.504 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:25.084Z,1652387005.084 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:25.628Z,1652387005.628 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:26.048Z,1652387006.048 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:26.612Z,1652387006.612 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:27.052Z,1652387007.052 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:27.248Z,1652387007.248 [WetLabsBB2FL] Communications Fault, FailCount= 4
2022-05-12T20:23:27.248Z,1652387007.248 [WetLabsBB2FL](ERROR): Communications Fault
2022-05-12T20:23:27.512Z,1652387007.512 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:27.648Z,1652387007.648 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-05-12T20:23:27.748Z,1652387007.748 [WetLabsBB2FL](INFO): Powering down
2022-05-12T20:23:28.068Z,1652387008.068 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:28.068Z,1652387008.068 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:28.068Z,1652387008.068 [AHRS_M2] Communications Fault, FailCount= 2
2022-05-12T20:23:28.068Z,1652387008.068 [AHRS_M2](ERROR): Communications Fault
2022-05-12T20:23:28.204Z,1652387008.204 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-05-12T20:23:28.229Z,1652387008.229 [AHRS_M2](INFO): Powering down
2022-05-12T20:23:28.384Z,1652387008.384 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:28.384Z,1652387008.384 [CTD_Seabird] Communications Fault, FailCount= 2
2022-05-12T20:23:28.384Z,1652387008.384 [CTD_Seabird](ERROR): Communications Fault
2022-05-12T20:23:28.436Z,1652387008.436 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-12T20:23:28.728Z,1652387008.728 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-05-12T20:23:28.728Z,1652387008.728 [WetLabsBB2FL] No Fault, FailCount= 4
2022-05-12T20:23:28.788Z,1652387008.788 [CTD_Seabird](INFO): Powering down
2022-05-12T20:23:28.961Z,1652387008.961 [WetLabsBB2FL](INFO): Powering up
2022-05-12T20:23:29.168Z,1652387009.168 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-05-12T20:23:29.168Z,1652387009.168 [AHRS_M2] No Fault, FailCount= 2
2022-05-12T20:23:29.424Z,1652387009.424 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-12T20:23:29.944Z,1652387009.944 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-12T20:23:29.944Z,1652387009.944 [CTD_Seabird] No Fault, FailCount= 2
2022-05-12T20:23:30.132Z,1652387010.132 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-12T20:23:30.238Z,1652387010.238 [NAL9602](INFO): Powering up NAL9602
2022-05-12T20:23:32.984Z,1652387012.984 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:33.292Z,1652387013.292 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:33.412Z,1652387013.412 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.005513
CHAN A1 (24V): -0.000029
CHAN A2 (12V): -0.005784
CHAN A3 (5V): -0.002243
CHAN B0 (3.3V): -0.001334
CHAN B1 (3.15aV): -0.000729
CHAN B2 (3.15bV): -0.000640
CHAN B3 (GND): -0.000242
OPEN: 0.007040
Full Scale: +/- 1 mA
2022-05-12T20:23:33.692Z,1652387013.692 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:34.260Z,1652387014.260 [WetLabsBB2FL] Communications Fault, FailCount= 5
2022-05-12T20:23:34.260Z,1652387014.260 [WetLabsBB2FL](ERROR): Communications Fault
2022-05-12T20:23:34.312Z,1652387014.312 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:34.429Z,1652387014.429 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-05-12T20:23:34.430Z,1652387014.430 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2022-05-12T20:23:34.760Z,1652387014.760 [WetLabsBB2FL](INFO): Powering down
2022-05-12T20:23:34.761Z,1652387014.761 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:35.292Z,1652387015.292 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:35.724Z,1652387015.724 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:36.136Z,1652387016.136 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:36.552Z,1652387016.552 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:37.048Z,1652387017.048 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:37.516Z,1652387017.516 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:37.521Z,1652387017.521 [DVL_micro](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:37.522Z,1652387017.522 [DVL_micro] Communications Fault, FailCount= 2
2022-05-12T20:23:37.522Z,1652387017.522 [DVL_micro](ERROR): Communications Fault
2022-05-12T20:23:37.621Z,1652387017.621 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-05-12T20:23:37.948Z,1652387017.948 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:38.028Z,1652387018.028 [DVL_micro](INFO): Powering down
2022-05-12T20:23:38.468Z,1652387018.468 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:38.936Z,1652387018.936 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:39.058Z,1652387019.058 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-05-12T20:23:39.058Z,1652387019.058 [DVL_micro] No Fault, FailCount= 2
2022-05-12T20:23:39.396Z,1652387019.396 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:39.426Z,1652387019.426 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004769
2022-05-12T20:23:39.860Z,1652387019.860 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:40.292Z,1652387020.292 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:40.768Z,1652387020.768 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:41.268Z,1652387021.268 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:41.278Z,1652387021.278 [NAL9602](INFO): NAL9602 initialized
2022-05-12T20:23:41.800Z,1652387021.800 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:42.260Z,1652387022.260 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:42.260Z,1652387022.260 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:42.260Z,1652387022.260 [AHRS_M2] Communications Fault, FailCount= 3
2022-05-12T20:23:42.260Z,1652387022.260 [AHRS_M2](ERROR): Communications Fault
2022-05-12T20:23:42.473Z,1652387022.473 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-05-12T20:23:42.484Z,1652387022.484 [AHRS_M2](INFO): Powering down
2022-05-12T20:23:43.184Z,1652387023.184 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:43.184Z,1652387023.184 [CTD_Seabird] Communications Fault, FailCount= 3
2022-05-12T20:23:43.184Z,1652387023.184 [CTD_Seabird](ERROR): Communications Fault
2022-05-12T20:23:43.344Z,1652387023.344 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-12T20:23:43.345Z,1652387023.345 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-05-12T20:23:43.345Z,1652387023.345 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-05-12T20:23:43.345Z,1652387023.345 [AHRS_M2] No Fault, FailCount= 3
2022-05-12T20:23:43.588Z,1652387023.588 [CTD_Seabird](INFO): Powering down
2022-05-12T20:23:43.692Z,1652387023.692 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-12T20:23:47.256Z,1652387027.256 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:47.676Z,1652387027.676 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:48.092Z,1652387028.092 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:48.508Z,1652387028.508 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:48.920Z,1652387028.920 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:49.340Z,1652387029.340 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:49.764Z,1652387029.764 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:50.200Z,1652387030.200 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:50.628Z,1652387030.628 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:51.060Z,1652387031.060 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:51.472Z,1652387031.472 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:51.888Z,1652387031.888 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:52.296Z,1652387032.296 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:52.708Z,1652387032.708 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:53.136Z,1652387033.136 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:53.552Z,1652387033.552 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:53.968Z,1652387033.968 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:54.392Z,1652387034.392 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:54.824Z,1652387034.824 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:55.240Z,1652387035.240 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:55.744Z,1652387035.744 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:56.176Z,1652387036.176 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:56.592Z,1652387036.592 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:23:56.592Z,1652387036.592 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:56.592Z,1652387036.592 [AHRS_M2] Communications Fault, FailCount= 4
2022-05-12T20:23:56.592Z,1652387036.592 [AHRS_M2](ERROR): Communications Fault
2022-05-12T20:23:56.601Z,1652387036.601 [DVL_micro](FAULT): Failed to initialize within timeout.
2022-05-12T20:23:56.601Z,1652387036.601 [DVL_micro] Communications Fault, FailCount= 3
2022-05-12T20:23:56.601Z,1652387036.601 [DVL_micro](ERROR): Communications Fault
2022-05-12T20:23:56.649Z,1652387036.649 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-05-12T20:23:56.649Z,1652387036.649 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-05-12T20:23:56.649Z,1652387036.649 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2022-05-12T20:23:56.696Z,1652387036.696 [AHRS_M2](INFO): Powering down
2022-05-12T20:23:56.844Z,1652387036.844 [DVL_micro](INFO): Powering down
2022-05-12T20:23:56.863Z,1652387036.863 [CommandExec](FAULT): Scheduling is paused
2022-05-12T20:23:56.884Z,1652387036.884 [CBIT](INFO): Critical error at 20220512T202356
2022-05-12T20:23:56.884Z,1652387036.884 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-05-12T20:23:57.500Z,1652387037.500 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-05-12T20:23:57.500Z,1652387037.500 [AHRS_M2] No Fault, FailCount= 4
2022-05-12T20:23:57.869Z,1652387037.869 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-12T20:24:01.424Z,1652387041.424 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:01.840Z,1652387041.840 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:02.248Z,1652387042.248 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:02.664Z,1652387042.664 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:03.080Z,1652387043.080 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:03.496Z,1652387043.496 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:03.976Z,1652387043.976 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:04.444Z,1652387044.444 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:04.908Z,1652387044.908 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:05.376Z,1652387045.376 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:05.852Z,1652387045.852 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:06.312Z,1652387046.312 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:06.820Z,1652387046.820 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:07.468Z,1652387047.468 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:07.908Z,1652387047.908 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:08.324Z,1652387048.324 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:08.740Z,1652387048.740 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:09.160Z,1652387049.160 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:09.676Z,1652387049.676 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:10.120Z,1652387050.120 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:10.572Z,1652387050.572 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:11.032Z,1652387051.032 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-12T20:24:11.032Z,1652387051.032 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-05-12T20:24:11.032Z,1652387051.032 [AHRS_M2] Communications Fault, FailCount= 5
2022-05-12T20:24:11.032Z,1652387051.032 [AHRS_M2](ERROR): Communications Fault
2022-05-12T20:24:11.128Z,1652387051.128 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-05-12T20:24:11.129Z,1652387051.129 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2022-05-12T20:24:11.140Z,1652387051.140 [AHRS_M2](INFO): Powering down
2022-05-12T20:24:11.263Z,1652387051.263 [CBIT](INFO): Critical error at 20220512T202411
2022-05-12T20:24:15.621Z,1652387055.621 [SBIT](IMPORTANT): SBIT PASSED
2022-05-12T20:24:15.621Z,1652387055.621 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-05-12T20:24:15.622Z,1652387055.622 [SBIT](IMPORTANT): BPC1.loadAtStartup=0 bool;

2022-05-12T20:24:15.623Z,1652387055.623 [SBIT](IMPORTANT): Express linearApproximation CBIT.ampHoursUsed 1.000000 ampere_hour;

2022-05-12T20:24:15.694Z,1652387055.694 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1=34.4368 degree;

2022-05-12T20:24:15.694Z,1652387055.694 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2=34.267 degree;

2022-05-12T20:24:15.697Z,1652387055.697 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3=34.107 degree;

2022-05-12T20:24:15.697Z,1652387055.697 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4=34.37 degree;

2022-05-12T20:24:15.698Z,1652387055.698 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;

2022-05-12T20:24:15.698Z,1652387055.698 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1=-120.476 degree;

2022-05-12T20:24:15.698Z,1652387055.698 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2=-119.333 degree;

2022-05-12T20:24:15.698Z,1652387055.698 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3=-119.33 degree;

2022-05-12T20:24:15.698Z,1652387055.698 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4=-120.5 degree;

2022-05-12T20:24:15.698Z,1652387055.698 [SBIT](IMPORTANT): VerticalControl.massDefault=0 centimeter;

2022-05-12T20:24:15.698Z,1652387055.698 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter;

2022-05-12T20:24:16.017Z,1652387056.017 [MissionManager](IMPORTANT): Started mission Startup
2022-05-12T20:24:16.017Z,1652387056.017 [Startup] Running Loop=1
2022-05-12T20:24:16.017Z,1652387056.017 [Startup](DEBUG): Aggregate::initialize Startup
2022-05-12T20:24:16.017Z,1652387056.017 [Startup:A.GoToSurface] Running Loop=1
2022-05-12T20:24:16.017Z,1652387056.017 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-05-12T20:24:16.018Z,1652387056.018 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-05-12T20:24:16.018Z,1652387056.018 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-05-12T20:24:16.019Z,1652387056.019 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-05-12T20:24:16.019Z,1652387056.019 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-05-12T20:24:16.019Z,1652387056.019 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-05-12T20:24:16.021Z,1652387056.021 [Startup:StartupSatComms] Running Loop=1
2022-05-12T20:24:16.022Z,1652387056.022 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-05-12T20:24:16.022Z,1652387056.022 [Startup:StartupSatComms:A] Running Loop=1
2022-05-12T20:24:16.408Z,1652387056.408 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-05-12T20:24:47.959Z,1652387087.959 [CBIT](FAULT): WATER ALARM AUX.
2022-05-12T20:24:47.959Z,1652387087.959 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2022-05-12T20:24:48.757Z,1652387088.757 [CBIT](FAULT): WATER ALARM AUX.
2022-05-12T20:24:48.757Z,1652387088.757 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2022-05-12T20:24:49.164Z,1652387089.164 [CBIT](FAULT): WATER ALARM AUX.
2022-05-12T20:24:49.164Z,1652387089.164 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2022-05-12T20:25:00.868Z,1652387100.868 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-05-12T20:25:00.868Z,1652387100.868 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-05-12T20:25:00.868Z,1652387100.868 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-12T20:25:00.880Z,1652387100.880 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-12T20:25:01.283Z,1652387101.283 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-12T20:25:01.283Z,1652387101.283 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-05-12T20:25:02.524Z,1652387102.524 [CBIT](FAULT): WATER ALARM AUX.
2022-05-12T20:25:02.524Z,1652387102.524 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2022-05-12T20:25:02.910Z,1652387102.910 [CBIT](FAULT): WATER ALARM AUX.
2022-05-12T20:25:02.910Z,1652387102.910 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2022-05-12T20:25:03.314Z,1652387103.314 [CBIT](FAULT): WATER ALARM AUX.
2022-05-12T20:25:03.314Z,1652387103.314 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2022-05-12T20:25:03.708Z,1652387103.708 [CBIT](FAULT): WATER ALARM AUX.
2022-05-12T20:25:03.720Z,1652387103.720 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2022-05-12T20:25:03.720Z,1652387103.720 [CBIT](IMPORTANT): Powered off ESP with command: ! echo 0 > /dev/loadA6
2022-05-12T20:25:03.778Z,1652387103.778 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadA6
2022-05-12T20:25:04.115Z,1652387104.115 [CBIT](INFO): Critical error at 20220512T202503
2022-05-12T20:25:09.193Z,1652387109.193 [CommandExec](IMPORTANT): got command burn off
2022-05-12T20:25:09.193Z,1652387109.193 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-05-12T20:25:13.249Z,1652387113.249 [CommandExec](IMPORTANT): got command gfscan
2022-05-12T20:25:13.427Z,1652387113.427 [CBIT](IMPORTANT): Beginning ground fault scan
2022-05-12T20:25:16.224Z,1652387116.224 [Startup:StartupSatComms:A](INFO): Timed out from 2022-05-12T20:24:16.0Z
2022-05-12T20:25:16.224Z,1652387116.224 [Startup:StartupSatComms:A] Stopped
2022-05-12T20:25:16.224Z,1652387116.224 [Startup:StartupSatComms:B] Running Loop=1
2022-05-12T20:25:16.632Z,1652387116.632 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-05-12T20:25:23.349Z,1652387123.349 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20220512T181050/Courier0040.lzma
2022-05-12T20:25:24.347Z,1652387124.347 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.005346
CHAN A1 (24V): 0.000174
CHAN A2 (12V): -0.005391
CHAN A3 (5V): -0.002528
CHAN B0 (3.3V): -0.001196
CHAN B1 (3.15aV): -0.000923
CHAN B2 (3.15bV): -0.001078
CHAN B3 (GND): -0.000272
OPEN: 0.007265
Full Scale: +/- 1 mA
2022-05-12T20:25:24.350Z,1652387124.350 [DataOverHttps](INFO): Moved sent file to Logs/20220512T181050/Courier0040.lzma.bak
2022-05-12T20:25:24.350Z,1652387124.350 [DataOverHttps](INFO): SBD MOMSN=16750808
2022-05-12T20:25:25.749Z,1652387125.749 [CommandExec](IMPORTANT): got command strobe off
2022-05-12T20:25:25.749Z,1652387125.749 [CommandExec](IMPORTANT): Deactivating strobe
2022-05-12T20:25:37.188Z,1652387137.188 [CommandExec](IMPORTANT): got command quit
2022-05-12T20:25:38.192Z,1652387138.192 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:38.192Z,1652387138.192 [CommandExec](INFO): Uninitializing the command executive.
2022-05-12T20:25:38.192Z,1652387138.192 [CommandExec](INFO): Uninitializing the command scheduler.
2022-05-12T20:25:38.193Z,1652387138.193 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:38.376Z,1652387138.376 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-05-12T20:25:38.376Z,1652387138.376 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-05-12T20:25:38.377Z,1652387138.377 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:38.377Z,1652387138.377 [NavChartDb](INFO): Join timeout helper Thread ID is 9504
2022-05-12T20:25:38.728Z,1652387138.728 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:38.728Z,1652387138.728 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:38.745Z,1652387138.745 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-05-12T20:25:38.745Z,1652387138.745 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:38.749Z,1652387138.749 [Radio_Surface](INFO): Join timeout helper Thread ID is 9505
2022-05-12T20:25:38.968Z,1652387138.968 [Radio_Surface](INFO): Powering down
2022-05-12T20:25:38.969Z,1652387138.969 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:38.969Z,1652387138.969 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:38.976Z,1652387138.976 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-05-12T20:25:38.976Z,1652387138.976 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:38.976Z,1652387138.976 [Onboard](INFO): Join timeout helper Thread ID is 9506
2022-05-12T20:25:39.576Z,1652387139.576 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20220512T202251/Courier0000.lzma
2022-05-12T20:25:40.708Z,1652387140.708 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:40.708Z,1652387140.708 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:40.729Z,1652387140.729 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-05-12T20:25:40.729Z,1652387140.729 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:40.732Z,1652387140.732 [DataOverHttps](INFO): Join timeout helper Thread ID is 9507
2022-05-12T20:25:41.580Z,1652387141.580 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:41.583Z,1652387141.583 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:41.584Z,1652387141.584 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-05-12T20:25:41.584Z,1652387141.584 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:41.584Z,1652387141.584 [BackseatComponent](INFO): Join timeout helper Thread ID is 9508
2022-05-12T20:25:41.700Z,1652387141.700 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:41.700Z,1652387141.700 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:41.705Z,1652387141.705 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-05-12T20:25:41.705Z,1652387141.705 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:41.705Z,1652387141.705 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9509
2022-05-12T20:25:42.024Z,1652387142.024 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:42.027Z,1652387142.027 [WetLabsBB2FL](INFO): Powering down
2022-05-12T20:25:42.031Z,1652387142.031 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.033Z,1652387142.033 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-05-12T20:25:42.033Z,1652387142.033 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.033Z,1652387142.033 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9510
2022-05-12T20:25:42.156Z,1652387142.156 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:42.156Z,1652387142.156 [CTD_Seabird](INFO): Powering down
2022-05-12T20:25:42.168Z,1652387142.168 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.185Z,1652387142.185 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-05-12T20:25:42.185Z,1652387142.185 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.185Z,1652387142.185 [logger](INFO): Join timeout helper Thread ID is 9511
2022-05-12T20:25:42.208Z,1652387142.208 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:42.209Z,1652387142.209 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.229Z,1652387142.229 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-05-12T20:25:42.229Z,1652387142.229 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.229Z,1652387142.229 [CommandLine](INFO): Join timeout helper Thread ID is 9512
2022-05-12T20:25:42.292Z,1652387142.292 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:42.292Z,1652387142.292 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.308Z,1652387142.308 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-05-12T20:25:42.309Z,1652387142.309 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.309Z,1652387142.309 [CommandExec](INFO): Join timeout helper Thread ID is 9513
2022-05-12T20:25:42.310Z,1652387142.310 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-05-12T20:25:42.310Z,1652387142.310 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.311Z,1652387142.311 [controlThread](INFO): Join timeout helper Thread ID is 9514
2022-05-12T20:25:42.460Z,1652387142.460 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-12T20:25:42.460Z,1652387142.460 [controlThread](DEBUG): Uninitializing ControlThread
2022-05-12T20:25:42.462Z,1652387142.462 [AHRS_M2](INFO): Powering down
2022-05-12T20:25:42.463Z,1652387142.463 [DVL_micro](INFO): Powering down
2022-05-12T20:25:42.465Z,1652387142.465 [NAL9602](INFO): Powering down
2022-05-12T20:25:42.466Z,1652387142.466 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-05-12T20:25:42.466Z,1652387142.466 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-05-12T20:25:42.467Z,1652387142.467 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-05-12T20:25:42.467Z,1652387142.467 [MissionManager](INFO): Uninitializing Mission Default
2022-05-12T20:25:42.470Z,1652387142.470 [MissionManager](INFO): Uninitializing Mission Startup
2022-05-12T20:25:42.470Z,1652387142.470 [Startup] Stopped
2022-05-12T20:25:42.470Z,1652387142.470 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-05-12T20:25:42.470Z,1652387142.470 [Startup:A.GoToSurface] Stopped
2022-05-12T20:25:42.470Z,1652387142.470 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-05-12T20:25:42.470Z,1652387142.470 [Startup:StartupSatComms] Stopped
2022-05-12T20:25:42.471Z,1652387142.471 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-05-12T20:25:42.471Z,1652387142.471 [Startup:StartupSatComms:B] Stopped
2022-05-12T20:25:42.472Z,1652387142.472 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-05-12T20:25:42.473Z,1652387142.473 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-05-12T20:25:42.473Z,1652387142.473 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-05-12T20:25:42.473Z,1652387142.473 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-05-12T20:25:42.473Z,1652387142.473 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-05-12T20:25:42.474Z,1652387142.474 [BuoyancyServo](INFO): Powering down
2022-05-12T20:25:42.488Z,1652387142.488 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-05-12T20:25:42.488Z,1652387142.488 [ElevatorServo](INFO): Powering down
2022-05-12T20:25:42.489Z,1652387142.489 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-12T20:25:42.489Z,1652387142.489 [MassServo](INFO): Powering down
2022-05-12T20:25:42.490Z,1652387142.490 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-05-12T20:25:42.490Z,1652387142.490 [RudderServo](INFO): Powering down
2022-05-12T20:25:42.491Z,1652387142.491 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-05-12T20:25:42.491Z,1652387142.491 [ThrusterHE](INFO): Powering down
2022-05-12T20:25:42.492Z,1652387142.492 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-05-12T20:25:42.493Z,1652387142.493 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-05-12T20:25:42.493Z,1652387142.493 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-05-12T20:25:42.493Z,1652387142.493 [CBIT](DEBUG): Powering off loads.
2022-05-12T20:25:42.504Z,1652387142.504 [CBIT](DEBUG): Disabling WDT.
2022-05-12T20:25:42.516Z,1652387142.516 [CBIT](DEBUG): Opening all GF detection circuits.
2022-05-12T20:25:42.517Z,1652387142.517 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.564Z,1652387142.564 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.570Z,1652387142.570 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.600Z,1652387142.600 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.602Z,1652387142.602 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.610Z,1652387142.610 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.617Z,1652387142.617 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.667Z,1652387142.667 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-12T20:25:42.732Z,1652387142.732 [logger ThreadHandler](INFO): Thread cancelled.