2022-08-09T20:43:28.554Z,1660077808.554 [Supervisor](DEBUG): Initializing supervisor.
2022-08-09T20:43:28.559Z,1660077808.559 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-08-09T20:43:28.559Z,1660077808.559 [SyncHandler](INFO): Protected caller Thread ID is 831
2022-08-09T20:43:28.560Z,1660077808.560 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-08-09T20:43:28.560Z,1660077808.560 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-08-09T20:43:28.561Z,1660077808.561 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 832
2022-08-09T20:43:28.565Z,1660077808.565 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-08-09T20:43:28.582Z,1660077808.582 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-08-09T20:43:28.583Z,1660077808.583 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-08-09T20:43:28.583Z,1660077808.583 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 833
2022-08-09T20:43:28.587Z,1660077808.587 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-08-09T20:43:28.588Z,1660077808.588 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-08-09T20:43:28.589Z,1660077808.589 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 834
2022-08-09T20:43:28.591Z,1660077808.591 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-08-09T20:43:28.592Z,1660077808.592 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-08-09T20:43:28.592Z,1660077808.592 [logger ThreadHandler](INFO): Protected caller Thread ID is 835
2022-08-09T20:43:28.596Z,1660077808.596 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-08-09T20:43:28.596Z,1660077808.596 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-08-09T20:43:28.628Z,1660077808.628 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-08-09T20:43:28.935Z,1660077808.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-08-09T20:43:28.937Z,1660077808.937 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-08-09T20:43:29.552Z,1660077809.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-08-09T20:43:29.553Z,1660077809.553 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-08-09T20:43:29.659Z,1660077809.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-08-09T20:43:29.661Z,1660077809.661 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-08-09T20:43:30.128Z,1660077810.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-08-09T20:43:30.130Z,1660077810.130 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-08-09T20:43:30.227Z,1660077810.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-08-09T20:43:30.229Z,1660077810.229 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-08-09T20:43:30.319Z,1660077810.319 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-08-09T20:43:30.678Z,1660077810.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-08-09T20:43:30.679Z,1660077810.679 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-08-09T20:43:30.894Z,1660077810.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-08-09T20:43:30.895Z,1660077810.895 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-08-09T20:43:31.030Z,1660077811.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-08-09T20:43:31.031Z,1660077811.030 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-08-09T20:43:31.254Z,1660077811.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-08-09T20:43:31.256Z,1660077811.256 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-08-09T20:43:31.732Z,1660077811.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-08-09T20:43:31.734Z,1660077811.734 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-08-09T20:43:32.144Z,1660077812.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-08-09T20:43:32.513Z,1660077812.513 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-08-09T20:43:32.871Z,1660077812.871 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-08-09T20:43:33.050Z,1660077813.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-08-09T20:43:33.051Z,1660077813.051 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-08-09T20:43:33.304Z,1660077813.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-08-09T20:43:33.306Z,1660077813.306 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-08-09T20:43:33.586Z,1660077813.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-08-09T20:43:33.588Z,1660077813.588 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2022-08-09T20:43:33.592Z,1660077813.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2022-08-09T20:43:33.716Z,1660077813.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2022-08-09T20:43:33.862Z,1660077813.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2022-08-09T20:43:33.949Z,1660077813.949 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2022-08-09T20:43:34.034Z,1660077814.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2022-08-09T20:43:34.147Z,1660077814.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2022-08-09T20:43:34.322Z,1660077814.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2022-08-09T20:43:34.583Z,1660077814.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-08-09T20:43:34.584Z,1660077814.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2022-08-09T20:43:34.711Z,1660077814.711 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2022-08-09T20:43:34.804Z,1660077814.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2022-08-09T20:43:34.906Z,1660077814.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2022-08-09T20:43:35.008Z,1660077815.008 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-08-09T20:43:35.024Z,1660077815.024 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-08-09T20:43:35.141Z,1660077815.141 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-08-09T20:43:35.143Z,1660077815.143 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-08-09T20:43:35.158Z,1660077815.158 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-08-09T20:43:35.159Z,1660077815.159 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-08-09T20:43:35.228Z,1660077815.228 [DepthRateCalculator] Loaded
2022-08-09T20:43:35.228Z,1660077815.228 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-08-09T20:43:35.233Z,1660077815.233 [PitchRateCalculator] Loaded
2022-08-09T20:43:35.234Z,1660077815.234 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-08-09T20:43:35.244Z,1660077815.244 [SpeedCalculator] Loaded
2022-08-09T20:43:35.244Z,1660077815.244 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-08-09T20:43:35.249Z,1660077815.249 [YawRateCalculator] Loaded
2022-08-09T20:43:35.249Z,1660077815.249 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-08-09T20:43:35.270Z,1660077815.270 [ElevatorOffsetCalculator] Loaded
2022-08-09T20:43:35.270Z,1660077815.270 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-08-09T20:43:35.271Z,1660077815.271 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-08-09T20:43:35.273Z,1660077815.273 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-08-09T20:43:35.340Z,1660077815.340 [VerticalControl](DEBUG): Construct VerticalControl.
2022-08-09T20:43:35.395Z,1660077815.395 [VerticalControl] Loaded
2022-08-09T20:43:35.395Z,1660077815.395 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-08-09T20:43:35.398Z,1660077815.398 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-08-09T20:43:35.438Z,1660077815.438 [HorizontalControl] Loaded
2022-08-09T20:43:35.439Z,1660077815.439 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-08-09T20:43:35.441Z,1660077815.441 [SpeedControl](DEBUG): Construct SpeedControl.
2022-08-09T20:43:35.444Z,1660077815.444 [SpeedControl] Loaded
2022-08-09T20:43:35.444Z,1660077815.444 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-08-09T20:43:35.447Z,1660077815.447 [LoopControl](DEBUG): Construct LoopControl.
2022-08-09T20:43:35.447Z,1660077815.447 [LoopControl] Loaded
2022-08-09T20:43:35.447Z,1660077815.447 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-08-09T20:43:35.448Z,1660077815.448 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-08-09T20:43:35.448Z,1660077815.448 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-08-09T20:43:35.583Z,1660077815.583 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-08-09T20:43:35.583Z,1660077815.583 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-08-09T20:43:35.597Z,1660077815.597 [NavChart] Loaded
2022-08-09T20:43:35.597Z,1660077815.597 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-08-09T20:43:35.603Z,1660077815.603 [UniversalFixResidualReporter] Loaded
2022-08-09T20:43:35.603Z,1660077815.603 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-08-09T20:43:35.603Z,1660077815.603 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-08-09T20:43:35.604Z,1660077815.604 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-08-09T20:43:35.783Z,1660077815.783 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-08-09T20:43:35.784Z,1660077815.783 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-08-09T20:43:36.503Z,1660077816.503 [AHRS_M2] Loaded
2022-08-09T20:43:36.504Z,1660077816.504 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-08-09T20:43:36.753Z,1660077816.753 [BackseatComponent] Loaded
2022-08-09T20:43:36.753Z,1660077816.753 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-08-09T20:43:36.754Z,1660077816.754 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E44E0
2022-08-09T20:43:36.755Z,1660077816.755 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 918
2022-08-09T20:43:36.757Z,1660077816.757 [LcmUniversalReporter] Loaded
2022-08-09T20:43:36.758Z,1660077816.758 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-08-09T20:43:37.549Z,1660077817.549 [BPC1] Loaded
2022-08-09T20:43:37.549Z,1660077817.549 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-08-09T20:43:37.692Z,1660077817.692 [DataOverHttps] Loaded
2022-08-09T20:43:37.692Z,1660077817.692 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-08-09T20:43:37.693Z,1660077817.693 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409144E0
2022-08-09T20:43:37.693Z,1660077817.693 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 919
2022-08-09T20:43:37.714Z,1660077817.714 [Depth_Keller] Loaded
2022-08-09T20:43:37.714Z,1660077817.714 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-08-09T20:43:37.719Z,1660077817.719 [DropWeight] Loaded
2022-08-09T20:43:37.719Z,1660077817.719 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-08-09T20:43:37.756Z,1660077817.756 [DVL_micro] Loaded
2022-08-09T20:43:37.757Z,1660077817.757 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2022-08-09T20:43:37.818Z,1660077817.818 [NAL9602] Loaded
2022-08-09T20:43:37.818Z,1660077817.818 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-08-09T20:43:37.847Z,1660077817.847 [Onboard] Loaded
2022-08-09T20:43:37.848Z,1660077817.848 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-08-09T20:43:37.849Z,1660077817.849 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409444E0
2022-08-09T20:43:37.849Z,1660077817.849 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 920
2022-08-09T20:43:37.861Z,1660077817.861 [Power24vConverter] Loaded
2022-08-09T20:43:37.861Z,1660077817.861 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-08-09T20:43:37.874Z,1660077817.874 [Radio_Surface] Loaded
2022-08-09T20:43:37.874Z,1660077817.874 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-08-09T20:43:37.875Z,1660077817.875 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409744E0
2022-08-09T20:43:37.875Z,1660077817.875 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 921
2022-08-09T20:43:37.966Z,1660077817.966 [DAT] Loaded
2022-08-09T20:43:37.966Z,1660077817.966 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-08-09T20:43:37.967Z,1660077817.967 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0
2022-08-09T20:43:37.968Z,1660077817.968 [DAT ThreadHandler](INFO): Protected caller Thread ID is 922
2022-08-09T20:43:37.968Z,1660077817.968 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-08-09T20:43:37.969Z,1660077817.969 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-08-09T20:43:38.532Z,1660077818.532 [BuoyancyServo] Loaded
2022-08-09T20:43:38.533Z,1660077818.533 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-08-09T20:43:38.553Z,1660077818.553 [ElevatorServo] Loaded
2022-08-09T20:43:38.553Z,1660077818.553 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-08-09T20:43:38.572Z,1660077818.572 [RudderServo] Loaded
2022-08-09T20:43:38.572Z,1660077818.572 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-08-09T20:43:38.586Z,1660077818.586 [ThrusterHE] Loaded
2022-08-09T20:43:38.587Z,1660077818.587 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-08-09T20:43:38.587Z,1660077818.587 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-08-09T20:43:38.588Z,1660077818.588 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-08-09T20:43:38.669Z,1660077818.669 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-08-09T20:43:38.670Z,1660077818.670 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-08-09T20:43:38.713Z,1660077818.713 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-08-09T20:43:38.713Z,1660077818.713 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-08-09T20:43:39.066Z,1660077819.066 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-08-09T20:43:39.066Z,1660077819.066 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-08-09T20:43:39.281Z,1660077819.281 [CTD_Seabird] Loaded
2022-08-09T20:43:39.281Z,1660077819.281 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-08-09T20:43:39.282Z,1660077819.282 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B304E0
2022-08-09T20:43:39.282Z,1660077819.282 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 923
2022-08-09T20:43:39.315Z,1660077819.315 [ESPComponent] Loaded
2022-08-09T20:43:39.315Z,1660077819.315 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-08-09T20:43:39.335Z,1660077819.335 [PAR_Licor] Loaded
2022-08-09T20:43:39.335Z,1660077819.335 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-08-09T20:43:39.367Z,1660077819.367 [WetLabsBB2FL] Loaded
2022-08-09T20:43:39.367Z,1660077819.367 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-08-09T20:43:39.368Z,1660077819.368 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B604E0
2022-08-09T20:43:39.368Z,1660077819.368 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 924
2022-08-09T20:43:39.369Z,1660077819.369 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-08-09T20:43:39.370Z,1660077819.370 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-08-09T20:43:39.533Z,1660077819.533 [SBIT](DEBUG): Construct Startup Built In Test.
2022-08-09T20:43:39.542Z,1660077819.542 [SBIT] Loaded
2022-08-09T20:43:39.543Z,1660077819.543 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-08-09T20:43:39.546Z,1660077819.546 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-08-09T20:43:39.559Z,1660077819.559 [IBIT] Loaded
2022-08-09T20:43:39.560Z,1660077819.560 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-08-09T20:43:39.566Z,1660077819.566 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-08-09T20:43:39.670Z,1660077819.670 [CBIT] Loaded
2022-08-09T20:43:39.670Z,1660077819.670 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-08-09T20:43:39.671Z,1660077819.671 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-08-09T20:43:39.677Z,1660077819.677 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-08-09T20:43:39.680Z,1660077819.680 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-08-09T20:43:39.691Z,1660077819.691 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-08-09T20:43:39.692Z,1660077819.692 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCE4E0
2022-08-09T20:43:39.692Z,1660077819.692 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 925
2022-08-09T20:43:39.696Z,1660077819.696 [Supervisor](INFO): Main Thread ID is 827
2022-08-09T20:43:39.697Z,1660077819.697 [Supervisor](DEBUG): Running supervisor.
2022-08-09T20:43:39.697Z,1660077819.697 [CommandExec ThreadHandler](INFO): Handler Thread ID is 926
2022-08-09T20:43:39.698Z,1660077819.698 [CommandExec](INFO): Initializing the command executive.
2022-08-09T20:43:39.699Z,1660077819.699 [CommandLine ThreadHandler](INFO): Handler Thread ID is 927
2022-08-09T20:43:39.701Z,1660077819.701 [controlThread ThreadHandler](INFO): Handler Thread ID is 928
2022-08-09T20:43:39.702Z,1660077819.702 [controlThread](DEBUG): Initializing ControlThread
2022-08-09T20:43:39.703Z,1660077819.703 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-08-09T20:43:39.703Z,1660077819.703 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-08-09T20:43:39.703Z,1660077819.703 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-08-09T20:43:39.704Z,1660077819.704 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-08-09T20:43:39.704Z,1660077819.704 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-08-09T20:43:39.705Z,1660077819.705 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-08-09T20:43:39.706Z,1660077819.706 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-08-09T20:43:39.707Z,1660077819.707 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-08-09T20:43:39.707Z,1660077819.707 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-08-09T20:43:39.708Z,1660077819.708 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-08-09T20:43:39.708Z,1660077819.708 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-08-09T20:43:39.718Z,1660077819.718 [SBIT](INFO): Initialize SBIT Component.
2022-08-09T20:43:39.718Z,1660077819.718 [SBIT](IMPORTANT): git: 2022-08-02
2022-08-09T20:43:39.719Z,1660077819.719 [SBIT](INFO): git hash: f84e5919489b70ff94ab72b61eefa777bac519a0
2022-08-09T20:43:39.719Z,1660077819.719 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-08-09T20:43:39.720Z,1660077819.720 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-08-09T20:43:39.721Z,1660077819.721 [SBIT](INFO): Beginning SBIT in 79.000000 seconds.
2022-08-09T20:43:39.722Z,1660077819.722 [IBIT](INFO): Initialize IBIT Component.
2022-08-09T20:43:39.723Z,1660077819.723 [CBIT](DEBUG): Initialize CBIT Component.
2022-08-09T20:43:39.724Z,1660077819.724 [logger ThreadHandler](INFO): Handler Thread ID is 929
2022-08-09T20:43:39.734Z,1660077819.734 [CBIT](DEBUG): Initialized mux pins.
2022-08-09T20:43:39.734Z,1660077819.734 [CBIT](DEBUG): Initializing the watchdog timer.
2022-08-09T20:43:39.742Z,1660077819.742 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 930
2022-08-09T20:43:39.754Z,1660077819.754 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 931
2022-08-09T20:43:39.755Z,1660077819.755 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-08-09T20:43:39.758Z,1660077819.758 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-08-09T20:43:39.758Z,1660077819.758 [CBIT](DEBUG): Initializing heartbeat.
2022-08-09T20:43:39.766Z,1660077819.766 [Onboard ThreadHandler](INFO): Handler Thread ID is 932
2022-08-09T20:43:39.784Z,1660077819.784 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 933
2022-08-09T20:43:39.806Z,1660077819.806 [DAT ThreadHandler](INFO): Handler Thread ID is 934
2022-08-09T20:43:39.807Z,1660077819.807 [DAT](INFO): Powering up
2022-08-09T20:43:39.807Z,1660077819.807 [DAT](DEBUG): Initializing DAT.
2022-08-09T20:43:39.811Z,1660077819.811 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 936
2022-08-09T20:43:39.812Z,1660077819.812 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-08-09T20:43:39.815Z,1660077819.815 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 938
2022-08-09T20:43:39.818Z,1660077819.818 [WetLabsBB2FL](INFO): Powering up
2022-08-09T20:43:39.819Z,1660077819.819 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940
2022-08-09T20:43:39.828Z,1660077819.828 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2022-08-09T20:43:39.828Z,1660077819.828 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2022-08-09T20:43:39.828Z,1660077819.828 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2022-08-09T20:43:39.828Z,1660077819.828 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2022-08-09T20:43:39.829Z,1660077819.829 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2022-08-09T20:43:39.829Z,1660077819.829 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2022-08-09T20:43:39.829Z,1660077819.829 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2022-08-09T20:43:39.829Z,1660077819.829 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2022-08-09T20:43:39.829Z,1660077819.829 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2022-08-09T20:43:39.830Z,1660077819.830 [CBIT](DEBUG): Deactivating GF circuits.
2022-08-09T20:43:39.830Z,1660077819.830 [CBIT](DEBUG): Deactivating emergency mode.
2022-08-09T20:43:39.829Z,1660077819.829 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2022-08-09T20:43:39.830Z,1660077819.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2022-08-09T20:43:39.830Z,1660077819.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2022-08-09T20:43:39.866Z,1660077819.866 [CBIT](DEBUG): Backplane powered.
2022-08-09T20:43:39.867Z,1660077819.867 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-08-09T20:43:39.903Z,1660077819.903 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-08-09T20:43:39.931Z,1660077819.931 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2022-08-09T20:43:39.931Z,1660077819.931 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-08-09T20:43:39.995Z,1660077819.995 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-08-09T20:43:39.997Z,1660077819.997 [Default:A.Wait](DEBUG): Construct Wait.
2022-08-09T20:43:39.999Z,1660077819.999 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-08-09T20:43:40.030Z,1660077820.030 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-08-09T20:43:40.033Z,1660077820.033 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-08-09T20:43:40.066Z,1660077820.066 [Default:E.Execute](DEBUG): Construct Execute.
2022-08-09T20:43:40.069Z,1660077820.069 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2022-08-09T20:43:40.074Z,1660077820.074 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-08-09T20:43:40.080Z,1660077820.080 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-08-09T20:43:40.190Z,1660077820.190 [Radio_Surface](INFO): Powering up
2022-08-09T20:43:40.235Z,1660077820.235 [Power24vConverter](INFO): Powering up.
2022-08-09T20:43:40.273Z,1660077820.273 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-08-09T20:43:40.278Z,1660077820.278 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-08-09T20:43:40.280Z,1660077820.280 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-08-09T20:43:40.286Z,1660077820.286 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-08-09T20:43:40.287Z,1660077820.287 [RudderServo](DEBUG): Initializing EZServoServo.
2022-08-09T20:43:40.294Z,1660077820.294 [RudderServo](DEBUG): Initializing RudderServo.
2022-08-09T20:43:40.298Z,1660077820.298 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-08-09T20:43:40.306Z,1660077820.306 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-08-09T20:43:40.531Z,1660077820.531 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-08-09T20:43:40.531Z,1660077820.531 [DropWeight] Hardware Fault, FailCount= 1
2022-08-09T20:43:40.531Z,1660077820.531 [DropWeight](ERROR): Hardware Fault
2022-08-09T20:43:40.573Z,1660077820.573 [CommandExec](FAULT): Scheduling is paused
2022-08-09T20:43:40.582Z,1660077820.582 [CBIT](INFO): Critical error at 20220809T204340
2022-08-09T20:43:40.582Z,1660077820.582 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-08-09T20:43:40.584Z,1660077820.584 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-08-09T20:43:40.585Z,1660077820.585 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-08-09T20:43:41.222Z,1660077821.222 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-08-09T20:43:41.222Z,1660077821.222 [RudderServo](FAULT): Rudder failed to initialize
2022-08-09T20:43:41.222Z,1660077821.222 [RudderServo] Communications Fault, FailCount= 1
2022-08-09T20:43:41.222Z,1660077821.222 [RudderServo](ERROR): Communications Fault
2022-08-09T20:43:41.224Z,1660077821.224 [CBIT](INFO): Critical error at 20220809T204340
2022-08-09T20:43:41.226Z,1660077821.226 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-08-09T20:43:41.426Z,1660077821.426 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-08-09T20:43:41.426Z,1660077821.426 [RudderServo](INFO): Powering down
2022-08-09T20:43:42.133Z,1660077822.133 [RudderServo](DEBUG): Initializing EZServoServo.
2022-08-09T20:43:42.255Z,1660077822.255 [RudderServo](DEBUG): Initializing RudderServo.
2022-08-09T20:43:42.259Z,1660077822.259 [CBIT](INFO): Clearing failed state for component RudderServo
2022-08-09T20:43:42.259Z,1660077822.259 [RudderServo] No Fault, FailCount= 1
2022-08-09T20:43:42.430Z,1660077822.430 [WetLabsBB2FL](INFO): Powering down
2022-08-09T20:43:45.815Z,1660077825.815 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-08-09T20:43:46.842Z,1660077826.842 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-08-09T20:43:54.172Z,1660077834.172 [DAT](INFO): commRate: 800
2022-08-09T20:43:56.242Z,1660077836.242 [DAT](INFO): entering command mode
2022-08-09T20:43:56.439Z,1660077836.439 [DAT](INFO): setting verbose to 3
2022-08-09T20:43:56.691Z,1660077836.691 [DAT](INFO): set verbose to 3
2022-08-09T20:43:56.692Z,1660077836.692 [DAT](INFO): setting DatVerbose to 27440
2022-08-09T20:43:56.943Z,1660077836.943 [DAT](INFO): set DatVerbose to 27440
2022-08-09T20:43:56.944Z,1660077836.944 [DAT](INFO): setting transmit power to 8
2022-08-09T20:43:57.195Z,1660077837.195 [DAT](INFO): set transmit power to 8
2022-08-09T20:43:57.196Z,1660077837.196 [DAT](INFO): setting local address to 8
2022-08-09T20:43:57.447Z,1660077837.447 [DAT](INFO): set local address to 8
2022-08-09T20:43:57.448Z,1660077837.448 [DAT](INFO): Setting time to: 20:43:57 And date to:8/9/2022
2022-08-09T20:43:57.699Z,1660077837.699 [DAT](INFO): Local DAT time set to Tue Aug 9, 2022  20:43:57  

2022-08-09T20:44:07.989Z,1660077847.989 [NAL9602](INFO): Powering up NAL9602
2022-08-09T20:44:18.889Z,1660077858.889 [NAL9602](INFO): NAL9602 initialized
2022-08-09T20:44:42.733Z,1660077882.733 [NAL9602](INFO): SBD MO Status=2, MOMSN=10168, MT Status=2, MTMSN=0
2022-08-09T20:44:42.733Z,1660077882.733 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T20:44:50.811Z,1660077890.811 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:44:59.304Z,1660077899.304 [SBIT](IMPORTANT): Beginning Startup BIT
2022-08-09T20:44:59.309Z,1660077899.309 [CBIT](IMPORTANT): Beginning ground fault scan
2022-08-09T20:45:02.586Z,1660077902.586 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:45:02.590Z,1660077902.590 [NAL9602](INFO): SBD MO Status=0, MOMSN=10168, MT Status=0, MTMSN=0
2022-08-09T20:45:02.591Z,1660077902.591 [NAL9602](INFO): No messages in MT queue
2022-08-09T20:45:03.747Z,1660077903.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204457.00,A,4313.62723,N,08620.34245,W,0.136,0.00,090822,,,A*76
2022-08-09T20:45:03.767Z,1660077903.767 [NAL9602](INFO): GPS fix at 20220809T204457: (43.227120, -86.339041)
2022-08-09T20:45:10.250Z,1660077910.250 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.012209
CHAN A1 (24V): -0.002439
CHAN A2 (12V): -0.000764
CHAN A3 (5V): 0.000691
CHAN B0 (3.3V): 0.000610
CHAN B1 (3.15aV): -0.000093
CHAN B2 (3.15bV): 0.000257
CHAN B3 (GND): 0.000781
OPEN: -0.002611
Full Scale: +/- 1 mA
2022-08-09T20:45:12.645Z,1660077912.645 [SBIT](ERROR): Could not read massPosReader_.
2022-08-09T20:45:14.714Z,1660077914.714 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:45:26.427Z,1660077926.427 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:45:36.054Z,1660077936.054 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T20:45:38.548Z,1660077938.548 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:45:39.325Z,1660077939.325 [SBIT](ERROR): Could not read massPosReader_.
2022-08-09T20:45:39.325Z,1660077939.325 [SBIT](FAULT): Control surface position failure.
2022-08-09T20:45:50.258Z,1660077950.258 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:45:53.040Z,1660077953.040 [SBIT](ERROR): Could not read massPosReader_.
2022-08-09T20:45:53.040Z,1660077953.040 [SBIT](FAULT): Control surface position failure.
2022-08-09T20:45:53.451Z,1660077953.451 [SBIT](CRITICAL): SBIT FAILED
2022-08-09T20:45:53.451Z,1660077953.451 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-08-09T20:45:53.452Z,1660077953.452 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;

2022-08-09T20:45:53.452Z,1660077953.452 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;

2022-08-09T20:45:53.452Z,1660077953.452 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2022-08-09T20:45:53.453Z,1660077953.453 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar;

2022-08-09T20:45:53.453Z,1660077953.453 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-08-09T20:45:53.453Z,1660077953.453 [SBIT](IMPORTANT): MassServo.loadAtStartup=0 bool;

2022-08-09T20:45:53.453Z,1660077953.453 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=90 cubic_centimeter;

2022-08-09T20:45:53.453Z,1660077953.453 [SBIT](IMPORTANT): VerticalControl.massDefault=-15 millimeter;

2022-08-09T20:45:53.453Z,1660077953.453 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter;

2022-08-09T20:45:53.453Z,1660077953.453 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=32 millimeter;

2022-08-09T20:45:53.454Z,1660077953.454 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=1.5 meter;

2022-08-09T20:45:53.455Z,1660077953.455 [CBIT](INFO): Critical error at 20220809T204553
2022-08-09T20:45:53.859Z,1660077953.859 [MissionManager](IMPORTANT): Started mission Startup
2022-08-09T20:45:53.860Z,1660077953.860 [Startup] Running Loop=1
2022-08-09T20:45:53.860Z,1660077953.860 [Startup](DEBUG): Aggregate::initialize Startup
2022-08-09T20:45:53.860Z,1660077953.860 [Startup:A.GoToSurface] Running Loop=1
2022-08-09T20:45:53.860Z,1660077953.860 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-09T20:45:53.860Z,1660077953.860 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-09T20:45:53.861Z,1660077953.861 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-09T20:45:53.861Z,1660077953.861 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-09T20:45:53.862Z,1660077953.862 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-09T20:45:53.863Z,1660077953.863 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-09T20:45:53.864Z,1660077953.864 [Startup:StartupSatComms] Running Loop=1
2022-08-09T20:45:53.864Z,1660077953.864 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-08-09T20:45:53.864Z,1660077953.864 [Startup:StartupSatComms:A] Running Loop=1
2022-08-09T20:45:54.259Z,1660077954.259 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-08-09T20:45:55.857Z,1660077955.857 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204549.00,A,4313.62775,N,08620.33950,W,0.019,0.00,090822,,,A*7F
2022-08-09T20:45:55.859Z,1660077955.859 [NAL9602](INFO): GPS fix at 20220809T204549: (43.227129, -86.338992)
2022-08-09T20:45:55.870Z,1660077955.870 [Startup:StartupSatComms:A] Stopped
2022-08-09T20:45:55.870Z,1660077955.870 [Startup:StartupSatComms:B] Running Loop=1
2022-08-09T20:45:56.287Z,1660077956.287 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-08-09T20:46:01.998Z,1660077961.998 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:46:14.016Z,1660077974.016 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:46:21.279Z,1660077981.279 [NAL9602](INFO): SBD MO Status=1, MOMSN=10169, MT Status=0, MTMSN=0
2022-08-09T20:46:21.498Z,1660077981.498 [NAL9602](INFO): Sent 18 bytes from file Logs/20220809T172553/Courier0108.lzma
2022-08-09T20:46:21.498Z,1660077981.498 [NAL9602](INFO): Packets left to send: 0
2022-08-09T20:46:25.964Z,1660077985.964 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:46:32.383Z,1660077992.383 [NAL9602](INFO): SBD MO Status=1, MOMSN=10170, MT Status=0, MTMSN=0
2022-08-09T20:46:32.434Z,1660077992.434 [NAL9602](INFO): Sent 332 bytes from file Logs/20220809T204328/Courier0000.lzma
2022-08-09T20:46:32.434Z,1660077992.434 [NAL9602](INFO): Packets left to send: 1
2022-08-09T20:46:37.679Z,1660077997.679 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:46:39.229Z,1660077999.229 [NAL9602](INFO): SBD MO Status=1, MOMSN=10171, MT Status=0, MTMSN=0
2022-08-09T20:46:39.278Z,1660077999.278 [NAL9602](INFO): Sent 51 bytes from file Logs/20220809T204328/Courier0000.lzma
2022-08-09T20:46:39.278Z,1660077999.278 [NAL9602](INFO): Packets left to send: 0
2022-08-09T20:46:45.428Z,1660078005.428 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004398
2022-08-09T20:46:49.564Z,1660078009.564 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:46:49.663Z,1660078009.663 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:46:55.165Z,1660078015.165 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220809T172553/Express0109.lzma
2022-08-09T20:46:56.118Z,1660078016.118 [Startup:StartupSatComms:B](INFO): Timed out from 2022-08-09T20:45:55.9Z
2022-08-09T20:46:56.118Z,1660078016.118 [Startup:StartupSatComms:B] Stopped
2022-08-09T20:46:56.118Z,1660078016.118 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-08-09T20:46:56.118Z,1660078016.118 [Startup:StartupSatComms] Stopped
2022-08-09T20:46:56.118Z,1660078016.118 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-08-09T20:46:56.119Z,1660078016.119 [Startup](INFO): Completed Startup
2022-08-09T20:46:56.119Z,1660078016.119 [MissionManager](INFO): Startup is completed.
2022-08-09T20:46:56.119Z,1660078016.119 [MissionManager](INFO): Uninitializing Mission Startup
2022-08-09T20:46:56.119Z,1660078016.119 [Startup] Stopped
2022-08-09T20:46:56.119Z,1660078016.119 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-08-09T20:46:56.120Z,1660078016.120 [Startup:A.GoToSurface] Stopped
2022-08-09T20:46:56.120Z,1660078016.120 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-09T20:46:56.168Z,1660078016.168 [DataOverHttps](INFO): Moved sent file to Logs/20220809T172553/Express0109.lzma.bak
2022-08-09T20:46:56.169Z,1660078016.169 [DataOverHttps](INFO): SBD MOMSN=17097622
2022-08-09T20:46:56.462Z,1660078016.462 [MissionManager](IMPORTANT): Started mission Default
2022-08-09T20:46:56.462Z,1660078016.462 [Default] Running Loop=1
2022-08-09T20:46:56.462Z,1660078016.462 [Default](DEBUG): Aggregate::initialize Default
2022-08-09T20:46:56.463Z,1660078016.463 [Default:B.GoToSurface] Running Loop=1
2022-08-09T20:46:56.463Z,1660078016.463 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-09T20:46:56.463Z,1660078016.463 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-09T20:46:56.463Z,1660078016.463 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-09T20:46:56.463Z,1660078016.463 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-09T20:46:56.464Z,1660078016.464 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-09T20:46:56.464Z,1660078016.464 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-09T20:46:56.464Z,1660078016.464 [Default:A.Wait] Running Loop=1
2022-08-09T20:46:56.464Z,1660078016.464 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-08-09T20:46:59.068Z,1660078019.068 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:47:02.205Z,1660078022.205 [NAL9602](INFO): SBD MO Status=2, MOMSN=10172, MT Status=2, MTMSN=0
2022-08-09T20:47:02.205Z,1660078022.205 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T20:47:07.434Z,1660078027.434 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T20:47:07.434Z,1660078027.434 [DVL_micro](ERROR): Failed to parse:
:TS,000000005.0,+25.7,489.0,000
2022-08-09T20:47:08.236Z,1660078028.236 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:47:09.468Z,1660078029.468 [Default:A.Wait](INFO): Done Waiting.
2022-08-09T20:47:09.468Z,1660078029.468 [Default:A.Wait] Stopped
2022-08-09T20:47:09.468Z,1660078029.468 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T20:47:09.904Z,1660078029.904 [Default:CheckIn] Running Loop=1
2022-08-09T20:47:09.904Z,1660078029.904 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T20:47:09.904Z,1660078029.904 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T20:47:10.273Z,1660078030.273 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-08-09T20:47:10.729Z,1660078030.729 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:47:11.880Z,1660078031.880 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204708.00,A,4313.62818,N,08620.33485,W,0.000,0.00,090822,,,D*74
2022-08-09T20:47:11.884Z,1660078031.884 [NAL9602](INFO): GPS fix at 20220809T204708: (43.227136, -86.338914)
2022-08-09T20:47:11.894Z,1660078031.894 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T20:47:11.894Z,1660078031.894 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T20:47:12.355Z,1660078032.355 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-08-09T20:47:15.928Z,1660078035.928 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:47:20.713Z,1660078040.713 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220809T204328/Courier0004.lzma
2022-08-09T20:47:21.715Z,1660078041.715 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0004.lzma.bak
2022-08-09T20:47:21.715Z,1660078041.715 [DataOverHttps](INFO): SBD MOMSN=17097626
2022-08-09T20:47:29.313Z,1660078049.313 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:47:31.313Z,1660078051.313 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:47:40.807Z,1660078060.807 [DataOverHttps](INFO): Sending 1076 bytes from file Logs/20220809T204328/Express0001.lzma
2022-08-09T20:47:41.807Z,1660078061.807 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0001.lzma.bak
2022-08-09T20:47:41.807Z,1660078061.807 [DataOverHttps](INFO): SBD MOMSN=17097629
2022-08-09T20:47:44.603Z,1660078064.603 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T20:47:47.065Z,1660078067.065 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:47:51.464Z,1660078071.464 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:48:01.109Z,1660078081.109 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20220809T204328/Express0005.lzma
2022-08-09T20:48:02.111Z,1660078082.111 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0005.lzma.bak
2022-08-09T20:48:02.111Z,1660078082.111 [DataOverHttps](INFO): SBD MOMSN=17097654
2022-08-09T20:48:02.421Z,1660078082.421 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:48:05.677Z,1660078085.677 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T20:48:05.677Z,1660078085.677 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T20:48:05.677Z,1660078085.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T20:48:12.876Z,1660078092.876 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:48:17.801Z,1660078097.801 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:48:33.530Z,1660078113.530 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:48:34.692Z,1660078114.692 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:48:48.880Z,1660078128.880 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:48:56.104Z,1660078136.104 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:49:04.229Z,1660078144.229 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:49:13.934Z,1660078153.934 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:49:19.181Z,1660078159.181 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:49:25.667Z,1660078165.667 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:49:33.724Z,1660078173.724 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:49:37.408Z,1660078177.408 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:49:47.879Z,1660078187.879 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:49:49.114Z,1660078189.114 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:49:59.608Z,1660078199.608 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:00.819Z,1660078200.819 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:11.725Z,1660078211.725 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:12.531Z,1660078212.531 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:23.437Z,1660078223.437 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:24.246Z,1660078224.246 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:35.559Z,1660078235.559 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:36.364Z,1660078236.364 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:47.276Z,1660078247.276 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:48.089Z,1660078248.089 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:58.991Z,1660078258.991 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:50:59.798Z,1660078259.798 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:51:11.111Z,1660078271.111 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:51:11.515Z,1660078271.515 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:51:22.827Z,1660078282.827 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:51:23.231Z,1660078283.231 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:51:34.951Z,1660078294.951 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:51:35.023Z,1660078295.023 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:51:46.664Z,1660078306.664 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:51:58.783Z,1660078318.783 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:52:10.499Z,1660078330.499 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:52:10.853Z,1660078330.853 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T20:52:10.853Z,1660078330.853 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000+25.9,0000.0,1489.0,000
2022-08-09T20:53:06.203Z,1660078386.203 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T20:53:06.203Z,1660078386.203 [Default:CheckIn:C.Wait] Stopped
2022-08-09T20:53:06.203Z,1660078386.203 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T20:53:06.203Z,1660078386.203 [Default:CheckIn:D] Running Loop=1
2022-08-09T20:53:06.601Z,1660078386.601 [Default:CheckIn:D] Stopped
2022-08-09T20:53:06.601Z,1660078386.601 [Default:CheckIn:E] Running Loop=1
2022-08-09T20:53:07.005Z,1660078387.005 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.168968 min 
2022-08-09T20:53:07.005Z,1660078387.005 [Default:CheckIn:E] Stopped
2022-08-09T20:53:07.005Z,1660078387.005 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T20:53:07.005Z,1660078387.005 [Default:CheckIn] Stopped
2022-08-09T20:53:07.005Z,1660078387.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T20:53:07.005Z,1660078387.005 [Default:CheckIn](INFO): Running loop #2
2022-08-09T20:53:07.005Z,1660078387.005 [Default:CheckIn] Running Loop=2
2022-08-09T20:53:07.006Z,1660078387.006 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T20:53:07.006Z,1660078387.006 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T20:53:09.042Z,1660078389.042 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T20:53:09.042Z,1660078389.042 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+25.9,0000.0,1489.0+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2022-08-09T20:53:09.044Z,1660078389.044 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205305.00,A,4313.63015,N,08620.33687,W,0.058,0.00,090822,,,D*75
2022-08-09T20:53:09.047Z,1660078389.047 [NAL9602](INFO): GPS fix at 20220809T205305: (43.227169, -86.338948)
2022-08-09T20:53:09.062Z,1660078389.062 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T20:53:09.062Z,1660078389.062 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T20:53:17.897Z,1660078397.897 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220809T204328/Courier0007.lzma
2022-08-09T20:53:18.899Z,1660078398.899 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0007.lzma.bak
2022-08-09T20:53:18.899Z,1660078398.899 [DataOverHttps](INFO): SBD MOMSN=17097673
2022-08-09T20:53:27.608Z,1660078407.608 [NAL9602](INFO): SBD MO Status=1, MOMSN=10172, MT Status=0, MTMSN=0
2022-08-09T20:53:27.609Z,1660078407.609 [NAL9602](INFO): No messages in MT queue
2022-08-09T20:53:38.477Z,1660078418.477 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20220809T204328/Express0008.lzma
2022-08-09T20:53:39.479Z,1660078419.479 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0008.lzma.bak
2022-08-09T20:53:39.479Z,1660078419.479 [DataOverHttps](INFO): SBD MOMSN=17097704
2022-08-09T20:53:42.973Z,1660078422.973 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T20:53:42.973Z,1660078422.973 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T20:53:42.973Z,1660078422.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T20:53:58.324Z,1660078438.324 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T20:54:10.471Z,1660078450.471 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:54:49.258Z,1660078489.258 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:55:00.985Z,1660078500.985 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:55:13.105Z,1660078513.105 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:55:24.821Z,1660078524.821 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:55:36.545Z,1660078536.545 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:55:48.252Z,1660078548.252 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:55:59.969Z,1660078559.969 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:56:11.277Z,1660078571.277 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:56:22.600Z,1660078582.600 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:56:34.310Z,1660078594.310 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:56:46.431Z,1660078606.431 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:56:50.805Z,1660078610.805 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:56:58.146Z,1660078618.146 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:57:09.869Z,1660078629.869 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:57:12.619Z,1660078632.619 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:57:26.815Z,1660078646.815 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:57:34.032Z,1660078654.032 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:57:42.160Z,1660078662.160 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:57:55.848Z,1660078675.848 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:57:57.115Z,1660078677.115 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:58:08.959Z,1660078688.959 [DataOverHttps](IMPORTANT): SBD MTMSN=20220809T205808
2022-08-09T20:58:12.490Z,1660078692.490 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:58:16.478Z,1660078696.478 [DataOverHttps](INFO): Received command: burn on
2022-08-09T20:58:16.487Z,1660078696.487 [CommandExec](IMPORTANT): got command burn on
2022-08-09T20:58:16.487Z,1660078696.487 [CommandExec](IMPORTANT): Activating dropweight wire
2022-08-09T20:58:17.479Z,1660078697.479 [DataOverHttps](IMPORTANT): SBD MTMSN=20220809T205816
2022-08-09T20:58:17.664Z,1660078697.664 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:58:24.913Z,1660078704.913 [DataOverHttps](INFO): Received command: get onboard.pressure psi;get onboard.humidity;get onboard.temperature
2022-08-09T20:58:26.811Z,1660078706.811 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2022-08-09T20:58:26.812Z,1660078706.812 [CommandExec](IMPORTANT): Onboard.Pressure 8.615986 psi
2022-08-09T20:58:26.812Z,1660078706.812 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2022-08-09T20:58:26.813Z,1660078706.813 [CommandExec](IMPORTANT): Onboard.Humidity 31.150043 %
2022-08-09T20:58:26.813Z,1660078706.813 [CommandExec](IMPORTANT): got command get Onboard.Temperature
2022-08-09T20:58:26.814Z,1660078706.814 [CommandExec](IMPORTANT): Onboard.Temperature 34.250999 degC
2022-08-09T20:58:27.409Z,1660078707.409 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:58:39.076Z,1660078719.076 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:58:40.737Z,1660078720.737 [CBIT](INFO): Clearing failed state for component DropWeight
2022-08-09T20:58:40.737Z,1660078720.737 [DropWeight] No Fault, FailCount= 1
2022-08-09T20:58:42.314Z,1660078722.314 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-08-09T20:58:42.314Z,1660078722.314 [DropWeight] Hardware Fault, FailCount= 1
2022-08-09T20:58:42.314Z,1660078722.314 [DropWeight](ERROR): Hardware Fault
2022-08-09T20:58:42.386Z,1660078722.385 [CBIT](INFO): Critical error at 20220809T205842
2022-08-09T20:58:42.388Z,1660078722.388 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-08-09T20:58:42.388Z,1660078722.388 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-08-09T20:58:42.762Z,1660078722.762 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:58:42.782Z,1660078722.782 [CBIT](INFO): Critical error at 20220809T205842
2022-08-09T20:58:43.552Z,1660078723.552 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T20:58:43.553Z,1660078723.553 [Default:CheckIn:C.Wait] Stopped
2022-08-09T20:58:43.553Z,1660078723.553 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T20:58:43.553Z,1660078723.553 [Default:CheckIn:D] Running Loop=1
2022-08-09T20:58:43.941Z,1660078723.941 [Default:CheckIn:D] Stopped
2022-08-09T20:58:43.941Z,1660078723.941 [Default:CheckIn:E] Running Loop=1
2022-08-09T20:58:44.340Z,1660078724.340 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.791307 min 
2022-08-09T20:58:44.340Z,1660078724.340 [Default:CheckIn:E] Stopped
2022-08-09T20:58:44.340Z,1660078724.340 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T20:58:44.341Z,1660078724.341 [Default:CheckIn] Stopped
2022-08-09T20:58:44.341Z,1660078724.341 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T20:58:44.341Z,1660078724.341 [Default:CheckIn](INFO): Running loop #3
2022-08-09T20:58:44.341Z,1660078724.341 [Default:CheckIn] Running Loop=3
2022-08-09T20:58:44.341Z,1660078724.341 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T20:58:44.341Z,1660078724.341 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T20:58:46.357Z,1660078726.357 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205845.00,A,4313.62868,N,08620.33546,W,0.039,332.21,090822,,,D*71
2022-08-09T20:58:46.364Z,1660078726.364 [NAL9602](INFO): GPS fix at 20220809T205845: (43.227145, -86.338924)
2022-08-09T20:58:46.375Z,1660078726.375 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T20:58:46.375Z,1660078726.375 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T20:58:54.901Z,1660078734.901 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220809T204328/Courier0010.lzma
2022-08-09T20:58:55.903Z,1660078735.903 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0010.lzma.bak
2022-08-09T20:58:55.903Z,1660078735.903 [DataOverHttps](INFO): SBD MOMSN=17097742
2022-08-09T20:58:58.159Z,1660078738.159 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:00.895Z,1660078740.895 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T20:59:05.757Z,1660078745.757 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:13.503Z,1660078753.503 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:15.090Z,1660078755.090 [DataOverHttps](INFO): Sending 368 bytes from file Logs/20220809T204328/Express0011.lzma
2022-08-09T20:59:16.091Z,1660078756.091 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0011.lzma.bak
2022-08-09T20:59:16.092Z,1660078756.092 [DataOverHttps](IMPORTANT): SBD MOMSN=17097748, MTMSN=20220809T205915
2022-08-09T20:59:17.592Z,1660078757.592 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:25.861Z,1660078765.861 [DataOverHttps](INFO): Received command: burn off
2022-08-09T20:59:25.931Z,1660078765.931 [CommandExec](IMPORTANT): got command burn off
2022-08-09T20:59:25.931Z,1660078765.931 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-08-09T20:59:25.945Z,1660078765.945 [NAL9602](INFO): SBD MO Status=2, MOMSN=10173, MT Status=2, MTMSN=0
2022-08-09T20:59:25.945Z,1660078765.945 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T20:59:28.816Z,1660078768.816 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:29.247Z,1660078769.247 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:34.393Z,1660078774.393 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220809T204328/Express0012.lzma
2022-08-09T20:59:35.396Z,1660078775.396 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0012.lzma.bak
2022-08-09T20:59:35.396Z,1660078775.396 [DataOverHttps](INFO): SBD MOMSN=17097763
2022-08-09T20:59:41.420Z,1660078781.420 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:43.409Z,1660078783.409 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:48.196Z,1660078788.196 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:50.201Z,1660078790.201 [NAL9602](INFO): SBD MO Status=1, MOMSN=10173, MT Status=0, MTMSN=0
2022-08-09T20:59:50.201Z,1660078790.201 [NAL9602](INFO): No messages in MT queue
2022-08-09T20:59:53.077Z,1660078793.077 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T20:59:53.485Z,1660078793.485 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220809T204328/Courier0015.lzma
2022-08-09T20:59:54.487Z,1660078794.487 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0015.lzma.bak
2022-08-09T20:59:54.487Z,1660078794.487 [DataOverHttps](INFO): SBD MOMSN=17097771
2022-08-09T20:59:59.000Z,1660078800.000 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:04.794Z,1660078804.794 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:11.713Z,1660078811.713 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:12.733Z,1660078812.733 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20220809T204328/Express0017.lzma
2022-08-09T21:00:13.735Z,1660078813.735 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0017.lzma.bak
2022-08-09T21:00:13.735Z,1660078813.735 [DataOverHttps](INFO): SBD MOMSN=17097773
2022-08-09T21:00:16.576Z,1660078816.576 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:17.302Z,1660078817.302 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:00:17.302Z,1660078817.302 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:00:17.302Z,1660078817.302 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:00:20.887Z,1660078820.887 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:00:23.073Z,1660078823.073 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:23.090Z,1660078823.090 [DVL_micro](ERROR): Failed to parse:
:0,35.0,+26.2,0000.0,1489.0,000
2022-08-09T21:00:28.633Z,1660078828.633 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:35.094Z,1660078835.094 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:40.346Z,1660078840.346 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:46.811Z,1660078846.811 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:52.466Z,1660078852.466 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:00:58.933Z,1660078858.933 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:01:04.187Z,1660078864.187 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:01:10.652Z,1660078870.652 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:01:11.987Z,1660078871.987 [DataOverHttps](IMPORTANT): SBD MTMSN=20220809T210111
2022-08-09T21:01:15.965Z,1660078875.965 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:01:19.549Z,1660078879.549 [DataOverHttps](INFO): Received command: get depth
2022-08-09T21:01:19.734Z,1660078879.734 [CommandExec](IMPORTANT): got command get depth
2022-08-09T21:01:19.735Z,1660078879.735 [CommandExec](IMPORTANT): depth -0.033579 m
2022-08-09T21:01:22.766Z,1660078882.766 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:01:27.614Z,1660078887.614 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:01:34.483Z,1660078894.483 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:01:39.331Z,1660078899.331 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:01:46.602Z,1660078906.602 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:01:58.345Z,1660078918.345 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:02:10.440Z,1660078930.440 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:02:22.154Z,1660078942.154 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:04:00.308Z,1660079040.308 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:04:52.454Z,1660079092.454 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:05:04.165Z,1660079104.165 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:05:16.282Z,1660079116.282 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:05:17.872Z,1660079117.872 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:05:17.872Z,1660079117.872 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:05:17.872Z,1660079117.872 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:05:17.872Z,1660079117.872 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:05:18.249Z,1660079118.249 [Default:CheckIn:D] Stopped
2022-08-09T21:05:18.249Z,1660079118.249 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:05:18.650Z,1660079118.650 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.363104 min 
2022-08-09T21:05:18.650Z,1660079118.650 [Default:CheckIn:E] Stopped
2022-08-09T21:05:18.650Z,1660079118.650 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:05:18.650Z,1660079118.650 [Default:CheckIn] Stopped
2022-08-09T21:05:18.650Z,1660079118.650 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:05:18.650Z,1660079118.650 [Default:CheckIn](INFO): Running loop #4
2022-08-09T21:05:18.650Z,1660079118.650 [Default:CheckIn] Running Loop=4
2022-08-09T21:05:18.651Z,1660079118.651 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:05:18.651Z,1660079118.651 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:05:20.680Z,1660079120.680 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210519.00,A,4313.62689,N,08620.33431,W,0.019,332.21,090822,,,D*73
2022-08-09T21:05:20.682Z,1660079120.682 [NAL9602](INFO): GPS fix at 20220809T210519: (43.227115, -86.338905)
2022-08-09T21:05:20.693Z,1660079120.693 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:05:20.693Z,1660079120.693 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:05:28.095Z,1660079128.095 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:05:30.113Z,1660079130.113 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220809T204328/Courier0019.lzma
2022-08-09T21:05:31.115Z,1660079131.115 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0019.lzma.bak
2022-08-09T21:05:31.115Z,1660079131.115 [DataOverHttps](INFO): SBD MOMSN=17097789
2022-08-09T21:05:33.232Z,1660079133.232 [DVL_micro](ERROR): only read 3 of 4 data items
2022-08-09T21:05:33.232Z,1660079133.232 [DVL_micro](ERROR): Failed to parse:
:BI,00033,+00041,+00000,I
2022-08-09T21:05:40.176Z,1660079140.176 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:05:45.737Z,1660079145.737 [NAL9602](INFO): SBD MO Status=1, MOMSN=10174, MT Status=0, MTMSN=0
2022-08-09T21:05:45.738Z,1660079145.738 [NAL9602](INFO): No messages in MT queue
2022-08-09T21:05:51.838Z,1660079151.838 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:05:52.141Z,1660079152.141 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220809T204328/Express0020.lzma
2022-08-09T21:05:53.143Z,1660079153.143 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0020.lzma.bak
2022-08-09T21:05:53.143Z,1660079153.143 [DataOverHttps](INFO): SBD MOMSN=17097793
2022-08-09T21:06:03.488Z,1660079163.488 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T21:06:03.489Z,1660079163.489 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000+26.3,0000.0,1489.0,000
2022-08-09T21:06:03.953Z,1660079163.953 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:06:12.269Z,1660079172.269 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20220809T204328/Express0023.lzma
2022-08-09T21:06:14.041Z,1660079174.041 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0023.lzma.bak
2022-08-09T21:06:14.041Z,1660079174.041 [DataOverHttps](INFO): SBD MOMSN=17097799
2022-08-09T21:06:15.736Z,1660079175.736 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:06:16.427Z,1660079176.427 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:06:17.668Z,1660079177.668 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:06:17.668Z,1660079177.668 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:06:17.668Z,1660079177.668 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:06:27.791Z,1660079187.791 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:06:32.164Z,1660079192.164 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T21:06:39.511Z,1660079199.511 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:06:51.627Z,1660079211.627 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:06:53.576Z,1660079213.576 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T21:07:03.369Z,1660079223.369 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:07:15.096Z,1660079235.096 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:07:15.393Z,1660079235.393 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T21:07:19.846Z,1660079239.846 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:07:35.642Z,1660079255.642 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:07:37.207Z,1660079257.207 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T21:07:50.994Z,1660079270.994 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:07:58.621Z,1660079278.621 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T21:08:06.766Z,1660079286.766 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:08:20.436Z,1660079300.436 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T21:08:21.691Z,1660079301.691 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:08:37.051Z,1660079317.051 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:08:42.253Z,1660079322.253 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-08-09T21:08:51.993Z,1660079331.993 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:00.486Z,1660079340.486 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:07.754Z,1660079347.754 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:12.218Z,1660079352.218 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:22.700Z,1660079362.700 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:23.934Z,1660079363.934 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:35.651Z,1660079375.651 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:37.651Z,1660079377.651 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:47.373Z,1660079387.373 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:52.214Z,1660079392.214 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:09:59.488Z,1660079399.488 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:03.980Z,1660079403.980 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:11.200Z,1660079411.200 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:16.048Z,1660079416.048 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:23.319Z,1660079423.319 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:27.766Z,1660079427.766 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:35.044Z,1660079435.044 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:39.488Z,1660079439.488 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:46.754Z,1660079446.754 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:51.197Z,1660079451.197 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:10:58.490Z,1660079458.490 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:11:03.316Z,1660079463.316 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:11:10.187Z,1660079470.187 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:11:15.092Z,1660079475.092 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:11:18.249Z,1660079478.249 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:11:18.250Z,1660079478.250 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:11:18.250Z,1660079478.250 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:11:18.250Z,1660079478.250 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:11:18.613Z,1660079478.613 [Default:CheckIn:D] Stopped
2022-08-09T21:11:18.617Z,1660079478.617 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:11:19.028Z,1660079479.028 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.369177 min 
2022-08-09T21:11:19.028Z,1660079479.028 [Default:CheckIn:E] Stopped
2022-08-09T21:11:19.028Z,1660079479.028 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:11:19.028Z,1660079479.028 [Default:CheckIn] Stopped
2022-08-09T21:11:19.028Z,1660079479.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:11:19.028Z,1660079479.028 [Default:CheckIn](INFO): Running loop #5
2022-08-09T21:11:19.028Z,1660079479.028 [Default:CheckIn] Running Loop=5
2022-08-09T21:11:19.028Z,1660079479.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:11:19.028Z,1660079479.028 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:11:21.037Z,1660079481.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211120.00,A,4313.62700,N,08620.33576,W,0.039,332.21,090822,,,D*7C
2022-08-09T21:11:21.039Z,1660079481.039 [NAL9602](INFO): GPS fix at 20220809T211120: (43.227117, -86.338929)
2022-08-09T21:11:21.066Z,1660079481.066 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:11:21.066Z,1660079481.066 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:11:21.976Z,1660079481.976 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-09T21:11:26.818Z,1660079486.818 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:11:29.569Z,1660079489.569 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220809T204328/Courier0025.lzma
2022-08-09T21:11:30.571Z,1660079490.571 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0025.lzma.bak
2022-08-09T21:11:30.571Z,1660079490.571 [DataOverHttps](INFO): SBD MOMSN=17097812
2022-08-09T21:11:38.929Z,1660079498.929 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:11:42.444Z,1660079502.444 [NAL9602](INFO): SBD MO Status=1, MOMSN=10175, MT Status=0, MTMSN=0
2022-08-09T21:11:42.444Z,1660079502.444 [NAL9602](INFO): No messages in MT queue
2022-08-09T21:11:49.637Z,1660079509.637 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220809T204328/Express0026.lzma
2022-08-09T21:11:50.589Z,1660079510.589 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:11:50.639Z,1660079510.639 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0026.lzma.bak
2022-08-09T21:11:50.640Z,1660079510.640 [DataOverHttps](INFO): SBD MOMSN=17097815
2022-08-09T21:11:54.210Z,1660079514.210 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:11:54.210Z,1660079514.210 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:11:54.210Z,1660079514.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:12:02.706Z,1660079522.706 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:12:11.141Z,1660079531.141 [DVL_micro](ERROR): Failed to parse:
:B000.00,+00000000.00,+00000000.00,9999.99,00D,+9999.99,+9999.99,+9999.99,+9999.99
2022-08-09T21:12:13.142Z,1660079533.142 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:12:14.429Z,1660079534.429 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:12:26.156Z,1660079546.156 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:12:44.341Z,1660079564.341 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:13:37.639Z,1660079617.639 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:13:42.032Z,1660079622.032 [DVL_micro](ERROR): only read 3 of 4 data items
2022-08-09T21:13:42.032Z,1660079622.032 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+99999.99
2022-08-09T21:13:42.451Z,1660079622.451 [CBIT](INFO): Clearing failed state for component DropWeight
2022-08-09T21:13:42.451Z,1660079622.451 [DropWeight] No Fault, FailCount= 1
2022-08-09T21:14:17.630Z,1660079657.630 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-09T21:14:40.607Z,1660079680.607 [BPC1](INFO): Calculating totals. Valid battery stick count: 26. Valid reserve battery stick count: 0.
2022-08-09T21:14:40.610Z,1660079680.610 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2022-08-09T21:16:54.777Z,1660079814.777 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:16:54.778Z,1660079814.778 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:16:54.778Z,1660079814.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:16:54.778Z,1660079814.778 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:16:55.182Z,1660079815.182 [Default:CheckIn:D] Stopped
2022-08-09T21:16:55.182Z,1660079815.182 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:16:55.571Z,1660079815.571 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.978666 min 
2022-08-09T21:16:55.572Z,1660079815.572 [Default:CheckIn:E] Stopped
2022-08-09T21:16:55.572Z,1660079815.572 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:16:55.572Z,1660079815.572 [Default:CheckIn] Stopped
2022-08-09T21:16:55.572Z,1660079815.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:16:55.572Z,1660079815.572 [Default:CheckIn](INFO): Running loop #6
2022-08-09T21:16:55.572Z,1660079815.572 [Default:CheckIn] Running Loop=6
2022-08-09T21:16:55.572Z,1660079815.572 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:16:55.572Z,1660079815.572 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:16:57.578Z,1660079817.578 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211656.00,A,4313.62919,N,08620.33490,W,0.058,332.21,090822,,,D*72
2022-08-09T21:16:57.580Z,1660079817.580 [NAL9602](INFO): GPS fix at 20220809T211656: (43.227153, -86.338915)
2022-08-09T21:16:57.593Z,1660079817.593 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:16:57.593Z,1660079817.593 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:17:06.085Z,1660079826.085 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20220809T204328/Courier0028.lzma
2022-08-09T21:17:07.087Z,1660079827.087 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0028.lzma.bak
2022-08-09T21:17:07.087Z,1660079827.087 [DataOverHttps](INFO): SBD MOMSN=17097827
2022-08-09T21:17:20.196Z,1660079840.196 [NAL9602](INFO): SBD MO Status=2, MOMSN=10176, MT Status=2, MTMSN=0
2022-08-09T21:17:20.197Z,1660079840.197 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:17:25.781Z,1660079845.781 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220809T204328/Express0029.lzma
2022-08-09T21:17:27.787Z,1660079847.787 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0029.lzma.bak
2022-08-09T21:17:27.787Z,1660079847.787 [DataOverHttps](INFO): SBD MOMSN=17097832
2022-08-09T21:17:30.296Z,1660079850.296 [NAL9602](INFO): SBD MO Status=1, MOMSN=10176, MT Status=0, MTMSN=0
2022-08-09T21:17:30.296Z,1660079850.296 [NAL9602](INFO): No messages in MT queue
2022-08-09T21:17:31.535Z,1660079851.535 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:17:31.535Z,1660079851.535 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:17:31.535Z,1660079851.535 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:18:01.003Z,1660079881.003 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:19:31.505Z,1660079971.505 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T21:19:31.505Z,1660079971.505 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+26.8,0000.0,1489.0,000
2022-08-09T21:19:39.172Z,1660079979.172 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T21:19:39.173Z,1660079979.173 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+26.8,089.0,000
2022-08-09T21:21:56.699Z,1660080116.699 [DataOverHttps](IMPORTANT): SBD MTMSN=20220809T212155
2022-08-09T21:22:07.077Z,1660080127.077 [DataOverHttps](INFO): Received command: get platform_battery_charge
2022-08-09T21:22:07.978Z,1660080127.978 [CommandExec](IMPORTANT): got command get platform_battery_charge
2022-08-09T21:22:07.978Z,1660080127.978 [CommandExec](FAULT): Element has no value
2022-08-09T21:22:32.088Z,1660080152.088 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:22:32.088Z,1660080152.088 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:22:32.088Z,1660080152.088 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:22:32.089Z,1660080152.089 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:22:32.506Z,1660080152.506 [Default:CheckIn:D] Stopped
2022-08-09T21:22:32.507Z,1660080152.507 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:22:32.901Z,1660080152.901 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.600732 min 
2022-08-09T21:22:32.901Z,1660080152.901 [Default:CheckIn:E] Stopped
2022-08-09T21:22:32.902Z,1660080152.902 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:22:32.902Z,1660080152.902 [Default:CheckIn] Stopped
2022-08-09T21:22:32.902Z,1660080152.902 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:22:32.902Z,1660080152.902 [Default:CheckIn](INFO): Running loop #7
2022-08-09T21:22:32.902Z,1660080152.902 [Default:CheckIn] Running Loop=7
2022-08-09T21:22:32.902Z,1660080152.902 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:22:32.902Z,1660080152.902 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:22:34.917Z,1660080154.917 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212234.00,A,4313.62672,N,08620.33537,W,0.019,332.21,090822,,,D*7A
2022-08-09T21:22:34.920Z,1660080154.920 [NAL9602](INFO): GPS fix at 20220809T212234: (43.227112, -86.338923)
2022-08-09T21:22:34.930Z,1660080154.930 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:22:34.930Z,1660080154.930 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:22:40.212Z,1660080160.212 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220809T204328/Courier0031.lzma
2022-08-09T21:22:41.219Z,1660080161.219 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0031.lzma.bak
2022-08-09T21:22:41.219Z,1660080161.219 [DataOverHttps](INFO): SBD MOMSN=17097870
2022-08-09T21:22:51.159Z,1660080171.159 [NAL9602](INFO): SBD MO Status=1, MOMSN=10177, MT Status=0, MTMSN=0
2022-08-09T21:22:51.159Z,1660080171.159 [NAL9602](INFO): No messages in MT queue
2022-08-09T21:22:59.713Z,1660080179.713 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20220809T204328/Express0032.lzma
2022-08-09T21:23:00.454Z,1660080180.454 [DVL_micro](ERROR): Failed to parse:
:WI,+00093,+00795,-00251,+00000,A
2022-08-09T21:23:00.715Z,1660080180.715 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0032.lzma.bak
2022-08-09T21:23:00.715Z,1660080180.715 [DataOverHttps](INFO): SBD MOMSN=17097874
2022-08-09T21:23:18.801Z,1660080198.801 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20220809T204328/Express0035.lzma
2022-08-09T21:23:19.803Z,1660080199.803 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0035.lzma.bak
2022-08-09T21:23:19.803Z,1660080199.803 [DataOverHttps](INFO): SBD MOMSN=17097881
2022-08-09T21:23:21.859Z,1660080201.859 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:23:23.110Z,1660080203.110 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:23:23.111Z,1660080203.111 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:23:23.111Z,1660080203.111 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:28:04.725Z,1660080484.725 [DVL_micro](ERROR): DVL uart error: serial timeout
2022-08-09T21:28:04.726Z,1660080484.726 [DVL_micro] Communications Fault, FailCount= 1
2022-08-09T21:28:04.726Z,1660080484.726 [DVL_micro](ERROR): Communications Fault
2022-08-09T21:28:04.726Z,1660080484.726 [DVL_micro](ERROR): Failed to parse:
2022-08-09T21:28:04.776Z,1660080484.776 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-08-09T21:28:05.134Z,1660080485.134 [DVL_micro](INFO): Powering down
2022-08-09T21:28:05.905Z,1660080485.905 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-08-09T21:28:05.905Z,1660080485.905 [DVL_micro] No Fault, FailCount= 1
2022-08-09T21:28:23.671Z,1660080503.671 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:28:23.671Z,1660080503.671 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:28:23.671Z,1660080503.671 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:28:23.671Z,1660080503.671 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:28:24.062Z,1660080504.062 [Default:CheckIn:D] Stopped
2022-08-09T21:28:24.062Z,1660080504.062 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:28:24.480Z,1660080504.480 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.459981 min 
2022-08-09T21:28:24.481Z,1660080504.481 [Default:CheckIn:E] Stopped
2022-08-09T21:28:24.481Z,1660080504.481 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:28:24.481Z,1660080504.481 [Default:CheckIn] Stopped
2022-08-09T21:28:24.481Z,1660080504.481 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:28:24.481Z,1660080504.481 [Default:CheckIn](INFO): Running loop #8
2022-08-09T21:28:24.481Z,1660080504.481 [Default:CheckIn] Running Loop=8
2022-08-09T21:28:24.481Z,1660080504.481 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:28:24.482Z,1660080504.482 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:28:26.491Z,1660080506.491 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212825.00,A,4313.62768,N,08620.33311,W,0.136,332.21,090822,,,D*74
2022-08-09T21:28:26.493Z,1660080506.493 [NAL9602](INFO): GPS fix at 20220809T212825: (43.227128, -86.338885)
2022-08-09T21:28:26.523Z,1660080506.523 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:28:26.523Z,1660080506.523 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:28:35.329Z,1660080515.329 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220809T204328/Courier0037.lzma
2022-08-09T21:28:36.331Z,1660080516.331 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0037.lzma.bak
2022-08-09T21:28:36.331Z,1660080516.331 [DataOverHttps](INFO): SBD MOMSN=17097911
2022-08-09T21:28:54.525Z,1660080534.525 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220809T204328/Express0038.lzma
2022-08-09T21:28:55.527Z,1660080535.527 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0038.lzma.bak
2022-08-09T21:28:55.528Z,1660080535.528 [DataOverHttps](INFO): SBD MOMSN=17097914
2022-08-09T21:28:58.866Z,1660080538.866 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:28:58.866Z,1660080538.866 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:28:58.866Z,1660080538.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:29:04.078Z,1660080544.078 [NAL9602](INFO): SBD MO Status=2, MOMSN=10178, MT Status=2, MTMSN=0
2022-08-09T21:29:04.078Z,1660080544.078 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:29:21.825Z,1660080561.825 [NAL9602](INFO): SBD MO Status=1, MOMSN=10178, MT Status=0, MTMSN=0
2022-08-09T21:29:21.825Z,1660080561.825 [NAL9602](INFO): No messages in MT queue
2022-08-09T21:29:51.471Z,1660080591.471 [DataOverHttps](IMPORTANT): SBD MTMSN=20220809T212950
2022-08-09T21:29:52.528Z,1660080592.528 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:29:58.897Z,1660080598.897 [DataOverHttps](INFO): Received command: configSet VerticalControl.buoyancyNeutral 400 cc persist
2022-08-09T21:29:59.295Z,1660080599.295 [CommandExec](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 400 cubic_centimeter persist
2022-08-09T21:31:02.035Z,1660080662.035 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2022-08-09T21:31:12.126Z,1660080672.126 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T21:31:12.126Z,1660080672.126 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+27.0,0000.0,148

2022-08-09T21:31:14.944Z,1660080674.944 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T21:31:14.944Z,1660080674.944 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+27.0,0000.0,1489.0,000
2022-08-09T21:31:17.371Z,1660080677.371 [DVL_micro](ERROR): No DVL communication! Re-initializing
2022-08-09T21:31:17.371Z,1660080677.371 [DVL_micro] Communications Fault, FailCount= 1
2022-08-09T21:31:17.371Z,1660080677.371 [DVL_micro](ERROR): Communications Fault
2022-08-09T21:31:17.419Z,1660080677.419 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-08-09T21:31:17.842Z,1660080677.842 [DVL_micro](INFO): Powering down
2022-08-09T21:31:18.608Z,1660080678.608 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-08-09T21:31:18.608Z,1660080678.608 [DVL_micro] No Fault, FailCount= 1
2022-08-09T21:31:46.411Z,1660080706.411 [DataOverHttps](IMPORTANT): SBD MTMSN=20220809T213145
2022-08-09T21:31:53.769Z,1660080713.769 [DataOverHttps](INFO): Received command: maintain control verticalControl.buoyancyAction 950 cc
2022-08-09T21:31:55.031Z,1660080715.031 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 950 cubic_centimeter
2022-08-09T21:31:55.035Z,1660080715.035 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2022-08-09T21:31:55.393Z,1660080715.393 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-08-09T21:33:41.283Z,1660080821.283 [DataOverHttps](IMPORTANT): SBD MTMSN=20220809T213340
2022-08-09T21:33:48.757Z,1660080828.757 [DataOverHttps](INFO): Received command: get platfrom_buoyancy_position
2022-08-09T21:33:50.482Z,1660080830.482 [CommandExec](IMPORTANT): got command get 
2022-08-09T21:33:50.482Z,1660080830.482 [CommandExec](FAULT): Incomplete syntax. Try: help get
2022-08-09T21:33:59.408Z,1660080839.408 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:33:59.409Z,1660080839.409 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:33:59.409Z,1660080839.409 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:33:59.409Z,1660080839.409 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:33:59.819Z,1660080839.819 [Default:CheckIn:D] Stopped
2022-08-09T21:33:59.819Z,1660080839.819 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:34:00.217Z,1660080840.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.055937 min 
2022-08-09T21:34:00.217Z,1660080840.217 [Default:CheckIn:E] Stopped
2022-08-09T21:34:00.217Z,1660080840.217 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:34:00.217Z,1660080840.217 [Default:CheckIn] Stopped
2022-08-09T21:34:00.217Z,1660080840.217 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:34:00.217Z,1660080840.217 [Default:CheckIn](INFO): Running loop #9
2022-08-09T21:34:00.217Z,1660080840.217 [Default:CheckIn] Running Loop=9
2022-08-09T21:34:00.218Z,1660080840.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:34:00.218Z,1660080840.218 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:34:02.245Z,1660080842.245 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213401.00,A,4313.62826,N,08620.33303,W,0.039,0.00,090822,,,D*76
2022-08-09T21:34:02.247Z,1660080842.247 [NAL9602](INFO): GPS fix at 20220809T213401: (43.227138, -86.338884)
2022-08-09T21:34:02.258Z,1660080842.258 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:34:02.258Z,1660080842.258 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:34:05.061Z,1660080845.061 [DVL_micro](ERROR): only read 3 of 4 data items
2022-08-09T21:34:05.061Z,1660080845.061 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2022-08-09T21:34:10.773Z,1660080850.773 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220809T204328/Courier0040.lzma
2022-08-09T21:34:11.775Z,1660080851.775 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0040.lzma.bak
2022-08-09T21:34:11.775Z,1660080851.775 [DataOverHttps](INFO): SBD MOMSN=17097932
2022-08-09T21:34:23.233Z,1660080863.233 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2022-08-09T21:34:23.233Z,1660080863.233 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:34:30.882Z,1660080870.882 [DataOverHttps](INFO): Sending 325 bytes from file Logs/20220809T204328/Express0041.lzma
2022-08-09T21:34:31.883Z,1660080871.883 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0041.lzma.bak
2022-08-09T21:34:31.883Z,1660080871.883 [DataOverHttps](INFO): SBD MOMSN=17097935
2022-08-09T21:34:45.088Z,1660080885.088 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2022-08-09T21:34:45.088Z,1660080885.088 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:34:51.137Z,1660080891.137 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20220809T204328/Express0042.lzma
2022-08-09T21:34:52.139Z,1660080892.139 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0042.lzma.bak
2022-08-09T21:34:52.139Z,1660080892.139 [DataOverHttps](INFO): SBD MOMSN=17097945
2022-08-09T21:34:55.591Z,1660080895.591 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:34:55.591Z,1660080895.591 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:34:55.591Z,1660080895.591 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:36:11.509Z,1660080971.509 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2022-08-09T21:36:11.509Z,1660080971.509 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:36:58.776Z,1660081018.776 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2022-08-09T21:36:58.777Z,1660081018.777 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:38:03.825Z,1660081083.825 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2022-08-09T21:38:03.825Z,1660081083.825 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:38:50.683Z,1660081130.683 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2022-08-09T21:38:50.683Z,1660081130.683 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:39:07.651Z,1660081147.651 [NAL9602](INFO): SBD MO Status=1, MOMSN=10179, MT Status=0, MTMSN=0
2022-08-09T21:39:07.651Z,1660081147.651 [NAL9602](INFO): No messages in MT queue
2022-08-09T21:39:38.359Z,1660081178.359 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:39:56.139Z,1660081196.139 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:39:56.140Z,1660081196.140 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:39:56.140Z,1660081196.140 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:39:56.140Z,1660081196.140 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:39:56.549Z,1660081196.549 [Default:CheckIn:D] Stopped
2022-08-09T21:39:56.549Z,1660081196.549 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:39:56.962Z,1660081196.962 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.001440 min 
2022-08-09T21:39:56.962Z,1660081196.962 [Default:CheckIn:E] Stopped
2022-08-09T21:39:56.962Z,1660081196.962 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:39:56.962Z,1660081196.962 [Default:CheckIn] Stopped
2022-08-09T21:39:56.963Z,1660081196.963 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:39:56.963Z,1660081196.963 [Default:CheckIn](INFO): Running loop #10
2022-08-09T21:39:56.963Z,1660081196.963 [Default:CheckIn] Running Loop=10
2022-08-09T21:39:56.963Z,1660081196.963 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:39:56.963Z,1660081196.963 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:39:58.961Z,1660081198.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213958.00,A,4313.62869,N,08620.33522,W,0.058,0.00,090822,,,D*7E
2022-08-09T21:39:58.963Z,1660081198.963 [NAL9602](INFO): GPS fix at 20220809T213958: (43.227145, -86.338920)
2022-08-09T21:39:58.974Z,1660081198.974 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:39:58.974Z,1660081198.974 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:40:07.337Z,1660081207.337 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220809T204328/Courier0045.lzma
2022-08-09T21:40:08.339Z,1660081208.339 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0045.lzma.bak
2022-08-09T21:40:08.339Z,1660081208.339 [DataOverHttps](INFO): SBD MOMSN=17097955
2022-08-09T21:40:09.061Z,1660081209.061 [NAL9602](INFO): SBD MO Status=1, MOMSN=10180, MT Status=0, MTMSN=0
2022-08-09T21:40:09.061Z,1660081209.061 [NAL9602](INFO): No messages in MT queue
2022-08-09T21:40:26.537Z,1660081226.537 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220809T204328/Express0047.lzma
2022-08-09T21:40:27.251Z,1660081227.251 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2022-08-09T21:40:27.539Z,1660081227.539 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0047.lzma.bak
2022-08-09T21:40:27.540Z,1660081227.540 [DataOverHttps](INFO): SBD MOMSN=17097958
2022-08-09T21:40:30.906Z,1660081230.906 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:40:30.906Z,1660081230.906 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:40:30.906Z,1660081230.906 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:40:39.731Z,1660081239.731 [DataOverHttps](IMPORTANT): SBD MTMSN=20220809T214038
2022-08-09T21:40:39.780Z,1660081239.780 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:40:48.233Z,1660081248.233 [DataOverHttps](INFO): Received command: get platform_buoyancy_position
2022-08-09T21:40:49.168Z,1660081249.168 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2022-08-09T21:40:49.168Z,1660081249.168 [CommandExec](IMPORTANT): platform_buoyancy_position 958.203247 cc
2022-08-09T21:42:45.417Z,1660081365.417 [DVL_micro](ERROR): only read 3 of 4 data items
2022-08-09T21:42:45.417Z,1660081365.417 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2022-08-09T21:43:43.990Z,1660081423.990 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-08-09T21:43:43.990Z,1660081423.990 [DropWeight] Hardware Fault, FailCount= 1
2022-08-09T21:43:43.991Z,1660081423.991 [DropWeight](ERROR): Hardware Fault
2022-08-09T21:43:44.044Z,1660081424.044 [CBIT](INFO): Critical error at 20220809T214343
2022-08-09T21:43:44.047Z,1660081424.047 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-08-09T21:43:44.047Z,1660081424.047 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-08-09T21:43:44.418Z,1660081424.418 [CBIT](INFO): Critical error at 20220809T214344
2022-08-09T21:45:31.494Z,1660081531.494 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:45:31.494Z,1660081531.494 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:45:31.494Z,1660081531.494 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:45:31.494Z,1660081531.494 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:45:31.872Z,1660081531.872 [Default:CheckIn:D] Stopped
2022-08-09T21:45:31.872Z,1660081531.872 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:45:32.274Z,1660081532.274 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.590165 min 
2022-08-09T21:45:32.274Z,1660081532.274 [Default:CheckIn:E] Stopped
2022-08-09T21:45:32.274Z,1660081532.274 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:45:32.274Z,1660081532.274 [Default:CheckIn] Stopped
2022-08-09T21:45:32.274Z,1660081532.274 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:45:32.274Z,1660081532.274 [Default:CheckIn](INFO): Running loop #11
2022-08-09T21:45:32.274Z,1660081532.274 [Default:CheckIn] Running Loop=11
2022-08-09T21:45:32.274Z,1660081532.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:45:32.274Z,1660081532.274 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:45:34.296Z,1660081534.296 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214533.00,A,4313.62919,N,08620.33173,W,0.019,0.00,090822,,,D*7B
2022-08-09T21:45:34.298Z,1660081534.298 [NAL9602](INFO): GPS fix at 20220809T214533: (43.227153, -86.338862)
2022-08-09T21:45:34.309Z,1660081534.309 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:45:34.309Z,1660081534.309 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:45:43.401Z,1660081543.401 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20220809T204328/Courier0049.lzma
2022-08-09T21:45:44.403Z,1660081544.403 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0049.lzma.bak
2022-08-09T21:45:44.404Z,1660081544.404 [DataOverHttps](INFO): SBD MOMSN=17097974
2022-08-09T21:46:01.761Z,1660081561.761 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220809T204328/Courier0052.lzma
2022-08-09T21:46:02.764Z,1660081562.764 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0052.lzma.bak
2022-08-09T21:46:02.764Z,1660081562.764 [DataOverHttps](INFO): SBD MOMSN=17097980
2022-08-09T21:46:21.097Z,1660081581.097 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20220809T204328/Express0050.lzma
2022-08-09T21:46:22.099Z,1660081582.099 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0050.lzma.bak
2022-08-09T21:46:22.100Z,1660081582.100 [DataOverHttps](INFO): SBD MOMSN=17097989
2022-08-09T21:46:40.941Z,1660081600.941 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20220809T204328/Express0053.lzma
2022-08-09T21:46:41.943Z,1660081601.943 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0053.lzma.bak
2022-08-09T21:46:41.944Z,1660081601.944 [DataOverHttps](INFO): SBD MOMSN=17097995
2022-08-09T21:46:45.432Z,1660081605.432 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:46:45.432Z,1660081605.432 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:46:45.433Z,1660081605.433 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:47:45.592Z,1660081665.592 [NAL9602](INFO): SBD MO Status=1, MOMSN=10181, MT Status=0, MTMSN=0
2022-08-09T21:47:45.592Z,1660081665.592 [NAL9602](INFO): No messages in MT queue
2022-08-09T21:48:16.295Z,1660081696.295 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:51:46.001Z,1660081906.001 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:51:46.002Z,1660081906.002 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:51:46.002Z,1660081906.002 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:51:46.002Z,1660081906.002 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:51:46.404Z,1660081906.404 [Default:CheckIn:D] Stopped
2022-08-09T21:51:46.404Z,1660081906.404 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:51:46.809Z,1660081906.809 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.832365 min 
2022-08-09T21:51:46.809Z,1660081906.809 [Default:CheckIn:E] Stopped
2022-08-09T21:51:46.809Z,1660081906.809 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:51:46.809Z,1660081906.809 [Default:CheckIn] Stopped
2022-08-09T21:51:46.809Z,1660081906.809 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:51:46.810Z,1660081906.810 [Default:CheckIn](INFO): Running loop #12
2022-08-09T21:51:46.810Z,1660081906.810 [Default:CheckIn] Running Loop=12
2022-08-09T21:51:46.810Z,1660081906.810 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:51:46.810Z,1660081906.810 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:51:48.815Z,1660081908.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215148.00,A,4313.62740,N,08620.33619,W,0.136,305.98,090822,,,D*70
2022-08-09T21:51:48.828Z,1660081908.828 [NAL9602](INFO): GPS fix at 20220809T215148: (43.227123, -86.338937)
2022-08-09T21:51:48.839Z,1660081908.839 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:51:48.839Z,1660081908.839 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:51:57.617Z,1660081917.617 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220809T204328/Courier0055.lzma
2022-08-09T21:51:58.621Z,1660081918.621 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Courier0055.lzma.bak
2022-08-09T21:51:58.621Z,1660081918.621 [DataOverHttps](INFO): SBD MOMSN=17098002
2022-08-09T21:52:10.701Z,1660081930.701 [DVL_micro](ERROR): DVL uart error: serial timeout
2022-08-09T21:52:10.702Z,1660081930.702 [DVL_micro] Communications Fault, FailCount= 1
2022-08-09T21:52:10.702Z,1660081930.702 [DVL_micro](ERROR): Communications Fault
2022-08-09T21:52:10.702Z,1660081930.702 [DVL_micro](ERROR): Failed to parse:
2022-08-09T21:52:10.760Z,1660081930.760 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-08-09T21:52:11.106Z,1660081931.106 [DVL_micro](INFO): Powering down
2022-08-09T21:52:11.877Z,1660081931.877 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-08-09T21:52:11.877Z,1660081931.877 [DVL_micro] No Fault, FailCount= 1
2022-08-09T21:52:13.460Z,1660081933.460 [NAL9602](INFO): SBD MO Status=2, MOMSN=10182, MT Status=2, MTMSN=0
2022-08-09T21:52:13.460Z,1660081933.460 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:52:16.789Z,1660081936.789 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220809T204328/Express0056.lzma
2022-08-09T21:52:17.791Z,1660081937.791 [DataOverHttps](INFO): Moved sent file to Logs/20220809T204328/Express0056.lzma.bak
2022-08-09T21:52:17.792Z,1660081937.792 [DataOverHttps](INFO): SBD MOMSN=17098005
2022-08-09T21:52:21.149Z,1660081941.149 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:52:21.149Z,1660081941.149 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:52:21.149Z,1660081941.149 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:52:30.832Z,1660081950.832 [NAL9602](INFO): SBD MO Status=2, MOMSN=10182, MT Status=2, MTMSN=0
2022-08-09T21:52:30.832Z,1660081950.832 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:53:17.307Z,1660081997.307 [NAL9602](INFO): SBD MO Status=2, MOMSN=10182, MT Status=2, MTMSN=0
2022-08-09T21:53:17.307Z,1660081997.307 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:55:00.320Z,1660082100.320 [NAL9602](INFO): SBD MO Status=2, MOMSN=10182, MT Status=2, MTMSN=0
2022-08-09T21:55:00.321Z,1660082100.321 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T21:55:10.009Z,1660082110.009 [NAL9602](INFO): SBD MO Status=1, MOMSN=10182, MT Status=0, MTMSN=0
2022-08-09T21:55:10.009Z,1660082110.009 [NAL9602](INFO): No messages in MT queue
2022-08-09T21:55:40.729Z,1660082140.729 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:56:58.690Z,1660082218.690 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T21:56:58.690Z,1660082218.690 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+27.3,0000.0,1489.0,,+02515,+02830,-00210,+00000,A
2022-08-09T21:57:01.122Z,1660082221.122 [DVL_micro](ERROR): only read 3 of 4 data items
2022-08-09T21:57:01.122Z,1660082221.122 [DVL_micro](ERROR): Failed to parse:
:BI,+01474,00161,+000D,+00000000.0000.00,+00000000.00,9999.99,000.00
2022-08-09T21:57:05.959Z,1660082225.959 [DVL_micro](ERROR): only read 3 of 4 data items
2022-08-09T21:57:05.960Z,1660082225.960 [DVL_micro](ERROR): Failed to parse:
:BI,+01241,+01067,-0000,I
2022-08-09T21:57:11.293Z,1660082231.293 [DVL_micro](ERROR): DVL uart error: serial timeout
2022-08-09T21:57:11.293Z,1660082231.293 [DVL_micro] Communications Fault, FailCount= 1
2022-08-09T21:57:11.294Z,1660082231.294 [DVL_micro](ERROR): Communications Fault
2022-08-09T21:57:11.294Z,1660082231.294 [DVL_micro](ERROR): Failed to parse:
2022-08-09T21:57:11.345Z,1660082231.345 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-08-09T21:57:11.686Z,1660082231.686 [DVL_micro](INFO): Powering down
2022-08-09T21:57:12.202Z,1660082232.202 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.639603
2022-08-09T21:57:12.438Z,1660082232.438 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-08-09T21:57:12.438Z,1660082232.438 [DVL_micro] No Fault, FailCount= 1
2022-08-09T21:57:21.724Z,1660082241.724 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T21:57:21.724Z,1660082241.724 [Default:CheckIn:C.Wait] Stopped
2022-08-09T21:57:21.724Z,1660082241.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T21:57:21.724Z,1660082241.724 [Default:CheckIn:D] Running Loop=1
2022-08-09T21:57:22.128Z,1660082242.128 [Default:CheckIn:D] Stopped
2022-08-09T21:57:22.128Z,1660082242.128 [Default:CheckIn:E] Running Loop=1
2022-08-09T21:57:22.540Z,1660082242.540 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.427767 min 
2022-08-09T21:57:22.540Z,1660082242.540 [Default:CheckIn:E] Stopped
2022-08-09T21:57:22.540Z,1660082242.540 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T21:57:22.540Z,1660082242.540 [Default:CheckIn] Stopped
2022-08-09T21:57:22.540Z,1660082242.540 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T21:57:22.541Z,1660082242.541 [Default:CheckIn](INFO): Running loop #13
2022-08-09T21:57:22.541Z,1660082242.541 [Default:CheckIn] Running Loop=13
2022-08-09T21:57:22.541Z,1660082242.541 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T21:57:22.541Z,1660082242.541 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T21:57:24.539Z,1660082244.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215723.00,A,4313.62737,N,08620.33576,W,0.019,222.24,090822,,,D*7E
2022-08-09T21:57:24.541Z,1660082244.541 [NAL9602](INFO): GPS fix at 20220809T215723: (43.227123, -86.338929)
2022-08-09T21:57:24.551Z,1660082244.551 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T21:57:24.551Z,1660082244.551 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T21:57:45.354Z,1660082265.354 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T21:57:50.430Z,1660082270.430 [NAL9602](INFO): SBD MO Status=1, MOMSN=10183, MT Status=0, MTMSN=0
2022-08-09T21:57:50.482Z,1660082270.482 [NAL9602](INFO): Sent 72 bytes from file Logs/20220809T204328/Courier0058.lzma
2022-08-09T21:57:50.482Z,1660082270.482 [NAL9602](INFO): Packets left to send: 0
2022-08-09T21:58:01.456Z,1660082281.456 [NAL9602](INFO): SBD MO Status=1, MOMSN=10184, MT Status=0, MTMSN=0
2022-08-09T21:58:01.511Z,1660082281.511 [NAL9602](INFO): Sent 129 bytes from file Logs/20220809T204328/Express0059.lzma
2022-08-09T21:58:01.511Z,1660082281.511 [NAL9602](INFO): Packets left to send: 0
2022-08-09T21:58:09.541Z,1660082289.541 [NAL9602](INFO): SBD MO Status=0, MOMSN=10185, MT Status=0, MTMSN=0
2022-08-09T21:58:09.633Z,1660082289.633 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T21:58:09.633Z,1660082289.633 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-09T21:58:09.633Z,1660082289.633 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-09T21:58:20.498Z,1660082300.498 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T21:58:40.252Z,1660082320.252 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-09T21:58:44.304Z,1660082324.304 [CBIT](INFO): Clearing failed state for component DropWeight
2022-08-09T21:58:44.304Z,1660082324.304 [DropWeight] No Fault, FailCount= 1
2022-08-09T21:58:55.638Z,1660082335.638 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T21:59:30.779Z,1660082370.779 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:00:05.918Z,1660082405.918 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:00:16.040Z,1660082416.040 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T22:00:16.040Z,1660082416.040 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+27.3,0000.0,1489.0,,+03981,-00201,-00869,+00000,A
2022-08-09T22:00:21.254Z,1660082421.254 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T22:00:21.254Z,1660082421.254 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+27.3,00000,000
2022-08-09T22:00:41.058Z,1660082441.058 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:01:16.202Z,1660082476.202 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:01:51.342Z,1660082511.342 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:02:26.490Z,1660082546.490 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:03:01.647Z,1660082581.647 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:03:10.126Z,1660082590.126 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-09T22:03:10.127Z,1660082590.127 [Default:CheckIn:C.Wait] Stopped
2022-08-09T22:03:10.127Z,1660082590.127 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-09T22:03:10.127Z,1660082590.127 [Default:CheckIn:D] Running Loop=1
2022-08-09T22:03:10.546Z,1660082590.546 [Default:CheckIn:D] Stopped
2022-08-09T22:03:10.546Z,1660082590.546 [Default:CheckIn:E] Running Loop=1
2022-08-09T22:03:10.948Z,1660082590.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.234725 min 
2022-08-09T22:03:10.948Z,1660082590.948 [Default:CheckIn:E] Stopped
2022-08-09T22:03:10.948Z,1660082590.948 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-09T22:03:10.948Z,1660082590.948 [Default:CheckIn] Stopped
2022-08-09T22:03:10.948Z,1660082590.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T22:03:10.948Z,1660082590.948 [Default:CheckIn](INFO): Running loop #14
2022-08-09T22:03:10.948Z,1660082590.948 [Default:CheckIn] Running Loop=14
2022-08-09T22:03:10.949Z,1660082590.949 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-09T22:03:10.949Z,1660082590.949 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-09T22:03:12.948Z,1660082592.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220312.00,A,4313.62495,N,08620.33459,W,0.039,284.69,090822,,,D*7E
2022-08-09T22:03:12.950Z,1660082592.950 [NAL9602](INFO): GPS fix at 20220809T220312: (43.227083, -86.338910)
2022-08-09T22:03:12.961Z,1660082592.961 [Default:CheckIn:Read_GPS] Stopped
2022-08-09T22:03:12.961Z,1660082592.961 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-09T22:03:36.786Z,1660082616.786 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:03:47.691Z,1660082627.691 [NAL9602](INFO): SBD MO Status=2, MOMSN=10186, MT Status=2, MTMSN=0
2022-08-09T22:03:47.691Z,1660082627.691 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T22:04:11.926Z,1660082651.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:04:26.533Z,1660082666.533 [NAL9602](INFO): SBD MO Status=2, MOMSN=10186, MT Status=2, MTMSN=0
2022-08-09T22:04:26.533Z,1660082666.533 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T22:04:47.070Z,1660082687.070 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:04:58.400Z,1660082698.400 [NAL9602](INFO): SBD MO Status=2, MOMSN=10186, MT Status=2, MTMSN=0
2022-08-09T22:04:58.401Z,1660082698.401 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T22:05:22.210Z,1660082722.210 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:05:27.765Z,1660082727.765 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-08-09T22:05:27.765Z,1660082727.765 [DVL_micro](ERROR): Failed to parse::TS,00000000000000,35,0000.0,1489.0,000
2022-08-09T22:05:44.325Z,1660082744.325 [NAL9602](INFO): SBD MO Status=2, MOMSN=10186, MT Status=2, MTMSN=0
2022-08-09T22:05:44.325Z,1660082744.325 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-09T22:05:55.395Z,1660082755.395 [DVL_micro](ERROR): only read 3 of 4 data items
2022-08-09T22:05:55.395Z,1660082755.395 [DVL_micro](ERROR): Failed to parse:
:BI,+01452,-01362,-00764I
2022-08-09T22:05:57.382Z,1660082757.382 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-09T22:06:02.671Z,1660082762.671 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=10186, MT Status=1, MTMSN=799
2022-08-09T22:06:02.734Z,1660082762.734 [NAL9602](INFO): Sent 71 bytes from file Logs/20220809T204328/Courier0061.lzma
2022-08-09T22:06:02.734Z,1660082762.734 [NAL9602](INFO): Packets left to send: 0
2022-08-09T22:06:03.202Z,1660082763.202 [NAL9602](INFO): Received command: restart sys
2022-08-09T22:06:03.291Z,1660082763.291 [CommandExec](IMPORTANT): got command restart system
2022-08-09T22:06:05.418Z,1660082765.418 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:05.418Z,1660082765.418 [CommandExec](INFO): Uninitializing the command executive.
2022-08-09T22:06:05.418Z,1660082765.418 [CommandExec](INFO): Uninitializing the command scheduler.
2022-08-09T22:06:05.418Z,1660082765.418 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:05.446Z,1660082765.446 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-08-09T22:06:05.446Z,1660082765.446 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-08-09T22:06:05.446Z,1660082765.446 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:05.447Z,1660082765.447 [NavChartDb](INFO): Join timeout helper Thread ID is 972
2022-08-09T22:06:05.538Z,1660082765.538 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:05.538Z,1660082765.538 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:05.545Z,1660082765.545 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-08-09T22:06:05.546Z,1660082765.546 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:05.546Z,1660082765.546 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 973
2022-08-09T22:06:05.918Z,1660082765.918 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:05.919Z,1660082765.919 [WetLabsBB2FL](INFO): Powering down
2022-08-09T22:06:05.920Z,1660082765.920 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:05.925Z,1660082765.925 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-08-09T22:06:05.926Z,1660082765.926 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:05.926Z,1660082765.926 [CTD_Seabird](INFO): Join timeout helper Thread ID is 974
2022-08-09T22:06:06.658Z,1660082766.658 [CTD_Seabird](INFO): Powering down
2022-08-09T22:06:06.670Z,1660082766.670 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:06.670Z,1660082766.670 [CTD_Seabird](INFO): Powering down
2022-08-09T22:06:06.682Z,1660082766.682 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:06.694Z,1660082766.694 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-08-09T22:06:06.694Z,1660082766.694 [DAT ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:06.694Z,1660082766.694 [DAT](INFO): Join timeout helper Thread ID is 975
2022-08-09T22:06:06.826Z,1660082766.826 [DAT](INFO): Powering down
2022-08-09T22:06:06.827Z,1660082766.827 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:06.827Z,1660082766.827 [DAT](INFO): Powering down
2022-08-09T22:06:06.828Z,1660082766.828 [DAT ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:06.833Z,1660082766.833 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-08-09T22:06:06.834Z,1660082766.834 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:06.834Z,1660082766.834 [Radio_Surface](INFO): Join timeout helper Thread ID is 976
2022-08-09T22:06:07.034Z,1660082767.034 [Radio_Surface](INFO): Powering down
2022-08-09T22:06:07.035Z,1660082767.035 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:07.035Z,1660082767.035 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:07.051Z,1660082767.051 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-08-09T22:06:07.051Z,1660082767.051 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:07.051Z,1660082767.051 [Onboard](INFO): Join timeout helper Thread ID is 977
2022-08-09T22:06:11.274Z,1660082771.274 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:11.274Z,1660082771.274 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.278Z,1660082771.278 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-08-09T22:06:11.278Z,1660082771.278 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.279Z,1660082771.279 [DataOverHttps](INFO): Join timeout helper Thread ID is 978
2022-08-09T22:06:11.458Z,1660082771.458 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:11.458Z,1660082771.458 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.478Z,1660082771.478 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-08-09T22:06:11.478Z,1660082771.478 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.479Z,1660082771.479 [BackseatComponent](INFO): Join timeout helper Thread ID is 979
2022-08-09T22:06:11.566Z,1660082771.566 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:11.566Z,1660082771.566 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.578Z,1660082771.578 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-08-09T22:06:11.578Z,1660082771.578 [logger ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.579Z,1660082771.579 [logger](INFO): Join timeout helper Thread ID is 980
2022-08-09T22:06:11.609Z,1660082771.609 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:11.610Z,1660082771.610 [logger ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.618Z,1660082771.618 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-08-09T22:06:11.618Z,1660082771.618 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.619Z,1660082771.619 [CommandLine](INFO): Join timeout helper Thread ID is 981
2022-08-09T22:06:11.642Z,1660082771.642 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:11.642Z,1660082771.642 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.658Z,1660082771.658 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-08-09T22:06:11.658Z,1660082771.658 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.659Z,1660082771.659 [CommandExec](INFO): Join timeout helper Thread ID is 982
2022-08-09T22:06:11.660Z,1660082771.660 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-08-09T22:06:11.660Z,1660082771.660 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:11.660Z,1660082771.660 [controlThread](INFO): Join timeout helper Thread ID is 983
2022-08-09T22:06:12.589Z,1660082772.589 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-09T22:06:12.589Z,1660082772.589 [controlThread](DEBUG): Uninitializing ControlThread
2022-08-09T22:06:12.589Z,1660082772.589 [AHRS_M2](INFO): Powering down
2022-08-09T22:06:12.734Z,1660082772.734 [DVL_micro](INFO): Powering down
2022-08-09T22:06:12.735Z,1660082772.735 [NAL9602](INFO): Powering down
2022-08-09T22:06:12.738Z,1660082772.738 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-08-09T22:06:12.739Z,1660082772.739 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-08-09T22:06:12.739Z,1660082772.739 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-08-09T22:06:12.740Z,1660082772.740 [MissionManager](INFO): Uninitializing Mission Default
2022-08-09T22:06:12.740Z,1660082772.740 [Default] Stopped
2022-08-09T22:06:12.740Z,1660082772.740 [Default](DEBUG): Aggregate::uninitialize Default
2022-08-09T22:06:12.740Z,1660082772.740 [Default:B.GoToSurface] Stopped
2022-08-09T22:06:12.740Z,1660082772.740 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-09T22:06:12.740Z,1660082772.740 [Default:CheckIn] Stopped
2022-08-09T22:06:12.740Z,1660082772.740 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-09T22:06:12.740Z,1660082772.740 [Default:CheckIn:Read_Iridium] Stopped
2022-08-09T22:06:12.743Z,1660082772.743 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-08-09T22:06:12.744Z,1660082772.744 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-08-09T22:06:12.744Z,1660082772.744 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-08-09T22:06:12.744Z,1660082772.744 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-08-09T22:06:12.745Z,1660082772.745 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-08-09T22:06:12.745Z,1660082772.745 [BuoyancyServo](INFO): Powering down
2022-08-09T22:06:12.758Z,1660082772.758 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-08-09T22:06:12.758Z,1660082772.758 [ElevatorServo](INFO): Powering down
2022-08-09T22:06:12.759Z,1660082772.759 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-08-09T22:06:12.759Z,1660082772.759 [RudderServo](INFO): Powering down
2022-08-09T22:06:12.760Z,1660082772.760 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-08-09T22:06:12.760Z,1660082772.760 [ThrusterHE](INFO): Powering down
2022-08-09T22:06:12.761Z,1660082772.761 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-08-09T22:06:12.761Z,1660082772.761 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-08-09T22:06:12.762Z,1660082772.762 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-08-09T22:06:12.762Z,1660082772.762 [CBIT](DEBUG): Powering off loads.
2022-08-09T22:06:12.773Z,1660082772.773 [CBIT](DEBUG): Disabling WDT.
2022-08-09T22:06:12.785Z,1660082772.785 [CBIT](DEBUG): Opening all GF detection circuits.
2022-08-09T22:06:12.786Z,1660082772.786 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:12.835Z,1660082772.835 [DAT ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:12.840Z,1660082772.840 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:12.842Z,1660082772.842 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:12.850Z,1660082772.850 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:12.896Z,1660082772.896 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:12.948Z,1660082772.948 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:12.953Z,1660082772.953 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:12.977Z,1660082772.977 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-09T22:06:13.025Z,1660082773.025 [logger ThreadHandler](INFO): Thread cancelled.