2021-10-29T22:05:37.287Z,1635545137.287 [Supervisor](DEBUG): Initializing supervisor.
2021-10-29T22:05:37.292Z,1635545137.292 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-10-29T22:05:37.293Z,1635545137.293 [SyncHandler](INFO): Protected caller Thread ID is 7278
2021-10-29T22:05:37.293Z,1635545137.293 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-10-29T22:05:37.294Z,1635545137.294 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-10-29T22:05:37.294Z,1635545137.294 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7279
2021-10-29T22:05:37.299Z,1635545137.299 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-10-29T22:05:37.319Z,1635545137.319 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-10-29T22:05:37.320Z,1635545137.320 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-10-29T22:05:37.321Z,1635545137.321 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7280
2021-10-29T22:05:37.325Z,1635545137.325 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-10-29T22:05:37.326Z,1635545137.326 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-10-29T22:05:37.327Z,1635545137.327 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7281
2021-10-29T22:05:37.329Z,1635545137.329 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-10-29T22:05:37.330Z,1635545137.330 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-10-29T22:05:37.330Z,1635545137.330 [logger ThreadHandler](INFO): Protected caller Thread ID is 7282
2021-10-29T22:05:37.334Z,1635545137.334 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-10-29T22:05:37.335Z,1635545137.335 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-10-29T22:05:37.339Z,1635545137.339 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-10-29T22:05:37.643Z,1635545137.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-10-29T22:05:37.643Z,1635545137.643 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-10-29T22:05:37.727Z,1635545137.727 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-10-29T22:05:38.249Z,1635545138.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-10-29T22:05:38.251Z,1635545138.251 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-10-29T22:05:38.595Z,1635545138.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-10-29T22:05:38.596Z,1635545138.596 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-10-29T22:05:38.696Z,1635545138.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-10-29T22:05:38.698Z,1635545138.698 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-10-29T22:05:39.081Z,1635545139.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-10-29T22:05:39.083Z,1635545139.083 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-10-29T22:05:39.287Z,1635545139.287 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-10-29T22:05:39.288Z,1635545139.288 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-10-29T22:05:39.752Z,1635545139.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-10-29T22:05:39.754Z,1635545139.754 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-10-29T22:05:39.883Z,1635545139.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-10-29T22:05:39.884Z,1635545139.884 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-10-29T22:05:39.963Z,1635545139.963 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-10-29T22:05:40.660Z,1635545140.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-10-29T22:05:41.100Z,1635545141.100 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-10-29T22:05:41.629Z,1635545141.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-10-29T22:05:41.631Z,1635545141.631 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-10-29T22:05:41.831Z,1635545141.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-10-29T22:05:41.832Z,1635545141.832 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-10-29T22:05:41.944Z,1635545141.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-10-29T22:05:41.946Z,1635545141.946 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-10-29T22:05:42.101Z,1635545142.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-10-29T22:05:42.103Z,1635545142.103 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-10-29T22:05:42.338Z,1635545142.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-10-29T22:05:42.340Z,1635545142.340 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-10-29T22:05:42.342Z,1635545142.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-10-29T22:05:42.492Z,1635545142.492 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-10-29T22:05:42.580Z,1635545142.580 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-10-29T22:05:42.684Z,1635545142.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-10-29T22:05:42.767Z,1635545142.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-10-29T22:05:42.864Z,1635545142.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-10-29T22:05:42.967Z,1635545142.967 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-10-29T22:05:43.160Z,1635545143.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-10-29T22:05:43.237Z,1635545143.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-10-29T22:05:43.389Z,1635545143.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-10-29T22:05:43.532Z,1635545143.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-10-29T22:05:43.653Z,1635545143.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-10-29T22:05:43.915Z,1635545143.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-10-29T22:05:43.915Z,1635545143.915 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-10-29T22:05:43.934Z,1635545143.934 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-10-29T22:05:44.007Z,1635545144.007 [VerticalControl](DEBUG): Construct VerticalControl.
2021-10-29T22:05:44.073Z,1635545144.073 [VerticalControl] Loaded
2021-10-29T22:05:44.073Z,1635545144.073 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-10-29T22:05:44.076Z,1635545144.076 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-10-29T22:05:44.116Z,1635545144.116 [HorizontalControl] Loaded
2021-10-29T22:05:44.116Z,1635545144.116 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-10-29T22:05:44.119Z,1635545144.119 [SpeedControl](DEBUG): Construct SpeedControl.
2021-10-29T22:05:44.122Z,1635545144.122 [SpeedControl] Loaded
2021-10-29T22:05:44.122Z,1635545144.122 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-10-29T22:05:44.125Z,1635545144.125 [LoopControl](DEBUG): Construct LoopControl.
2021-10-29T22:05:44.125Z,1635545144.125 [LoopControl] Loaded
2021-10-29T22:05:44.126Z,1635545144.126 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-10-29T22:05:44.126Z,1635545144.126 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-10-29T22:05:44.128Z,1635545144.128 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-10-29T22:05:44.144Z,1635545144.144 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-10-29T22:05:44.145Z,1635545144.145 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-10-29T22:05:44.279Z,1635545144.279 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-10-29T22:05:44.280Z,1635545144.280 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-10-29T22:05:44.438Z,1635545144.438 [BuoyancyServo] Loaded
2021-10-29T22:05:44.438Z,1635545144.438 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-10-29T22:05:44.459Z,1635545144.459 [ElevatorServo] Loaded
2021-10-29T22:05:44.459Z,1635545144.459 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-10-29T22:05:44.479Z,1635545144.479 [MassServo] Loaded
2021-10-29T22:05:44.479Z,1635545144.479 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-10-29T22:05:44.498Z,1635545144.498 [RudderServo] Loaded
2021-10-29T22:05:44.498Z,1635545144.498 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-10-29T22:05:44.516Z,1635545144.516 [ThrusterServo] Loaded
2021-10-29T22:05:44.517Z,1635545144.517 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-10-29T22:05:44.517Z,1635545144.517 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-10-29T22:05:44.518Z,1635545144.518 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-10-29T22:05:44.620Z,1635545144.620 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-10-29T22:05:44.620Z,1635545144.620 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-10-29T22:05:44.634Z,1635545144.634 [NavChart] Loaded
2021-10-29T22:05:44.634Z,1635545144.634 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-10-29T22:05:44.640Z,1635545144.640 [UniversalFixResidualReporter] Loaded
2021-10-29T22:05:44.641Z,1635545144.641 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-10-29T22:05:44.641Z,1635545144.641 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-10-29T22:05:44.642Z,1635545144.642 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-10-29T22:05:44.725Z,1635545144.725 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-10-29T22:05:44.726Z,1635545144.726 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-10-29T22:05:45.432Z,1635545145.432 [AHRS_M2] Loaded
2021-10-29T22:05:45.432Z,1635545145.432 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-10-29T22:05:46.249Z,1635545146.249 [BPC1] Loaded
2021-10-29T22:05:46.249Z,1635545146.249 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-10-29T22:05:46.536Z,1635545146.536 [DataOverHttps] Loaded
2021-10-29T22:05:46.537Z,1635545146.537 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-10-29T22:05:46.538Z,1635545146.538 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BB4E0
2021-10-29T22:05:46.538Z,1635545146.538 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7367
2021-10-29T22:05:46.559Z,1635545146.559 [Depth_Keller] Loaded
2021-10-29T22:05:46.560Z,1635545146.560 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-10-29T22:05:46.564Z,1635545146.564 [DropWeight] Loaded
2021-10-29T22:05:46.565Z,1635545146.565 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-10-29T22:05:46.627Z,1635545146.627 [NAL9602] Loaded
2021-10-29T22:05:46.627Z,1635545146.627 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-10-29T22:05:46.658Z,1635545146.658 [Onboard] Loaded
2021-10-29T22:05:46.658Z,1635545146.658 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-10-29T22:05:46.660Z,1635545146.660 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408EB4E0
2021-10-29T22:05:46.660Z,1635545146.660 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7368
2021-10-29T22:05:46.673Z,1635545146.673 [Power24vConverter] Loaded
2021-10-29T22:05:46.673Z,1635545146.673 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-10-29T22:05:46.687Z,1635545146.687 [Radio_Surface] Loaded
2021-10-29T22:05:46.688Z,1635545146.688 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-10-29T22:05:46.689Z,1635545146.689 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091B4E0
2021-10-29T22:05:46.689Z,1635545146.689 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7369
2021-10-29T22:05:46.690Z,1635545146.690 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-10-29T22:05:46.691Z,1635545146.691 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-10-29T22:05:46.792Z,1635545146.792 [DepthRateCalculator] Loaded
2021-10-29T22:05:46.792Z,1635545146.792 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-10-29T22:05:46.836Z,1635545146.836 [PitchRateCalculator] Loaded
2021-10-29T22:05:46.836Z,1635545146.836 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-10-29T22:05:46.846Z,1635545146.846 [SpeedCalculator] Loaded
2021-10-29T22:05:46.846Z,1635545146.846 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-10-29T22:05:46.861Z,1635545146.861 [TempGradientCalculator] Loaded
2021-10-29T22:05:46.861Z,1635545146.861 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-10-29T22:05:46.899Z,1635545146.899 [YawRateCalculator] Loaded
2021-10-29T22:05:46.899Z,1635545146.899 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-10-29T22:05:47.016Z,1635545147.016 [ElevatorOffsetCalculator] Loaded
2021-10-29T22:05:47.017Z,1635545147.017 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-10-29T22:05:47.019Z,1635545147.019 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-10-29T22:05:47.019Z,1635545147.019 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-10-29T22:05:47.712Z,1635545147.712 [CANONSampler] Loaded
2021-10-29T22:05:47.712Z,1635545147.712 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2021-10-29T22:05:47.772Z,1635545147.772 [CTD_Seabird] Loaded
2021-10-29T22:05:47.773Z,1635545147.773 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-10-29T22:05:47.774Z,1635545147.774 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0
2021-10-29T22:05:47.774Z,1635545147.774 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7370
2021-10-29T22:05:47.795Z,1635545147.795 [PAR_Licor] Loaded
2021-10-29T22:05:47.795Z,1635545147.795 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-10-29T22:05:47.829Z,1635545147.829 [WetLabsBB2FL] Loaded
2021-10-29T22:05:47.829Z,1635545147.829 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-10-29T22:05:47.830Z,1635545147.830 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A004E0
2021-10-29T22:05:47.830Z,1635545147.830 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7371
2021-10-29T22:05:47.831Z,1635545147.831 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-10-29T22:05:47.832Z,1635545147.832 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-10-29T22:05:47.875Z,1635545147.875 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-10-29T22:05:47.876Z,1635545147.876 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-10-29T22:05:48.260Z,1635545148.260 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-10-29T22:05:48.261Z,1635545148.261 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-10-29T22:05:48.437Z,1635545148.437 [SBIT](DEBUG): Construct Startup Built In Test.
2021-10-29T22:05:48.447Z,1635545148.447 [SBIT] Loaded
2021-10-29T22:05:48.447Z,1635545148.447 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-10-29T22:05:48.450Z,1635545148.450 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-10-29T22:05:48.464Z,1635545148.464 [IBIT] Loaded
2021-10-29T22:05:48.464Z,1635545148.464 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-10-29T22:05:48.470Z,1635545148.470 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-10-29T22:05:48.575Z,1635545148.575 [CBIT] Loaded
2021-10-29T22:05:48.575Z,1635545148.575 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-10-29T22:05:48.576Z,1635545148.576 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-10-29T22:05:48.577Z,1635545148.577 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-10-29T22:05:48.697Z,1635545148.697 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-10-29T22:05:48.705Z,1635545148.705 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-10-29T22:05:48.708Z,1635545148.708 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-10-29T22:05:48.719Z,1635545148.719 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-10-29T22:05:48.720Z,1635545148.720 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B344E0
2021-10-29T22:05:48.721Z,1635545148.721 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7372
2021-10-29T22:05:48.727Z,1635545148.727 [Supervisor](INFO): Main Thread ID is 7277
2021-10-29T22:05:48.727Z,1635545148.727 [Supervisor](DEBUG): Running supervisor.
2021-10-29T22:05:48.728Z,1635545148.728 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7373
2021-10-29T22:05:48.728Z,1635545148.728 [CommandExec](INFO): Initializing the command executive.
2021-10-29T22:05:48.730Z,1635545148.730 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7374
2021-10-29T22:05:48.732Z,1635545148.732 [controlThread ThreadHandler](INFO): Handler Thread ID is 7375
2021-10-29T22:05:48.732Z,1635545148.732 [controlThread](DEBUG): Initializing ControlThread
2021-10-29T22:05:48.733Z,1635545148.733 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-10-29T22:05:48.735Z,1635545148.735 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-10-29T22:05:48.736Z,1635545148.736 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-10-29T22:05:48.736Z,1635545148.736 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-10-29T22:05:48.739Z,1635545148.739 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-10-29T22:05:48.739Z,1635545148.739 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-10-29T22:05:48.743Z,1635545148.743 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-10-29T22:05:48.744Z,1635545148.744 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-10-29T22:05:48.744Z,1635545148.744 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-10-29T22:05:48.744Z,1635545148.744 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-10-29T22:05:48.745Z,1635545148.745 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-10-29T22:05:48.745Z,1635545148.745 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-10-29T22:05:48.750Z,1635545148.750 [SBIT](INFO): Initialize SBIT Component.
2021-10-29T22:05:48.750Z,1635545148.750 [SBIT](IMPORTANT): git: 2021-09-30a
2021-10-29T22:05:48.751Z,1635545148.751 [SBIT](INFO): git hash: 921004aa1e1e6e697b3aa6ab2c76f23053d013df
2021-10-29T22:05:48.751Z,1635545148.751 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-10-29T22:05:48.752Z,1635545148.752 [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
2021-10-29T22:05:48.753Z,1635545148.753 [SBIT](INFO): Beginning SBIT in 89.000000 seconds.
2021-10-29T22:05:48.754Z,1635545148.754 [IBIT](INFO): Initialize IBIT Component.
2021-10-29T22:05:48.755Z,1635545148.755 [CBIT](DEBUG): Initialize CBIT Component.
2021-10-29T22:05:48.756Z,1635545148.756 [logger ThreadHandler](INFO): Handler Thread ID is 7376
2021-10-29T22:05:48.766Z,1635545148.766 [CBIT](DEBUG): Initialized mux pins.
2021-10-29T22:05:48.766Z,1635545148.766 [CBIT](DEBUG): Initializing the watchdog timer.
2021-10-29T22:05:48.774Z,1635545148.774 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7377
2021-10-29T22:05:48.775Z,1635545148.775 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-10-29T22:05:48.786Z,1635545148.786 [Onboard ThreadHandler](INFO): Handler Thread ID is 7378
2021-10-29T22:05:48.790Z,1635545148.790 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-10-29T22:05:48.791Z,1635545148.791 [CBIT](DEBUG): Initializing heartbeat.
2021-10-29T22:05:48.807Z,1635545148.807 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7379
2021-10-29T22:05:48.818Z,1635545148.818 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7380
2021-10-29T22:05:48.819Z,1635545148.819 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-10-29T22:05:48.822Z,1635545148.822 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7382
2021-10-29T22:05:48.826Z,1635545148.826 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7384
2021-10-29T22:05:48.827Z,1635545148.827 [WetLabsBB2FL](INFO): Powering up
2021-10-29T22:05:48.836Z,1635545148.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-10-29T22:05:48.836Z,1635545148.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-10-29T22:05:48.836Z,1635545148.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-10-29T22:05:48.836Z,1635545148.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-10-29T22:05:48.837Z,1635545148.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-10-29T22:05:48.837Z,1635545148.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-10-29T22:05:48.837Z,1635545148.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-10-29T22:05:48.838Z,1635545148.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-10-29T22:05:48.838Z,1635545148.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-10-29T22:05:48.838Z,1635545148.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-10-29T22:05:48.838Z,1635545148.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-10-29T22:05:48.838Z,1635545148.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-10-29T22:05:48.839Z,1635545148.839 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-10-29T22:05:48.839Z,1635545148.839 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-10-29T22:05:48.839Z,1635545148.839 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-10-29T22:05:48.839Z,1635545148.839 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-10-29T22:05:48.861Z,1635545148.861 [CBIT](DEBUG): Deactivating GF circuits.
2021-10-29T22:05:48.862Z,1635545148.862 [CBIT](DEBUG): Deactivating emergency mode.
2021-10-29T22:05:48.897Z,1635545148.897 [CBIT](DEBUG): Backplane powered.
2021-10-29T22:05:48.898Z,1635545148.898 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-10-29T22:05:48.925Z,1635545148.925 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-10-29T22:05:48.965Z,1635545148.965 [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>


2021-10-29T22:05:48.966Z,1635545148.966 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-10-29T22:05:49.040Z,1635545149.040 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-10-29T22:05:49.041Z,1635545149.041 [Default:A.Wait](DEBUG): Construct Wait.
2021-10-29T22:05:49.068Z,1635545149.068 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-10-29T22:05:49.104Z,1635545149.104 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-10-29T22:05:49.106Z,1635545149.106 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-10-29T22:05:49.128Z,1635545149.128 [Default:E.Execute](DEBUG): Construct Execute.
2021-10-29T22:05:49.140Z,1635545149.140 [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>


2021-10-29T22:05:49.145Z,1635545149.145 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-10-29T22:05:49.166Z,1635545149.166 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-10-29T22:05:49.210Z,1635545149.210 [Radio_Surface](INFO): Powering up
2021-10-29T22:05:49.287Z,1635545149.287 [Power24vConverter](INFO): Powering up.
2021-10-29T22:05:49.287Z,1635545149.287 [CANONSampler](INFO): Powering down
2021-10-29T22:05:49.320Z,1635545149.320 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-10-29T22:05:49.326Z,1635545149.326 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-10-29T22:05:49.327Z,1635545149.327 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-10-29T22:05:49.334Z,1635545149.334 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-10-29T22:05:49.335Z,1635545149.335 [MassServo](DEBUG): Initializing EZServoServo.
2021-10-29T22:05:49.342Z,1635545149.342 [MassServo](DEBUG): Initializing MassServo.
2021-10-29T22:05:49.343Z,1635545149.343 [RudderServo](DEBUG): Initializing EZServoServo.
2021-10-29T22:05:49.350Z,1635545149.350 [RudderServo](DEBUG): Initializing RudderServo.
2021-10-29T22:05:49.351Z,1635545149.351 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-10-29T22:05:49.358Z,1635545149.358 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-10-29T22:05:50.286Z,1635545150.286 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-10-29T22:05:50.286Z,1635545150.286 [RudderServo](FAULT): Rudder failed to initialize
2021-10-29T22:05:50.286Z,1635545150.286 [RudderServo] Communications Fault, FailCount= 1
2021-10-29T22:05:50.286Z,1635545150.286 [RudderServo](ERROR): Communications Fault
2021-10-29T22:05:50.386Z,1635545150.386 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-10-29T22:05:50.559Z,1635545150.559 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-10-29T22:05:50.559Z,1635545150.559 [RudderServo](INFO): Powering down
2021-10-29T22:05:50.934Z,1635545150.934 [WetLabsBB2FL](INFO): Powering down
2021-10-29T22:05:51.222Z,1635545151.222 [RudderServo](DEBUG): Initializing EZServoServo.
2021-10-29T22:05:51.343Z,1635545151.343 [RudderServo](DEBUG): Initializing RudderServo.
2021-10-29T22:05:51.347Z,1635545151.347 [CBIT](INFO): Clearing failed state for component RudderServo
2021-10-29T22:05:51.348Z,1635545151.348 [RudderServo] No Fault, FailCount= 1
2021-10-29T22:06:01.693Z,1635545161.693 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004647
2021-10-29T22:06:16.659Z,1635545176.659 [NAL9602](INFO): Powering up NAL9602
2021-10-29T22:06:27.567Z,1635545187.567 [NAL9602](INFO): NAL9602 initialized
2021-10-29T22:06:55.065Z,1635545215.065 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:07:06.032Z,1635545226.032 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:07:16.942Z,1635545236.942 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:07:18.535Z,1635545238.535 [SBIT](IMPORTANT): Beginning Startup BIT
2021-10-29T22:07:18.539Z,1635545238.539 [CBIT](IMPORTANT): Beginning ground fault scan
2021-10-29T22:07:28.251Z,1635545248.251 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:07:29.450Z,1635545249.450 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.011698
CHAN A1 (24V): -0.180176
CHAN A2 (12V): -0.002771
CHAN A3 (5V): -0.001495
CHAN B0 (3.3V): 0.000276
CHAN B1 (3.15aV): 0.000170
CHAN B2 (3.15bV): -0.000329
CHAN B3 (GND): 0.000053
OPEN: -0.000357
Full Scale: +/- 1 mA
2021-10-29T22:07:39.168Z,1635545259.168 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:07:50.068Z,1635545270.068 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:08:01.384Z,1635545281.384 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:08:12.288Z,1635545292.288 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:08:12.662Z,1635545292.662 [SBIT](IMPORTANT): SBIT PASSED
2021-10-29T22:08:12.662Z,1635545292.662 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-10-29T22:08:12.663Z,1635545292.663 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2021-10-29T22:08:12.663Z,1635545292.663 [SBIT](IMPORTANT): BuoyancyServo.fastPumpDepth=6 meter;

2021-10-29T22:08:12.663Z,1635545292.663 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2021-10-29T22:08:12.663Z,1635545292.663 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-10-29T22:08:12.663Z,1635545292.663 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2021-10-29T22:08:12.663Z,1635545292.663 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2021-10-29T22:08:12.664Z,1635545292.664 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2021-10-29T22:08:12.664Z,1635545292.664 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=274.642113 cubic_centimeter;

2021-10-29T22:08:12.664Z,1635545292.664 [SBIT](IMPORTANT): VerticalControl.kdDepthBuoy=1000 second;

2021-10-29T22:08:12.664Z,1635545292.664 [SBIT](IMPORTANT): VerticalControl.kiDepthBuoy=0.15 reciprocal_second;

2021-10-29T22:08:12.664Z,1635545292.664 [SBIT](IMPORTANT): VerticalControl.kiDepthOff=0.01 meter_per_second;

2021-10-29T22:08:12.664Z,1635545292.664 [SBIT](IMPORTANT): VerticalControl.kpDepthBuoy=120 ratio;

2021-10-29T22:08:12.664Z,1635545292.664 [SBIT](IMPORTANT): VerticalControl.massDefault=-17.281281 millimeter;

2021-10-29T22:08:13.051Z,1635545293.051 [MissionManager](IMPORTANT): Started mission Startup
2021-10-29T22:08:13.052Z,1635545293.052 [Startup] Running Loop=1
2021-10-29T22:08:13.052Z,1635545293.052 [Startup](DEBUG): Aggregate::initialize Startup
2021-10-29T22:08:13.052Z,1635545293.052 [Startup:A.GoToSurface] Running Loop=1
2021-10-29T22:08:13.052Z,1635545293.052 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-10-29T22:08:13.053Z,1635545293.053 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-10-29T22:08:13.053Z,1635545293.053 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-10-29T22:08:13.073Z,1635545293.073 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-10-29T22:08:13.074Z,1635545293.074 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-10-29T22:08:13.074Z,1635545293.074 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-10-29T22:08:13.076Z,1635545293.076 [Startup:StartupSatComms] Running Loop=1
2021-10-29T22:08:13.076Z,1635545293.076 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-10-29T22:08:13.076Z,1635545293.076 [Startup:StartupSatComms:A] Running Loop=1
2021-10-29T22:08:13.440Z,1635545293.440 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-10-29T22:08:23.204Z,1635545303.204 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:08:34.108Z,1635545314.108 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:08:45.016Z,1635545325.016 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:08:48.999Z,1635545328.999 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-10-29T22:08:48.999Z,1635545328.999 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:08:49.011Z,1635545329.011 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:08:49.415Z,1635545329.415 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:08:49.415Z,1635545329.415 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-10-29T22:08:55.958Z,1635545335.958 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:09:07.233Z,1635545347.233 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:09:13.269Z,1635545353.269 [Startup:StartupSatComms:A](INFO): Timed out from 2021-10-29T22:08:13.1Z
2021-10-29T22:09:13.269Z,1635545353.269 [Startup:StartupSatComms:A] Stopped
2021-10-29T22:09:13.269Z,1635545353.269 [Startup:StartupSatComms:B] Running Loop=1
2021-10-29T22:09:13.666Z,1635545353.666 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-10-29T22:09:17.861Z,1635545357.861 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:09:20.153Z,1635545360.153 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20211008T212822/Courier0060.lzma
2021-10-29T22:09:21.156Z,1635545361.156 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Courier0060.lzma.bak
2021-10-29T22:09:21.156Z,1635545361.156 [DataOverHttps](INFO): SBD MOMSN=16171843
2021-10-29T22:09:25.529Z,1635545365.529 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:09:28.719Z,1635545368.719 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:09:37.037Z,1635545377.037 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20211029T220537/Courier0000.lzma
2021-10-29T22:09:38.040Z,1635545378.040 [DataOverHttps](INFO): Moved sent file to Logs/20211029T220537/Courier0000.lzma.bak
2021-10-29T22:09:38.040Z,1635545378.040 [DataOverHttps](INFO): SBD MOMSN=16171848
2021-10-29T22:09:39.383Z,1635545379.383 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:09:50.190Z,1635545390.190 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:09:53.689Z,1635545393.689 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20211008T212822/Express0061.lzma
2021-10-29T22:09:54.691Z,1635545394.691 [DataOverHttps](INFO): Moved sent file to Logs/20211008T212822/Express0061.lzma.bak
2021-10-29T22:09:54.692Z,1635545394.692 [DataOverHttps](INFO): SBD MOMSN=16171851
2021-10-29T22:10:01.036Z,1635545401.036 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:10.358Z,1635545410.358 [DataOverHttps](INFO): Sending 880 bytes from file Logs/20211029T220537/Express0001.lzma
2021-10-29T22:10:11.360Z,1635545411.360 [DataOverHttps](INFO): Moved sent file to Logs/20211029T220537/Express0001.lzma.bak
2021-10-29T22:10:11.360Z,1635545411.360 [DataOverHttps](INFO): SBD MOMSN=16171856
2021-10-29T22:10:12.351Z,1635545412.351 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:12.696Z,1635545412.696 [Startup:StartupSatComms:B] Stopped
2021-10-29T22:10:12.697Z,1635545412.697 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-10-29T22:10:12.697Z,1635545412.697 [Startup:StartupSatComms] Stopped
2021-10-29T22:10:12.697Z,1635545412.697 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-10-29T22:10:12.698Z,1635545412.698 [Startup](INFO): Completed Startup
2021-10-29T22:10:12.698Z,1635545412.698 [MissionManager](INFO): Startup is completed.
2021-10-29T22:10:12.698Z,1635545412.698 [MissionManager](INFO): Uninitializing Mission Startup
2021-10-29T22:10:12.698Z,1635545412.698 [Startup] Stopped
2021-10-29T22:10:12.699Z,1635545412.699 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-10-29T22:10:12.699Z,1635545412.699 [Startup:A.GoToSurface] Stopped
2021-10-29T22:10:12.699Z,1635545412.699 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-10-29T22:10:13.100Z,1635545413.100 [MissionManager](IMPORTANT): Started mission Default
2021-10-29T22:10:13.101Z,1635545413.101 [Default] Running Loop=1
2021-10-29T22:10:13.101Z,1635545413.101 [Default](DEBUG): Aggregate::initialize Default
2021-10-29T22:10:13.101Z,1635545413.101 [Default:B.GoToSurface] Running Loop=1
2021-10-29T22:10:13.101Z,1635545413.101 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-10-29T22:10:13.101Z,1635545413.101 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-10-29T22:10:13.102Z,1635545413.102 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-10-29T22:10:13.102Z,1635545413.102 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-10-29T22:10:13.102Z,1635545413.102 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-10-29T22:10:13.103Z,1635545413.103 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-10-29T22:10:13.103Z,1635545413.103 [Default:A.Wait] Running Loop=1
2021-10-29T22:10:13.103Z,1635545413.103 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-10-29T22:10:18.759Z,1635545418.759 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-10-29T22:10:18.761Z,1635545418.761 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2021-10-29T22:10:21.209Z,1635545421.209 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:23.613Z,1635545423.613 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:26.036Z,1635545426.036 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:26.489Z,1635545426.489 [Default:A.Wait](INFO): Done Waiting.
2021-10-29T22:10:26.489Z,1635545426.489 [Default:A.Wait] Stopped
2021-10-29T22:10:26.489Z,1635545426.489 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-10-29T22:10:26.900Z,1635545426.900 [Default:CheckIn] Running Loop=1
2021-10-29T22:10:26.900Z,1635545426.900 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-10-29T22:10:26.900Z,1635545426.900 [Default:CheckIn:Read_GPS] Running Loop=1
2021-10-29T22:10:27.281Z,1635545427.281 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-10-29T22:10:28.460Z,1635545428.460 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:30.884Z,1635545430.884 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:33.778Z,1635545433.778 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:36.137Z,1635545436.137 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:38.559Z,1635545438.559 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:41.388Z,1635545441.388 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:43.811Z,1635545443.811 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:45.155Z,1635545445.155 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:46.644Z,1635545446.644 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:49.467Z,1635545449.467 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:52.296Z,1635545452.296 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:54.721Z,1635545454.721 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:55.596Z,1635545455.596 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:57.144Z,1635545457.144 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:10:59.971Z,1635545459.971 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:02.396Z,1635545462.396 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:04.819Z,1635545464.819 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:06.105Z,1635545466.105 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:07.244Z,1635545467.244 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:09.673Z,1635545469.673 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:12.092Z,1635545472.092 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:14.932Z,1635545474.932 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:17.050Z,1635545477.050 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:17.759Z,1635545477.759 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:20.171Z,1635545480.171 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:22.596Z,1635545482.596 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:25.019Z,1635545485.019 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:27.918Z,1635545487.918 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:27.923Z,1635545487.923 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:30.271Z,1635545490.271 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:31.095Z,1635545491.095 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-10-29T22:11:32.696Z,1635545492.696 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:35.523Z,1635545495.523 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:37.948Z,1635545497.948 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:38.823Z,1635545498.823 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:40.371Z,1635545500.371 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:43.201Z,1635545503.201 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:45.623Z,1635545505.623 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:48.456Z,1635545508.456 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:49.734Z,1635545509.734 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:50.103Z,1635545510.103 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-10-29T22:11:50.103Z,1635545510.103 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:11:50.115Z,1635545510.115 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:11:50.507Z,1635545510.507 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:11:50.507Z,1635545510.507 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-10-29T22:11:51.280Z,1635545511.280 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:54.115Z,1635545514.115 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:56.535Z,1635545516.535 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:11:58.956Z,1635545518.956 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:00.226Z,1635545520.226 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:01.381Z,1635545521.381 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:03.804Z,1635545523.804 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:06.227Z,1635545526.227 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:09.056Z,1635545529.056 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:11.152Z,1635545531.152 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:11.494Z,1635545531.494 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:13.904Z,1635545533.904 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:16.327Z,1635545536.327 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:18.752Z,1635545538.752 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:21.175Z,1635545541.175 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:22.053Z,1635545542.053 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:24.004Z,1635545544.004 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:26.427Z,1635545546.427 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:29.267Z,1635545549.267 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:31.681Z,1635545551.681 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:32.554Z,1635545552.554 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:34.509Z,1635545554.509 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:37.335Z,1635545557.335 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:43.464Z,1635545563.464 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:12:54.376Z,1635545574.376 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:13:05.689Z,1635545585.689 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:14:51.093Z,1635545691.093 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-10-29T22:14:51.093Z,1635545691.093 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:14:51.145Z,1635545691.145 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:14:51.543Z,1635545691.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:14:51.543Z,1635545691.543 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-10-29T22:15:03.987Z,1635545703.987 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:07.220Z,1635545707.220 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:10.451Z,1635545710.451 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:14.092Z,1635545714.092 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:17.322Z,1635545717.322 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:20.552Z,1635545720.552 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:21.450Z,1635545721.450 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:23.783Z,1635545723.783 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:27.017Z,1635545727.017 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:27.028Z,1635545727.028 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-10-29T22:10:26.9Z
2021-10-29T22:15:27.028Z,1635545727.028 [Default:CheckIn:Read_GPS] Stopped
2021-10-29T22:15:27.028Z,1635545727.028 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-10-29T22:15:27.478Z,1635545727.478 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-10-29T22:15:30.247Z,1635545730.247 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:33.480Z,1635545733.480 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:34.325Z,1635545734.325 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211029T220537/Courier0004.lzma
2021-10-29T22:15:35.328Z,1635545735.328 [DataOverHttps](INFO): Moved sent file to Logs/20211029T220537/Courier0004.lzma.bak
2021-10-29T22:15:35.328Z,1635545735.328 [DataOverHttps](INFO): SBD MOMSN=16171951
2021-10-29T22:15:36.711Z,1635545736.711 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:40.031Z,1635545740.031 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:43.175Z,1635545743.175 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:46.408Z,1635545746.408 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:49.639Z,1635545749.639 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:50.068Z,1635545750.068 [Power24vConverter](INFO): Powering down.
2021-10-29T22:15:51.017Z,1635545751.017 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20211029T220537/Express0005.lzma
2021-10-29T22:15:52.019Z,1635545752.019 [DataOverHttps](INFO): Moved sent file to Logs/20211029T220537/Express0005.lzma.bak
2021-10-29T22:15:52.020Z,1635545752.020 [DataOverHttps](INFO): SBD MOMSN=16171953
2021-10-29T22:15:52.936Z,1635545752.936 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:53.724Z,1635545753.724 [Default:CheckIn:Read_Iridium] Stopped
2021-10-29T22:15:53.724Z,1635545753.724 [Default:CheckIn:C.Wait] Running Loop=1
2021-10-29T22:15:53.724Z,1635545753.724 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-10-29T22:15:56.108Z,1635545756.108 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:15:59.343Z,1635545759.343 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:02.567Z,1635545762.567 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:05.811Z,1635545765.811 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:09.038Z,1635545769.038 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:12.267Z,1635545772.267 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:15.500Z,1635545775.500 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:18.747Z,1635545778.747 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:22.363Z,1635545782.363 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:25.596Z,1635545785.596 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:28.827Z,1635545788.827 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:29.247Z,1635545789.247 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-10-29T22:16:29.247Z,1635545789.247 [NAL9602] Data Fault, FailCount= 1
2021-10-29T22:16:29.247Z,1635545789.247 [NAL9602](ERROR): Data Fault
2021-10-29T22:16:29.298Z,1635545789.298 [CBIT](ERROR): Data Fault in component: NAL9602
2021-10-29T22:16:29.651Z,1635545789.651 [NAL9602](INFO): Powering down
2021-10-29T22:16:30.502Z,1635545790.502 [CBIT](INFO): Clearing failed state for component NAL9602
2021-10-29T22:16:30.502Z,1635545790.502 [NAL9602] No Fault, FailCount= 1
2021-10-29T22:16:32.059Z,1635545792.059 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:35.695Z,1635545795.695 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:38.928Z,1635545798.928 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:42.166Z,1635545802.166 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:45.394Z,1635545805.394 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:48.623Z,1635545808.623 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:51.856Z,1635545811.856 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:55.144Z,1635545815.144 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:58.725Z,1635545818.725 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:16:59.936Z,1635545819.936 [NAL9602](INFO): Powering up NAL9602
2021-10-29T22:17:02.039Z,1635545822.039 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:04.985Z,1635545824.985 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:07.876Z,1635545827.876 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:10.640Z,1635545830.640 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:11.059Z,1635545831.059 [NAL9602](INFO): NAL9602 initialized
2021-10-29T22:17:13.478Z,1635545833.478 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:16.700Z,1635545836.700 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:19.124Z,1635545839.124 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:21.952Z,1635545841.952 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:24.780Z,1635545844.780 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:27.609Z,1635545847.609 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:38.180Z,1635545858.180 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:49.497Z,1635545869.497 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:17:52.291Z,1635545872.291 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-10-29T22:17:52.291Z,1635545872.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:17:52.302Z,1635545872.302 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:17:52.695Z,1635545872.695 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:17:52.695Z,1635545872.695 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-10-29T22:18:00.403Z,1635545880.403 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:18:06.053Z,1635545886.053 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-10-29T22:18:11.314Z,1635545891.314 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:18:21.812Z,1635545901.812 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:18:32.784Z,1635545912.784 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:18:43.628Z,1635545923.628 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:18:54.540Z,1635545934.540 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:19:05.447Z,1635545945.447 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:19:16.358Z,1635545956.358 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:19:27.294Z,1635545967.294 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:19:38.172Z,1635545978.172 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:19:49.078Z,1635545989.078 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:19:56.332Z,1635545996.332 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-10-29T22:20:53.291Z,1635546053.291 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-10-29T22:20:53.291Z,1635546053.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:20:53.302Z,1635546053.302 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:20:53.692Z,1635546053.692 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:20:53.692Z,1635546053.692 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-10-29T22:20:54.472Z,1635546054.472 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-10-29T22:20:54.472Z,1635546054.472 [Default:CheckIn:C.Wait] Stopped
2021-10-29T22:20:54.472Z,1635546054.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-10-29T22:20:54.472Z,1635546054.472 [Default:CheckIn:D] Running Loop=1
2021-10-29T22:20:54.875Z,1635546054.875 [Default:CheckIn:D] Stopped
2021-10-29T22:20:54.875Z,1635546054.875 [Default:CheckIn:E] Running Loop=1
2021-10-29T22:20:55.290Z,1635546055.290 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.696236 min 
2021-10-29T22:20:55.290Z,1635546055.290 [Default:CheckIn:E] Stopped
2021-10-29T22:20:55.290Z,1635546055.290 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-10-29T22:20:55.290Z,1635546055.290 [Default:CheckIn] Stopped
2021-10-29T22:20:55.290Z,1635546055.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-10-29T22:20:55.290Z,1635546055.290 [Default:CheckIn](INFO): Running loop #2
2021-10-29T22:20:55.290Z,1635546055.290 [Default:CheckIn] Running Loop=2
2021-10-29T22:20:55.290Z,1635546055.290 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-10-29T22:20:55.291Z,1635546055.291 [Default:CheckIn:Read_GPS] Running Loop=1
2021-10-29T22:23:54.243Z,1635546234.243 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-10-29T22:23:54.243Z,1635546234.243 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:23:54.254Z,1635546234.254 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:23:54.661Z,1635546234.661 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:23:54.661Z,1635546234.661 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-10-29T22:24:27.360Z,1635546267.360 [BPC1](ERROR): Failed to receive data from all battery packs.
2021-10-29T22:24:27.360Z,1635546267.360 [BPC1] Communications Fault, FailCount= 1
2021-10-29T22:24:27.360Z,1635546267.360 [BPC1](ERROR): Communications Fault
2021-10-29T22:24:27.381Z,1635546267.381 [CBIT](ERROR): Communications Fault in component: BPC1
2021-10-29T22:25:55.475Z,1635546355.475 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-10-29T22:20:55.3Z
2021-10-29T22:25:55.475Z,1635546355.475 [Default:CheckIn:Read_GPS] Stopped
2021-10-29T22:25:55.475Z,1635546355.475 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-10-29T22:26:02.745Z,1635546362.745 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211029T220537/Courier0007.lzma
2021-10-29T22:26:03.747Z,1635546363.747 [DataOverHttps](INFO): Moved sent file to Logs/20211029T220537/Courier0007.lzma.bak
2021-10-29T22:26:03.747Z,1635546363.747 [DataOverHttps](INFO): SBD MOMSN=16172040
2021-10-29T22:26:19.233Z,1635546379.233 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20211029T220537/Express0008.lzma
2021-10-29T22:26:20.235Z,1635546380.235 [DataOverHttps](INFO): Moved sent file to Logs/20211029T220537/Express0008.lzma.bak
2021-10-29T22:26:20.235Z,1635546380.235 [DataOverHttps](INFO): SBD MOMSN=16172045
2021-10-29T22:26:21.711Z,1635546381.711 [Default:CheckIn:Read_Iridium] Stopped
2021-10-29T22:26:21.712Z,1635546381.712 [Default:CheckIn:C.Wait] Running Loop=1
2021-10-29T22:26:21.712Z,1635546381.712 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-10-29T22:26:55.298Z,1635546415.298 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-10-29T22:26:55.298Z,1635546415.298 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:26:55.309Z,1635546415.309 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:26:55.711Z,1635546415.711 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:26:55.712Z,1635546415.712 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-10-29T22:27:14.627Z,1635546434.627 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-10-29T22:27:14.627Z,1635546434.627 [NAL9602] Data Fault, FailCount= 2
2021-10-29T22:27:14.628Z,1635546434.628 [NAL9602](ERROR): Data Fault
2021-10-29T22:27:14.681Z,1635546434.681 [CBIT](ERROR): Data Fault in component: NAL9602
2021-10-29T22:27:15.044Z,1635546435.044 [NAL9602](INFO): Powering down
2021-10-29T22:27:15.862Z,1635546435.862 [CBIT](INFO): Clearing failed state for component NAL9602
2021-10-29T22:27:15.862Z,1635546435.862 [NAL9602] No Fault, FailCount= 2
2021-10-29T22:27:45.327Z,1635546465.327 [NAL9602](INFO): Powering up NAL9602
2021-10-29T22:27:56.235Z,1635546476.235 [NAL9602](INFO): NAL9602 initialized
2021-10-29T22:29:56.254Z,1635546596.254 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-10-29T22:29:56.254Z,1635546596.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:29:56.278Z,1635546596.278 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:29:56.680Z,1635546596.680 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:29:56.680Z,1635546596.680 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-10-29T22:31:22.281Z,1635546682.281 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-10-29T22:31:22.281Z,1635546682.281 [Default:CheckIn:C.Wait] Stopped
2021-10-29T22:31:22.281Z,1635546682.281 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-10-29T22:31:22.282Z,1635546682.282 [Default:CheckIn:D] Running Loop=1
2021-10-29T22:31:22.691Z,1635546682.691 [Default:CheckIn:D] Stopped
2021-10-29T22:31:22.691Z,1635546682.691 [Default:CheckIn:E] Running Loop=1
2021-10-29T22:31:23.100Z,1635546683.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.159843 min 
2021-10-29T22:31:23.100Z,1635546683.100 [Default:CheckIn:E] Stopped
2021-10-29T22:31:23.101Z,1635546683.101 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-10-29T22:31:23.101Z,1635546683.101 [Default:CheckIn] Stopped
2021-10-29T22:31:23.101Z,1635546683.101 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-10-29T22:31:23.101Z,1635546683.101 [Default:CheckIn](INFO): Running loop #3
2021-10-29T22:31:23.101Z,1635546683.101 [Default:CheckIn] Running Loop=3
2021-10-29T22:31:23.101Z,1635546683.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-10-29T22:31:23.101Z,1635546683.101 [Default:CheckIn:Read_GPS] Running Loop=1
2021-10-29T22:32:57.239Z,1635546777.239 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-10-29T22:32:57.239Z,1635546777.239 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:32:57.250Z,1635546777.250 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:32:57.664Z,1635546777.664 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:32:57.664Z,1635546777.664 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-10-29T22:34:28.552Z,1635546868.552 [CBIT](INFO): Clearing failed state for component BPC1
2021-10-29T22:34:28.553Z,1635546868.553 [BPC1] No Fault, FailCount= 1
2021-10-29T22:35:58.219Z,1635546958.219 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-10-29T22:35:58.219Z,1635546958.219 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:35:58.262Z,1635546958.262 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:35:58.630Z,1635546958.630 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:35:58.630Z,1635546958.630 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-10-29T22:36:23.268Z,1635546983.268 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-10-29T22:31:23.1Z
2021-10-29T22:36:23.268Z,1635546983.268 [Default:CheckIn:Read_GPS] Stopped
2021-10-29T22:36:23.269Z,1635546983.269 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-10-29T22:36:30.461Z,1635546990.461 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20211029T220537/Courier0010.lzma
2021-10-29T22:36:31.464Z,1635546991.464 [DataOverHttps](INFO): Moved sent file to Logs/20211029T220537/Courier0010.lzma.bak
2021-10-29T22:36:31.464Z,1635546991.464 [DataOverHttps](INFO): SBD MOMSN=16172169
2021-10-29T22:36:47.185Z,1635547007.185 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20211029T220537/Express0011.lzma
2021-10-29T22:36:48.188Z,1635547008.188 [DataOverHttps](INFO): Moved sent file to Logs/20211029T220537/Express0011.lzma.bak
2021-10-29T22:36:48.188Z,1635547008.188 [DataOverHttps](INFO): SBD MOMSN=16172171
2021-10-29T22:36:49.525Z,1635547009.525 [Default:CheckIn:Read_Iridium] Stopped
2021-10-29T22:36:49.525Z,1635547009.525 [Default:CheckIn:C.Wait] Running Loop=1
2021-10-29T22:36:49.525Z,1635547009.525 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-10-29T22:37:57.795Z,1635547077.795 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-10-29T22:37:57.795Z,1635547077.795 [NAL9602] Data Fault, FailCount= 3
2021-10-29T22:37:57.795Z,1635547077.795 [NAL9602](ERROR): Data Fault
2021-10-29T22:37:57.834Z,1635547077.834 [CBIT](ERROR): Data Fault in component: NAL9602
2021-10-29T22:37:58.199Z,1635547078.199 [NAL9602](INFO): Powering down
2021-10-29T22:37:58.606Z,1635547078.606 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2021-10-29T22:37:58.606Z,1635547078.606 [NAL9602] Hardware Fault, FailCount= 3
2021-10-29T22:37:58.606Z,1635547078.606 [NAL9602](ERROR): Hardware Fault
2021-10-29T22:37:59.040Z,1635547079.040 [CBIT](INFO): Clearing failed state for component NAL9602
2021-10-29T22:37:59.041Z,1635547079.041 [NAL9602] No Fault, FailCount= 3
2021-10-29T22:38:28.504Z,1635547108.504 [NAL9602](INFO): Powering up NAL9602
2021-10-29T22:38:39.408Z,1635547119.408 [NAL9602](INFO): NAL9602 initialized
2021-10-29T22:38:59.211Z,1635547139.211 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-10-29T22:38:59.211Z,1635547139.211 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:38:59.250Z,1635547139.250 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:38:59.625Z,1635547139.625 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:38:59.626Z,1635547139.626 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-10-29T22:39:53.031Z,1635547193.031 [CommandExec](IMPORTANT): got command show variable prop
2021-10-29T22:39:53.036Z,1635547193.036 [CommandExec](IMPORTANT): platform_propeller_rotation_rate (radian_per_second)
2021-10-29T22:39:53.044Z,1635547193.044 [CommandExec](IMPORTANT): Config/Simulator.designPropEff (none)
2021-10-29T22:39:53.049Z,1635547193.049 [CommandExec](IMPORTANT): SpeedControl.propPitch (meter_per_radian)
2021-10-29T22:39:53.119Z,1635547193.119 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction (radian_per_second)
2021-10-29T22:39:53.121Z,1635547193.121 [CommandExec](IMPORTANT): ThrusterServo.platform_propeller_rotation_rate (radian_per_second)
2021-10-29T22:40:33.743Z,1635547233.743 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute
2021-10-29T22:40:33.746Z,1635547233.746 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2021-10-29T22:40:33.855Z,1635547233.855 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-10-29T22:40:43.351Z,1635547243.351 [CommandExec](IMPORTANT): got command maintain clear
2021-10-29T22:40:43.559Z,1635547243.559 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-10-29T22:41:08.070Z,1635547268.070 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2021-10-29T22:41:08.070Z,1635547268.070 [CommandExec](IMPORTANT): platform_buoyancy_position 954.325806 cc
2021-10-29T22:41:45.612Z,1635547305.612 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2021-10-29T22:41:45.615Z,1635547305.615 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2021-10-29T22:41:45.784Z,1635547305.784 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-10-29T22:41:50.170Z,1635547310.170 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-10-29T22:41:50.170Z,1635547310.170 [Default:CheckIn:C.Wait] Stopped
2021-10-29T22:41:50.170Z,1635547310.170 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-10-29T22:41:50.171Z,1635547310.171 [Default:CheckIn:D] Running Loop=1
2021-10-29T22:41:50.592Z,1635547310.592 [Default:CheckIn:D] Stopped
2021-10-29T22:41:50.592Z,1635547310.592 [Default:CheckIn:E] Running Loop=1
2021-10-29T22:41:51.001Z,1635547311.001 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.624847 min 
2021-10-29T22:41:51.001Z,1635547311.001 [Default:CheckIn:E] Stopped
2021-10-29T22:41:51.002Z,1635547311.002 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-10-29T22:41:51.002Z,1635547311.002 [Default:CheckIn] Stopped
2021-10-29T22:41:51.003Z,1635547311.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-10-29T22:41:51.003Z,1635547311.003 [Default:CheckIn](INFO): Running loop #4
2021-10-29T22:41:51.003Z,1635547311.003 [Default:CheckIn] Running Loop=4
2021-10-29T22:41:51.003Z,1635547311.003 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-10-29T22:41:51.003Z,1635547311.003 [Default:CheckIn:Read_GPS] Running Loop=1
2021-10-29T22:41:55.403Z,1635547315.403 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-10-29T22:41:55.419Z,1635547315.419 [BPC1](INFO): Received data from all battery sticks.
2021-10-29T22:42:00.275Z,1635547320.275 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2021-10-29T22:42:00.275Z,1635547320.275 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:42:00.334Z,1635547320.334 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:42:00.742Z,1635547320.742 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:42:00.742Z,1635547320.742 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2021-10-29T22:42:17.591Z,1635547337.591 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2021-10-29T22:42:17.592Z,1635547337.592 [CommandExec](IMPORTANT): platform_buoyancy_position 580.434509 cc
2021-10-29T22:42:28.522Z,1635547348.522 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2021-10-29T22:42:28.523Z,1635547348.523 [CommandExec](IMPORTANT): platform_buoyancy_position 436.599274 cc
2021-10-29T22:42:41.157Z,1635547361.157 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2021-10-29T22:42:41.158Z,1635547361.158 [CommandExec](IMPORTANT): platform_buoyancy_position 273.156128 cc
2021-10-29T22:42:48.709Z,1635547368.709 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2021-10-29T22:42:48.710Z,1635547368.710 [CommandExec](IMPORTANT): platform_buoyancy_position 208.692139 cc
2021-10-29T22:42:52.248Z,1635547372.248 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2021-10-29T22:42:52.249Z,1635547372.249 [CommandExec](IMPORTANT): platform_buoyancy_position 208.692139 cc
2021-10-29T22:42:57.322Z,1635547377.322 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2021-10-29T22:42:57.323Z,1635547377.323 [CommandExec](IMPORTANT): platform_buoyancy_position 208.692139 cc
2021-10-29T22:43:00.759Z,1635547380.759 [CommandExec](IMPORTANT): got command maintain clear
2021-10-29T22:43:00.904Z,1635547380.904 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-10-29T22:43:29.380Z,1635547409.380 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15.000000 
2021-10-29T22:43:29.380Z,1635547409.380 [CommandExec](FAULT): Incomplete syntax. Try: help maintain
2021-10-29T22:43:37.551Z,1635547417.551 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15.000000 degree
2021-10-29T22:43:37.565Z,1635547417.565 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2021-10-29T22:43:37.704Z,1635547417.704 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-10-29T22:43:55.751Z,1635547435.751 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15.000000 degree
2021-10-29T22:44:06.315Z,1635547446.315 [CommandExec](IMPORTANT): got command maintain clear
2021-10-29T22:44:06.377Z,1635547446.377 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-10-29T22:44:47.040Z,1635547487.040 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15.000000 degree
2021-10-29T22:44:47.053Z,1635547487.053 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2021-10-29T22:44:47.143Z,1635547487.143 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-10-29T22:45:01.278Z,1635547501.278 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2021-10-29T22:45:01.278Z,1635547501.278 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-29T22:45:01.304Z,1635547501.304 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-29T22:45:01.738Z,1635547501.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-29T22:45:01.738Z,1635547501.738 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2021-10-29T22:45:01.814Z,1635547501.814 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15.000000 degree
2021-10-29T22:45:11.999Z,1635547511.999 [CommandExec](IMPORTANT): got command maintain clear
2021-10-29T22:45:12.190Z,1635547512.190 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-10-29T22:45:22.671Z,1635547522.671 [CommandExec](IMPORTANT): got command show variable par
2021-10-29T22:45:22.732Z,1635547522.732 [CommandExec](IMPORTANT): PAR_Licor.loadControl (none)
2021-10-29T22:45:22.732Z,1635547522.732 [CommandExec](IMPORTANT): PAR_Licor.ad (none)
2021-10-29T22:45:22.732Z,1635547522.732 [CommandExec](IMPORTANT): PAR_Licor.adRes (bit)
2021-10-29T22:45:22.733Z,1635547522.733 [CommandExec](IMPORTANT): PAR_Licor.adTimeout (millisecond)
2021-10-29T22:45:22.733Z,1635547522.733 [CommandExec](IMPORTANT): PAR_Licor.adVref (volt)
2021-10-29T22:45:22.769Z,1635547522.769 [CommandExec](IMPORTANT): PAR_Licor.loadAtStartup (bool)
2021-10-29T22:45:22.770Z,1635547522.770 [CommandExec](IMPORTANT): PAR_Licor.simulateHardware (bool)
2021-10-29T22:45:22.770Z,1635547522.770 [CommandExec](IMPORTANT): PAR_Licor.adcCal (microampere_per_count)
2021-10-29T22:45:22.770Z,1635547522.770 [CommandExec](IMPORTANT): PAR_Licor.darkCount (count)
2021-10-29T22:45:22.771Z,1635547522.771 [CommandExec](IMPORTANT): PAR_Licor.maxBound (micromole_per_second_per_square_meter)
2021-10-29T22:45:22.771Z,1635547522.771 [CommandExec](IMPORTANT): PAR_Licor.maxValidPitch (degree)
2021-10-29T22:45:22.772Z,1635547522.772 [CommandExec](IMPORTANT): PAR_Licor.minBound (micromole_per_second_per_square_meter)
2021-10-29T22:45:22.772Z,1635547522.772 [CommandExec](IMPORTANT): PAR_Licor.minValidPitch (degree)
2021-10-29T22:45:22.772Z,1635547522.772 [CommandExec](IMPORTANT): PAR_Licor.multiplier (micromole_per_second_per_square_meter_per_microampere)
2021-10-29T22:45:22.773Z,1635547522.773 [CommandExec](IMPORTANT): PAR_Licor.parCal (none)
2021-10-29T22:45:22.773Z,1635547522.773 [CommandExec](IMPORTANT): PAR_Licor.serial (none)
2021-10-29T22:45:22.880Z,1635547522.880 [CommandExec](IMPORTANT): PAR_Licor.enableBroadcast (bool)
2021-10-29T22:45:22.880Z,1635547522.880 [CommandExec](IMPORTANT): PAR_Licor.component_voltage (volt)
2021-10-29T22:45:22.880Z,1635547522.880 [CommandExec](IMPORTANT): PAR_Licor.component_avgVoltage (volt)
2021-10-29T22:45:22.881Z,1635547522.881 [CommandExec](IMPORTANT): PAR_Licor.component_current (milliampere)
2021-10-29T22:45:22.881Z,1635547522.881 [CommandExec](IMPORTANT): PAR_Licor.component_avgCurrent (milliampere)
2021-10-29T22:45:22.882Z,1635547522.882 [CommandExec](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter)
2021-10-29T22:45:22.882Z,1635547522.882 [CommandExec](IMPORTANT): PAR_Licor.adcCount (count)
2021-10-29T22:45:42.653Z,1635547542.653 [CommandExec](IMPORTANT): got command get PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water
2021-10-29T22:45:42.653Z,1635547542.653 [CommandExec](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.267178 umol/s/m2
2021-10-29T22:46:07.308Z,1635547567.308 [CommandExec](IMPORTANT): got command report mod PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water
2021-10-29T22:46:07.547Z,1635547567.547 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.174934 umol/s/m2
2021-10-29T22:46:07.932Z,1635547567.932 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.163403 umol/s/m2
2021-10-29T22:46:08.344Z,1635547568.344 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.172050 umol/s/m2
2021-10-29T22:46:08.751Z,1635547568.751 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.146107 umol/s/m2
2021-10-29T22:46:09.174Z,1635547569.174 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.246998 umol/s/m2
2021-10-29T22:46:09.583Z,1635547569.583 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.186462 umol/s/m2
2021-10-29T22:46:09.997Z,1635547569.997 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.166286 umol/s/m2
2021-10-29T22:46:10.367Z,1635547570.367 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.252764 umol/s/m2
2021-10-29T22:46:10.776Z,1635547570.776 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.209524 umol/s/m2
2021-10-29T22:46:11.195Z,1635547571.195 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.226822 umol/s/m2
2021-10-29T22:46:11.000Z,1635547572.000 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.169168 umol/s/m2
2021-10-29T22:46:12.400Z,1635547572.400 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.218172 umol/s/m2
2021-10-29T22:46:12.815Z,1635547572.815 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.223938 umol/s/m2
2021-10-29T22:46:13.187Z,1635547573.187 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 17.947206 umol/s/m2
2021-10-29T22:46:13.599Z,1635547573.599 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 17.419687 umol/s/m2
2021-10-29T22:46:14.005Z,1635547574.005 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 16.549135 umol/s/m2
2021-10-29T22:46:14.400Z,1635547574.400 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.142415 umol/s/m2
2021-10-29T22:46:14.802Z,1635547574.802 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.037474 umol/s/m2
2021-10-29T22:46:15.252Z,1635547575.252 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.000000 umol/s/m2
2021-10-29T22:46:15.629Z,1635547575.629 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.049005 umol/s/m2
2021-10-29T22:46:16.047Z,1635547576.047 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.057652 umol/s/m2
2021-10-29T22:46:16.419Z,1635547576.419 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.077831 umol/s/m2
2021-10-29T22:46:16.832Z,1635547576.832 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.020178 umol/s/m2
2021-10-29T22:46:17.246Z,1635547577.246 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.034591 umol/s/m2
2021-10-29T22:46:17.628Z,1635547577.628 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.046122 umol/s/m2
2021-10-29T22:46:18.076Z,1635547578.076 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.034591 umol/s/m2
2021-10-29T22:46:18.460Z,1635547578.460 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.386811 umol/s/m2
2021-10-29T22:46:18.866Z,1635547578.866 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 16.808569 umol/s/m2
2021-10-29T22:46:19.263Z,1635547579.263 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 16.944054 umol/s/m2
2021-10-29T22:46:19.654Z,1635547579.654 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.102869 umol/s/m2
2021-10-29T22:46:20.065Z,1635547580.065 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.264296 umol/s/m2
2021-10-29T22:46:20.472Z,1635547580.472 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.301769 umol/s/m2
2021-10-29T22:46:20.893Z,1635547580.893 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.324831 umol/s/m2
2021-10-29T22:46:21.308Z,1635547581.308 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.232586 umol/s/m2
2021-10-29T22:46:21.695Z,1635547581.695 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.310415 umol/s/m2
2021-10-29T22:46:22.091Z,1635547582.091 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.293121 umol/s/m2
2021-10-29T22:46:22.886Z,1635547582.886 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.267178 umol/s/m2
2021-10-29T22:46:23.299Z,1635547583.299 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.290237 umol/s/m2
2021-10-29T22:46:23.707Z,1635547583.707 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.284473 umol/s/m2
2021-10-29T22:46:24.125Z,1635547584.125 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.298885 umol/s/m2
2021-10-29T22:46:24.540Z,1635547584.540 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.249882 umol/s/m2
2021-10-29T22:46:24.923Z,1635547584.923 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.290237 umol/s/m2
2021-10-29T22:46:25.320Z,1635547585.320 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.238350 umol/s/m2
2021-10-29T22:46:25.738Z,1635547585.738 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.278708 umol/s/m2
2021-10-29T22:46:26.120Z,1635547586.120 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.249882 umol/s/m2
2021-10-29T22:46:26.541Z,1635547586.541 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.264296 umol/s/m2
2021-10-29T22:46:26.950Z,1635547586.950 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.296003 umol/s/m2
2021-10-29T22:46:27.425Z,1635547587.425 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.321947 umol/s/m2
2021-10-29T22:46:27.763Z,1635547587.763 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.298885 umol/s/m2
2021-10-29T22:46:28.139Z,1635547588.139 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 18.307533 umol/s/m2
2021-10-29T22:46:28.339Z,1635547588.339 [CommandExec](IMPORTANT): got command report clear
2021-10-29T22:46:33.958Z,1635547593.958 [CommandExec](IMPORTANT): got command quit
2021-10-29T22:46:34.961Z,1635547594.961 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2021-10-29T22:46:34.962Z,1635547594.962 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:34.962Z,1635547594.962 [CommandExec](INFO): Uninitializing the command executive.
2021-10-29T22:46:34.962Z,1635547594.962 [CommandExec](INFO): Uninitializing the command scheduler.
2021-10-29T22:46:34.962Z,1635547594.962 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:35.141Z,1635547595.141 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-10-29T22:46:35.142Z,1635547595.142 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-10-29T22:46:35.142Z,1635547595.142 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:35.142Z,1635547595.142 [NavChartDb](INFO): Join timeout helper Thread ID is 7460
2021-10-29T22:46:35.534Z,1635547595.534 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:35.534Z,1635547595.534 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:35.553Z,1635547595.553 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-10-29T22:46:35.554Z,1635547595.554 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:35.554Z,1635547595.554 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7461
2021-10-29T22:46:36.054Z,1635547596.054 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:36.054Z,1635547596.054 [WetLabsBB2FL](INFO): Powering down
2021-10-29T22:46:36.055Z,1635547596.055 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:36.058Z,1635547596.058 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-10-29T22:46:36.058Z,1635547596.058 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:36.058Z,1635547596.058 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7462
2021-10-29T22:46:36.238Z,1635547596.238 [CTD_Seabird](INFO): Powering down
2021-10-29T22:46:36.254Z,1635547596.254 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:36.254Z,1635547596.254 [CTD_Seabird](INFO): Powering down
2021-10-29T22:46:36.266Z,1635547596.266 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:36.274Z,1635547596.274 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-10-29T22:46:36.274Z,1635547596.274 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:36.274Z,1635547596.274 [Radio_Surface](INFO): Join timeout helper Thread ID is 7463
2021-10-29T22:46:36.314Z,1635547596.314 [Radio_Surface](INFO): Powering down
2021-10-29T22:46:36.315Z,1635547596.315 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:36.315Z,1635547596.315 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:36.321Z,1635547596.321 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-10-29T22:46:36.322Z,1635547596.322 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:36.322Z,1635547596.322 [Onboard](INFO): Join timeout helper Thread ID is 7464
2021-10-29T22:46:37.390Z,1635547597.390 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-10-29T22:46:40.970Z,1635547600.970 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:40.970Z,1635547600.970 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:40.982Z,1635547600.982 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-10-29T22:46:40.983Z,1635547600.983 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:40.983Z,1635547600.983 [DataOverHttps](INFO): Join timeout helper Thread ID is 7465
2021-10-29T22:46:41.406Z,1635547601.406 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:41.406Z,1635547601.406 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:41.427Z,1635547601.427 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-10-29T22:46:41.427Z,1635547601.427 [logger ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:41.427Z,1635547601.427 [logger](INFO): Join timeout helper Thread ID is 7466
2021-10-29T22:46:41.462Z,1635547601.462 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:41.462Z,1635547601.462 [logger ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:41.466Z,1635547601.466 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-10-29T22:46:41.466Z,1635547601.466 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:41.467Z,1635547601.467 [CommandLine](INFO): Join timeout helper Thread ID is 7467
2021-10-29T22:46:41.542Z,1635547601.542 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:41.542Z,1635547601.542 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:41.546Z,1635547601.546 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2021-10-29T22:46:41.546Z,1635547601.546 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:41.547Z,1635547601.547 [CommandExec](INFO): Join timeout helper Thread ID is 7468
2021-10-29T22:46:41.548Z,1635547601.548 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-10-29T22:46:41.548Z,1635547601.548 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:41.548Z,1635547601.548 [controlThread](INFO): Join timeout helper Thread ID is 7469
2021-10-29T22:46:41.838Z,1635547601.838 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-29T22:46:41.838Z,1635547601.838 [controlThread](DEBUG): Uninitializing ControlThread
2021-10-29T22:46:41.838Z,1635547601.838 [AHRS_M2](INFO): Powering down
2021-10-29T22:46:41.910Z,1635547601.910 [NAL9602](INFO): Powering down
2021-10-29T22:46:41.913Z,1635547601.913 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-10-29T22:46:41.914Z,1635547601.914 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-10-29T22:46:41.915Z,1635547601.915 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-10-29T22:46:41.915Z,1635547601.915 [MissionManager](INFO): Uninitializing Mission Default
2021-10-29T22:46:41.915Z,1635547601.915 [Default] Stopped
2021-10-29T22:46:41.915Z,1635547601.915 [Default](DEBUG): Aggregate::uninitialize Default
2021-10-29T22:46:41.915Z,1635547601.915 [Default:B.GoToSurface] Stopped
2021-10-29T22:46:41.916Z,1635547601.916 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-10-29T22:46:41.916Z,1635547601.916 [Default:CheckIn] Stopped
2021-10-29T22:46:41.916Z,1635547601.916 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-10-29T22:46:41.916Z,1635547601.916 [Default:CheckIn:Read_GPS] Stopped
2021-10-29T22:46:41.919Z,1635547601.919 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-10-29T22:46:41.919Z,1635547601.919 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-10-29T22:46:41.920Z,1635547601.920 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-10-29T22:46:41.920Z,1635547601.920 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-10-29T22:46:41.920Z,1635547601.920 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-10-29T22:46:41.920Z,1635547601.920 [BuoyancyServo](INFO): Powering down
2021-10-29T22:46:41.934Z,1635547601.934 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-10-29T22:46:41.934Z,1635547601.934 [ElevatorServo](INFO): Powering down
2021-10-29T22:46:41.935Z,1635547601.935 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-10-29T22:46:41.935Z,1635547601.935 [MassServo](INFO): Powering down
2021-10-29T22:46:41.936Z,1635547601.936 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-10-29T22:46:41.936Z,1635547601.936 [RudderServo](INFO): Powering down
2021-10-29T22:46:41.937Z,1635547601.937 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-10-29T22:46:41.937Z,1635547601.937 [ThrusterServo](INFO): Powering down
2021-10-29T22:46:41.938Z,1635547601.938 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-10-29T22:46:41.938Z,1635547601.938 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-10-29T22:46:41.938Z,1635547601.938 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-10-29T22:46:41.939Z,1635547601.939 [CBIT](DEBUG): Powering off loads.
2021-10-29T22:46:41.949Z,1635547601.949 [CBIT](DEBUG): Disabling WDT.
2021-10-29T22:46:41.961Z,1635547601.961 [CBIT](DEBUG): Opening all GF detection circuits.
2021-10-29T22:46:41.962Z,1635547601.962 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:42.003Z,1635547602.003 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:42.007Z,1635547602.007 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:42.014Z,1635547602.014 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:42.089Z,1635547602.089 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:42.093Z,1635547602.093 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:42.134Z,1635547602.134 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-10-29T22:46:42.202Z,1635547602.202 [logger ThreadHandler](INFO): Thread cancelled.