2024-04-25T19:12:04.554Z,1714072324.554 [Supervisor](DEBUG): Initializing supervisor.
2024-04-25T19:12:04.558Z,1714072324.558 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-04-25T19:12:04.558Z,1714072324.558 [SyncHandler](INFO): Protected caller Thread ID is 834
2024-04-25T19:12:04.559Z,1714072324.559 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-04-25T19:12:04.560Z,1714072324.560 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-04-25T19:12:04.560Z,1714072324.560 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835
2024-04-25T19:12:04.564Z,1714072324.564 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-04-25T19:12:04.583Z,1714072324.583 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-04-25T19:12:04.584Z,1714072324.584 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-04-25T19:12:04.584Z,1714072324.584 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836
2024-04-25T19:12:04.589Z,1714072324.589 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-04-25T19:12:04.590Z,1714072324.590 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-04-25T19:12:04.590Z,1714072324.590 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
2024-04-25T19:12:04.592Z,1714072324.592 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-04-25T19:12:04.593Z,1714072324.593 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-04-25T19:12:04.593Z,1714072324.593 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
2024-04-25T19:12:04.597Z,1714072324.597 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-04-25T19:12:04.598Z,1714072324.598 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-04-25T19:12:04.602Z,1714072324.602 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-04-25T19:12:04.970Z,1714072324.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-04-25T19:12:04.972Z,1714072324.972 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-04-25T19:12:05.853Z,1714072325.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-04-25T19:12:05.855Z,1714072325.855 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-04-25T19:12:05.979Z,1714072325.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-04-25T19:12:05.981Z,1714072325.981 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-04-25T19:12:06.572Z,1714072326.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-04-25T19:12:06.573Z,1714072326.573 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-04-25T19:12:06.684Z,1714072326.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-04-25T19:12:06.686Z,1714072326.686 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-04-25T19:12:06.781Z,1714072326.781 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-04-25T19:12:07.234Z,1714072327.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-04-25T19:12:07.236Z,1714072327.236 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-04-25T19:12:07.484Z,1714072327.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-04-25T19:12:07.486Z,1714072327.486 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-04-25T19:12:07.643Z,1714072327.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-04-25T19:12:08.279Z,1714072328.279 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-04-25T19:12:08.794Z,1714072328.794 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-04-25T19:12:08.796Z,1714072328.796 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-04-25T19:12:09.391Z,1714072329.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-04-25T19:12:09.392Z,1714072329.392 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-04-25T19:12:09.807Z,1714072329.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-04-25T19:12:09.808Z,1714072329.808 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-04-25T19:12:09.901Z,1714072329.901 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-04-25T19:12:10.125Z,1714072330.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-04-25T19:12:10.127Z,1714072330.127 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-04-25T19:12:10.386Z,1714072330.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-04-25T19:12:10.387Z,1714072330.387 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-04-25T19:12:10.716Z,1714072330.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-04-25T19:12:10.718Z,1714072330.718 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2024-04-25T19:12:10.722Z,1714072330.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2024-04-25T19:12:10.846Z,1714072330.846 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2024-04-25T19:12:11.016Z,1714072331.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2024-04-25T19:12:11.115Z,1714072331.115 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2024-04-25T19:12:11.213Z,1714072331.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2024-04-25T19:12:11.338Z,1714072331.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2024-04-25T19:12:11.461Z,1714072331.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2024-04-25T19:12:11.744Z,1714072331.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-04-25T19:12:11.745Z,1714072331.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2024-04-25T19:12:11.897Z,1714072331.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2024-04-25T19:12:12.004Z,1714072332.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2024-04-25T19:12:12.111Z,1714072332.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2024-04-25T19:12:12.228Z,1714072332.228 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-04-25T19:12:12.242Z,1714072332.242 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-04-25T19:12:12.399Z,1714072332.399 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-04-25T19:12:12.400Z,1714072332.400 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-04-25T19:12:12.416Z,1714072332.416 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-04-25T19:12:12.418Z,1714072332.418 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-04-25T19:12:12.492Z,1714072332.492 [DepthRateCalculator] Loaded
2024-04-25T19:12:12.492Z,1714072332.492 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-04-25T19:12:12.498Z,1714072332.498 [PitchRateCalculator] Loaded
2024-04-25T19:12:12.498Z,1714072332.498 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-04-25T19:12:12.509Z,1714072332.509 [SpeedCalculator] Loaded
2024-04-25T19:12:12.509Z,1714072332.509 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-04-25T19:12:12.514Z,1714072332.514 [YawRateCalculator] Loaded
2024-04-25T19:12:12.514Z,1714072332.514 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-04-25T19:12:12.536Z,1714072332.536 [ElevatorOffsetCalculator] Loaded
2024-04-25T19:12:12.537Z,1714072332.537 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-04-25T19:12:12.537Z,1714072332.537 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-04-25T19:12:12.538Z,1714072332.538 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-04-25T19:12:12.610Z,1714072332.610 [VerticalControl](DEBUG): Construct VerticalControl.
2024-04-25T19:12:12.669Z,1714072332.669 [VerticalControl] Loaded
2024-04-25T19:12:12.669Z,1714072332.669 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-04-25T19:12:12.672Z,1714072332.672 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-04-25T19:12:12.720Z,1714072332.720 [HorizontalControl] Loaded
2024-04-25T19:12:12.720Z,1714072332.720 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-04-25T19:12:12.723Z,1714072332.723 [SpeedControl](DEBUG): Construct SpeedControl.
2024-04-25T19:12:12.726Z,1714072332.726 [SpeedControl] Loaded
2024-04-25T19:12:12.726Z,1714072332.726 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-04-25T19:12:12.729Z,1714072332.729 [LoopControl](DEBUG): Construct LoopControl.
2024-04-25T19:12:12.729Z,1714072332.729 [LoopControl] Loaded
2024-04-25T19:12:12.730Z,1714072332.730 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-04-25T19:12:12.730Z,1714072332.730 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-04-25T19:12:12.731Z,1714072332.731 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-04-25T19:12:13.421Z,1714072333.421 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-04-25T19:12:13.422Z,1714072333.422 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-04-25T19:12:13.436Z,1714072333.436 [NavChart] Loaded
2024-04-25T19:12:13.436Z,1714072333.436 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-04-25T19:12:13.442Z,1714072333.442 [UniversalFixResidualReporter] Loaded
2024-04-25T19:12:13.443Z,1714072333.443 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-04-25T19:12:13.443Z,1714072333.443 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-04-25T19:12:13.444Z,1714072333.444 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-04-25T19:12:13.673Z,1714072333.673 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-04-25T19:12:13.674Z,1714072333.674 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-04-25T19:12:14.487Z,1714072334.487 [AHRS_M2] Loaded
2024-04-25T19:12:14.487Z,1714072334.487 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-04-25T19:12:14.739Z,1714072334.739 [BackseatComponent] Loaded
2024-04-25T19:12:14.739Z,1714072334.739 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-04-25T19:12:14.740Z,1714072334.740 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409224E0
2024-04-25T19:12:14.740Z,1714072334.740 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2024-04-25T19:12:14.743Z,1714072334.743 [LcmUniversalReporter] Loaded
2024-04-25T19:12:14.744Z,1714072334.744 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-04-25T19:12:15.575Z,1714072335.575 [BPC1] Loaded
2024-04-25T19:12:15.576Z,1714072335.576 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-04-25T19:12:15.721Z,1714072335.721 [DataOverHttps] Loaded
2024-04-25T19:12:15.721Z,1714072335.721 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-04-25T19:12:15.722Z,1714072335.722 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409524E0
2024-04-25T19:12:15.722Z,1714072335.722 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2024-04-25T19:12:15.743Z,1714072335.743 [Depth_Keller] Loaded
2024-04-25T19:12:15.744Z,1714072335.744 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-04-25T19:12:15.749Z,1714072335.749 [DropWeight] Loaded
2024-04-25T19:12:15.749Z,1714072335.749 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-04-25T19:12:15.787Z,1714072335.787 [DVL_micro] Loaded
2024-04-25T19:12:15.788Z,1714072335.788 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-04-25T19:12:15.851Z,1714072335.851 [NAL9602] Loaded
2024-04-25T19:12:15.851Z,1714072335.851 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-04-25T19:12:15.882Z,1714072335.882 [Onboard] Loaded
2024-04-25T19:12:15.882Z,1714072335.882 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-04-25T19:12:15.883Z,1714072335.883 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409824E0
2024-04-25T19:12:15.883Z,1714072335.883 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2024-04-25T19:12:15.896Z,1714072335.896 [Power24vConverter] Loaded
2024-04-25T19:12:15.896Z,1714072335.896 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-04-25T19:12:15.910Z,1714072335.910 [Radio_Surface] Loaded
2024-04-25T19:12:15.910Z,1714072335.910 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-04-25T19:12:15.911Z,1714072335.911 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409B24E0
2024-04-25T19:12:15.911Z,1714072335.911 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2024-04-25T19:12:15.926Z,1714072335.926 [Sonardyne_Nano] Loaded
2024-04-25T19:12:15.926Z,1714072335.926 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-04-25T19:12:16.021Z,1714072336.021 [DAT] Loaded
2024-04-25T19:12:16.022Z,1714072336.022 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-04-25T19:12:16.023Z,1714072336.023 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409E24E0
2024-04-25T19:12:16.023Z,1714072336.023 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925
2024-04-25T19:12:16.024Z,1714072336.024 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-04-25T19:12:16.024Z,1714072336.024 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-04-25T19:12:16.194Z,1714072336.194 [BuoyancyServo] Loaded
2024-04-25T19:12:16.194Z,1714072336.194 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-04-25T19:12:16.216Z,1714072336.216 [ElevatorServo] Loaded
2024-04-25T19:12:16.216Z,1714072336.216 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-04-25T19:12:16.236Z,1714072336.236 [MassServo] Loaded
2024-04-25T19:12:16.236Z,1714072336.236 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-04-25T19:12:16.256Z,1714072336.256 [RudderServo] Loaded
2024-04-25T19:12:16.256Z,1714072336.256 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-04-25T19:12:16.271Z,1714072336.271 [ThrusterHE] Loaded
2024-04-25T19:12:16.272Z,1714072336.272 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-04-25T19:12:16.272Z,1714072336.272 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-04-25T19:12:16.273Z,1714072336.273 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-04-25T19:12:16.360Z,1714072336.360 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-04-25T19:12:16.361Z,1714072336.361 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-04-25T19:12:16.406Z,1714072336.406 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-04-25T19:12:16.407Z,1714072336.407 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-04-25T19:12:16.835Z,1714072336.835 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-04-25T19:12:16.836Z,1714072336.836 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-04-25T19:12:17.067Z,1714072337.067 [CTD_Seabird] Loaded
2024-04-25T19:12:17.067Z,1714072337.067 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-04-25T19:12:17.068Z,1714072337.068 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B984E0
2024-04-25T19:12:17.069Z,1714072337.069 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926
2024-04-25T19:12:17.103Z,1714072337.103 [ESPComponent] Loaded
2024-04-25T19:12:17.103Z,1714072337.103 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-04-25T19:12:17.124Z,1714072337.124 [PAR_Licor] Loaded
2024-04-25T19:12:17.124Z,1714072337.124 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-04-25T19:12:17.169Z,1714072337.169 [WetLabsBB2FL] Loaded
2024-04-25T19:12:17.169Z,1714072337.169 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-04-25T19:12:17.170Z,1714072337.170 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BC84E0
2024-04-25T19:12:17.170Z,1714072337.170 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927
2024-04-25T19:12:17.171Z,1714072337.171 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-04-25T19:12:17.173Z,1714072337.173 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-04-25T19:12:17.358Z,1714072337.358 [SBIT](DEBUG): Construct Startup Built In Test.
2024-04-25T19:12:17.367Z,1714072337.367 [SBIT] Loaded
2024-04-25T19:12:17.368Z,1714072337.368 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-04-25T19:12:17.371Z,1714072337.371 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-04-25T19:12:17.384Z,1714072337.384 [IBIT] Loaded
2024-04-25T19:12:17.384Z,1714072337.384 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-04-25T19:12:17.390Z,1714072337.390 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-04-25T19:12:17.500Z,1714072337.500 [CBIT] Loaded
2024-04-25T19:12:17.500Z,1714072337.500 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-04-25T19:12:17.501Z,1714072337.501 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-04-25T19:12:17.507Z,1714072337.507 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-04-25T19:12:17.510Z,1714072337.510 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-04-25T19:12:17.522Z,1714072337.522 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-04-25T19:12:17.523Z,1714072337.523 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0
2024-04-25T19:12:17.523Z,1714072337.523 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2024-04-25T19:12:17.528Z,1714072337.528 [Supervisor](INFO): Main Thread ID is 827
2024-04-25T19:12:17.528Z,1714072337.528 [Supervisor](DEBUG): Running supervisor.
2024-04-25T19:12:17.529Z,1714072337.529 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2024-04-25T19:12:17.529Z,1714072337.529 [CommandExec](INFO): Initializing the command executive.
2024-04-25T19:12:17.531Z,1714072337.531 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2024-04-25T19:12:17.533Z,1714072337.533 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2024-04-25T19:12:17.533Z,1714072337.533 [controlThread](DEBUG): Initializing ControlThread
2024-04-25T19:12:17.534Z,1714072337.534 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-04-25T19:12:17.534Z,1714072337.534 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-04-25T19:12:17.535Z,1714072337.535 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-04-25T19:12:17.535Z,1714072337.535 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-04-25T19:12:17.535Z,1714072337.535 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-04-25T19:12:17.536Z,1714072337.536 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-04-25T19:12:17.538Z,1714072337.538 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-04-25T19:12:17.538Z,1714072337.538 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-04-25T19:12:17.539Z,1714072337.539 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-04-25T19:12:17.540Z,1714072337.540 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-04-25T19:12:17.540Z,1714072337.540 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-04-25T19:12:17.548Z,1714072337.548 [SBIT](INFO): Initialize SBIT Component.
2024-04-25T19:12:17.549Z,1714072337.549 [SBIT](IMPORTANT): git: 2024-04-25
2024-04-25T19:12:17.549Z,1714072337.549 [SBIT](INFO): git hash: 9c8b4752de92409818c57bb73ab755afa5204686
2024-04-25T19:12:17.549Z,1714072337.549 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-04-25T19:12:17.550Z,1714072337.550 [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
2024-04-25T19:12:17.551Z,1714072337.551 [SBIT](INFO): Beginning SBIT in 69.000000 seconds.
2024-04-25T19:12:17.552Z,1714072337.552 [IBIT](INFO): Initialize IBIT Component.
2024-04-25T19:12:17.553Z,1714072337.553 [CBIT](DEBUG): Initialize CBIT Component.
2024-04-25T19:12:17.554Z,1714072337.554 [logger ThreadHandler](INFO): Handler Thread ID is 932
2024-04-25T19:12:17.564Z,1714072337.564 [CBIT](DEBUG): Initialized mux pins.
2024-04-25T19:12:17.564Z,1714072337.564 [CBIT](DEBUG): Initializing the watchdog timer.
2024-04-25T19:12:17.572Z,1714072337.572 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933
2024-04-25T19:12:17.584Z,1714072337.584 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2024-04-25T19:12:17.586Z,1714072337.586 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-04-25T19:12:17.588Z,1714072337.588 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-04-25T19:12:17.588Z,1714072337.588 [CBIT](DEBUG): Initializing heartbeat.
2024-04-25T19:12:17.596Z,1714072337.596 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2024-04-25T19:12:17.614Z,1714072337.614 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2024-04-25T19:12:17.633Z,1714072337.633 [DAT ThreadHandler](INFO): Handler Thread ID is 937
2024-04-25T19:12:17.634Z,1714072337.634 [DAT](INFO): Powering up
2024-04-25T19:12:17.634Z,1714072337.634 [DAT](DEBUG): Initializing DAT.
2024-04-25T19:12:17.637Z,1714072337.637 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2024-04-25T19:12:17.638Z,1714072337.638 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-04-25T19:12:17.641Z,1714072337.641 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2024-04-25T19:12:17.644Z,1714072337.644 [WetLabsBB2FL](INFO): Powering up
2024-04-25T19:12:17.646Z,1714072337.646 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2024-04-25T19:12:17.654Z,1714072337.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-04-25T19:12:17.654Z,1714072337.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-04-25T19:12:17.654Z,1714072337.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-04-25T19:12:17.654Z,1714072337.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-04-25T19:12:17.654Z,1714072337.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-04-25T19:12:17.655Z,1714072337.655 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-04-25T19:12:17.655Z,1714072337.655 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-04-25T19:12:17.655Z,1714072337.655 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-04-25T19:12:17.660Z,1714072337.660 [CBIT](DEBUG): Deactivating GF circuits.
2024-04-25T19:12:17.660Z,1714072337.660 [CBIT](DEBUG): Deactivating emergency mode.
2024-04-25T19:12:17.700Z,1714072337.700 [CBIT](DEBUG): Backplane powered.
2024-04-25T19:12:17.700Z,1714072337.700 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-04-25T19:12:17.701Z,1714072337.701 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-04-25T19:12:17.701Z,1714072337.701 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-04-25T19:12:17.708Z,1714072337.708 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-04-25T19:12:17.786Z,1714072337.786 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-04-25T19:12:17.790Z,1714072337.790 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-25T19:12:17.834Z,1714072337.834 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-04-25T19:12:17.835Z,1714072337.835 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-04-25T19:12:17.835Z,1714072337.835 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-04-25T19:12:17.852Z,1714072337.852 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-04-25T19:12:18.020Z,1714072338.020 [Radio_Surface](INFO): Powering up
2024-04-25T19:12:18.153Z,1714072338.153 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-04-25T19:12:18.160Z,1714072338.160 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-04-25T19:12:18.161Z,1714072338.161 [Default:A.Wait](DEBUG): Construct Wait.
2024-04-25T19:12:18.163Z,1714072338.163 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-25T19:12:18.216Z,1714072338.216 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-04-25T19:12:18.219Z,1714072338.219 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-04-25T19:12:18.240Z,1714072338.240 [Default:E.Execute](DEBUG): Construct Execute.
2024-04-25T19:12:18.243Z,1714072338.243 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-04-25T19:12:18.261Z,1714072338.261 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-04-25T19:12:18.284Z,1714072338.284 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-04-25T19:12:18.525Z,1714072338.525 [Depth_Keller](ERROR): Pressure or depth reading out of range: 916.699524 decibar, 0.000000 m
2024-04-25T19:12:18.553Z,1714072338.553 [Power24vConverter](INFO): Powering up.
2024-04-25T19:12:18.553Z,1714072338.553 [Sonardyne_Nano](INFO): Initializing.
2024-04-25T19:12:18.580Z,1714072338.580 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-04-25T19:12:18.658Z,1714072338.658 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-04-25T19:12:18.661Z,1714072338.661 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-04-25T19:12:18.662Z,1714072338.662 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-04-25T19:12:18.701Z,1714072338.701 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-04-25T19:12:18.702Z,1714072338.702 [MassServo](DEBUG): Initializing EZServoServo.
2024-04-25T19:12:18.783Z,1714072338.783 [MassServo](DEBUG): Initializing MassServo.
2024-04-25T19:12:18.784Z,1714072338.784 [RudderServo](DEBUG): Initializing EZServoServo.
2024-04-25T19:12:18.862Z,1714072338.862 [RudderServo](DEBUG): Initializing RudderServo.
2024-04-25T19:12:18.863Z,1714072338.863 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-04-25T19:12:18.928Z,1714072338.928 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-04-25T19:12:19.078Z,1714072339.078 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-04-25T19:12:19.078Z,1714072339.078 [DropWeight] Hardware Fault, FailCount= 1
2024-04-25T19:12:19.078Z,1714072339.078 [DropWeight](ERROR): Hardware Fault
2024-04-25T19:12:19.119Z,1714072339.119 [CommandExec](FAULT): Scheduling is paused
2024-04-25T19:12:19.140Z,1714072339.140 [CBIT](INFO): Critical error at 20240425T191219
2024-04-25T19:12:19.140Z,1714072339.140 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-04-25T19:12:19.149Z,1714072339.149 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-04-25T19:12:19.149Z,1714072339.149 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-04-25T19:12:19.393Z,1714072339.393 [CBIT](INFO): Critical error at 20240425T191219
2024-04-25T19:12:20.256Z,1714072340.256 [WetLabsBB2FL](INFO): Powering down
2024-04-25T19:12:20.562Z,1714072340.562 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-04-25T19:12:21.848Z,1714072341.848 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2024-04-25T19:12:24.297Z,1714072344.297 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-04-25T19:12:29.732Z,1714072349.732 [DAT](INFO): DAT read:
2024-04-25T19:12:29.734Z,1714072349.734 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-04-25T19:12:31.245Z,1714072351.245 [DAT](INFO): DAT read: MF Frequency Band
2024-04-25T19:12:31.246Z,1714072351.246 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-04-25T19:12:31.247Z,1714072351.247 [DAT](INFO): DAT read: Apr 25 2024 19:11:59
2024-04-25T19:12:32.505Z,1714072352.505 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-04-25T19:12:32.506Z,1714072352.506 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-04-25T19:12:32.507Z,1714072352.507 [DAT](INFO): commRate: 800
2024-04-25T19:12:34.572Z,1714072354.572 [DAT](INFO): entering command mode
2024-04-25T19:12:34.773Z,1714072354.773 [DAT](INFO): DAT read:
2024-04-25T19:12:34.774Z,1714072354.774 [DAT](INFO): DAT read: user:1>
2024-04-25T19:12:34.774Z,1714072354.774 [DAT](INFO): setting verbose to 3
2024-04-25T19:12:35.025Z,1714072355.025 [DAT](INFO): DAT read: user:1>
2024-04-25T19:12:35.026Z,1714072355.026 [DAT](INFO): DAT read: Verbose | 3
2024-04-25T19:12:35.026Z,1714072355.026 [DAT](INFO): set verbose to 3
2024-04-25T19:12:35.026Z,1714072355.026 [DAT](INFO): setting DatVerbose to 27440
2024-04-25T19:12:35.277Z,1714072355.277 [DAT](INFO): DAT read: user:2>
2024-04-25T19:12:35.278Z,1714072355.278 [DAT](INFO): DAT read: DatVerbose | 27440
2024-04-25T19:12:35.278Z,1714072355.278 [DAT](INFO): set DatVerbose to 27440
2024-04-25T19:12:35.278Z,1714072355.278 [DAT](INFO): setting transmit power to 8
2024-04-25T19:12:35.529Z,1714072355.529 [DAT](INFO): DAT read: user:3>
2024-04-25T19:12:35.529Z,1714072355.529 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-04-25T19:12:35.530Z,1714072355.530 [DAT](INFO): set transmit power to 8
2024-04-25T19:12:35.530Z,1714072355.530 [DAT](INFO): setting local address to 11
2024-04-25T19:12:35.780Z,1714072355.780 [DAT](INFO): DAT read: user:4>
2024-04-25T19:12:35.781Z,1714072355.781 [DAT](INFO): DAT read: LocalAddr | 11
2024-04-25T19:12:35.782Z,1714072355.782 [DAT](INFO): set local address to 11
2024-04-25T19:12:35.783Z,1714072355.783 [DAT](INFO): Setting time to: 19:12:35 And date to:4/25/2024
2024-04-25T19:12:36.033Z,1714072356.033 [DAT](INFO): DAT read: user:5>
2024-04-25T19:12:36.033Z,1714072356.033 [DAT](INFO): DAT read: Thu Apr 25, 2024 19:12:35
2024-04-25T19:12:36.034Z,1714072356.034 [DAT](INFO): Local DAT time set to Thu Apr 25, 2024 19:12:35
2024-04-25T19:12:46.026Z,1714072366.026 [NAL9602](INFO): Powering up NAL9602
2024-04-25T19:12:56.930Z,1714072376.930 [NAL9602](INFO): NAL9602 initialized
2024-04-25T19:13:27.258Z,1714072407.258 [SBIT](IMPORTANT): Beginning Startup BIT
2024-04-25T19:13:27.263Z,1714072407.263 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-25T19:13:38.280Z,1714072418.280 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.037158
CHAN A1 (24V): 0.022757
CHAN A2 (12V): -0.013546
CHAN A3 (5V): -0.022129
CHAN B0 (3.3V): -0.009990
CHAN B1 (3.15aV): -0.005014
CHAN B2 (3.15bV): -0.001588
CHAN B3 (GND): -0.009439
OPEN: -0.000901
Full Scale: +/- 1 mA
2024-04-25T19:14:21.379Z,1714072461.379 [SBIT](IMPORTANT): SBIT PASSED
2024-04-25T19:14:21.379Z,1714072461.379 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-25T19:14:21.380Z,1714072461.380 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;
2024-04-25T19:14:21.380Z,1714072461.380 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count;
2024-04-25T19:14:21.380Z,1714072461.380 [SBIT](IMPORTANT): DVL_micro.baud=19200 bit_per_second;
2024-04-25T19:14:21.380Z,1714072461.380 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar;
2024-04-25T19:14:21.380Z,1714072461.380 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-04-25T19:14:21.381Z,1714072461.381 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;
2024-04-25T19:14:21.381Z,1714072461.381 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=150 cubic_centimeter;
2024-04-25T19:14:21.381Z,1714072461.381 [SBIT](IMPORTANT): VerticalControl.massDefault=12.406871 millimeter;
2024-04-25T19:14:21.782Z,1714072461.782 [MissionManager](IMPORTANT): Started mission Startup
2024-04-25T19:14:21.782Z,1714072461.782 [Startup] Running Loop=1
2024-04-25T19:14:21.782Z,1714072461.782 [Startup](DEBUG): Aggregate::initialize Startup
2024-04-25T19:14:21.782Z,1714072461.782 [Startup:A.GoToSurface] Running Loop=1
2024-04-25T19:14:21.782Z,1714072461.782 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-25T19:14:21.783Z,1714072461.783 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-25T19:14:21.783Z,1714072461.783 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-25T19:14:21.784Z,1714072461.784 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-25T19:14:21.784Z,1714072461.784 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-25T19:14:21.785Z,1714072461.785 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-25T19:14:21.786Z,1714072461.786 [Startup:StartupSatComms] Running Loop=1
2024-04-25T19:14:21.786Z,1714072461.786 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-04-25T19:14:21.786Z,1714072461.786 [Startup:StartupSatComms:A] Running Loop=1
2024-04-25T19:14:22.189Z,1714072462.189 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-04-25T19:14:58.591Z,1714072498.591 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004751
2024-04-25T19:15:00.563Z,1714072500.563 [BPC1](ERROR): BPC1B buffer size (AAE bytes) exceeded the max device response limit.
2024-04-25T19:15:00.563Z,1714072500.563 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:15:14.350Z,1714072514.350 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:15:17.941Z,1714072517.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-04-25T19:15:17.941Z,1714072517.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:15:17.972Z,1714072517.972 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:15:18.341Z,1714072518.341 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:15:18.341Z,1714072518.341 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-04-25T19:15:21.987Z,1714072521.987 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-25T19:14:21.8Z
2024-04-25T19:15:21.987Z,1714072521.987 [Startup:StartupSatComms:A] Stopped
2024-04-25T19:15:21.987Z,1714072521.987 [Startup:StartupSatComms:B] Running Loop=1
2024-04-25T19:15:22.373Z,1714072522.373 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-25T19:15:28.967Z,1714072528.967 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:16:12.178Z,1714072572.178 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:16:22.175Z,1714072582.175 [Startup:StartupSatComms:B](INFO): Timed out from 2024-04-25T19:15:21.0Z
2024-04-25T19:16:22.175Z,1714072582.175 [Startup:StartupSatComms:B] Stopped
2024-04-25T19:16:22.175Z,1714072582.175 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-04-25T19:16:22.175Z,1714072582.175 [Startup:StartupSatComms] Stopped
2024-04-25T19:16:22.175Z,1714072582.175 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-04-25T19:16:22.176Z,1714072582.176 [Startup](INFO): Completed Startup
2024-04-25T19:16:22.176Z,1714072582.176 [MissionManager](INFO): Startup is completed.
2024-04-25T19:16:22.176Z,1714072582.176 [MissionManager](INFO): Uninitializing Mission Startup
2024-04-25T19:16:22.176Z,1714072582.176 [Startup] Stopped
2024-04-25T19:16:22.176Z,1714072582.176 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-04-25T19:16:22.177Z,1714072582.177 [Startup:A.GoToSurface] Stopped
2024-04-25T19:16:22.177Z,1714072582.177 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-25T19:16:22.573Z,1714072582.573 [MissionManager](IMPORTANT): Started mission Default
2024-04-25T19:16:22.573Z,1714072582.573 [Default] Running Loop=1
2024-04-25T19:16:22.573Z,1714072582.573 [Default](DEBUG): Aggregate::initialize Default
2024-04-25T19:16:22.573Z,1714072582.573 [Default:B.GoToSurface] Running Loop=1
2024-04-25T19:16:22.573Z,1714072582.573 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-25T19:16:22.573Z,1714072582.573 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-25T19:16:22.574Z,1714072582.574 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-25T19:16:22.574Z,1714072582.574 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-25T19:16:22.574Z,1714072582.574 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-25T19:16:22.575Z,1714072582.575 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-25T19:16:22.575Z,1714072582.575 [Default:A.Wait] Running Loop=1
2024-04-25T19:16:22.575Z,1714072582.575 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-04-25T19:16:35.905Z,1714072595.905 [Default:A.Wait](INFO): Done Waiting.
2024-04-25T19:16:35.905Z,1714072595.905 [Default:A.Wait] Stopped
2024-04-25T19:16:35.905Z,1714072595.905 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T19:16:36.310Z,1714072596.310 [Default:CheckIn] Running Loop=1
2024-04-25T19:16:36.310Z,1714072596.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T19:16:36.310Z,1714072596.310 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T19:16:36.715Z,1714072596.715 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-04-25T19:16:41.203Z,1714072601.203 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:17:09.480Z,1714072629.480 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:17:09.900Z,1714072629.900 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:17:40.591Z,1714072660.591 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:17:47.402Z,1714072667.402 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:17:59.540Z,1714072679.540 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-25T19:18:08.811Z,1714072688.811 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:18:18.947Z,1714072698.947 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-04-25T19:18:18.947Z,1714072698.947 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:18:18.975Z,1714072698.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:18:19.369Z,1714072699.369 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:18:19.369Z,1714072699.369 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-04-25T19:18:30.221Z,1714072710.221 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:18:51.633Z,1714072731.633 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:19:12.655Z,1714072752.655 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:19:34.053Z,1714072774.053 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:19:38.147Z,1714072778.147 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:19:55.466Z,1714072795.466 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:21:19.943Z,1714072879.943 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-04-25T19:21:19.943Z,1714072879.943 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:21:19.981Z,1714072879.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:21:20.389Z,1714072880.389 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:21:20.389Z,1714072880.389 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-04-25T19:21:36.533Z,1714072896.533 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-25T19:16:36.3Z
2024-04-25T19:21:36.533Z,1714072896.533 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T19:21:36.533Z,1714072896.533 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T19:21:36.954Z,1714072896.954 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-25T19:22:54.530Z,1714072974.530 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:22:59.345Z,1714072979.345 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-25T19:22:59.345Z,1714072979.345 [NAL9602] Data Fault, FailCount= 1
2024-04-25T19:22:59.345Z,1714072979.345 [NAL9602](ERROR): Data Fault
2024-04-25T19:22:59.416Z,1714072979.416 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-25T19:22:59.737Z,1714072979.737 [NAL9602](INFO): Powering down
2024-04-25T19:23:00.585Z,1714072980.585 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-25T19:23:00.585Z,1714072980.585 [NAL9602] No Fault, FailCount= 1
2024-04-25T19:23:13.457Z,1714072993.457 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-04-25T19:23:30.024Z,1714073010.024 [NAL9602](INFO): Powering up NAL9602
2024-04-25T19:23:40.928Z,1714073020.928 [NAL9602](INFO): NAL9602 initialized
2024-04-25T19:24:20.936Z,1714073060.936 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-04-25T19:24:20.936Z,1714073060.936 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:24:20.946Z,1714073060.946 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:24:21.480Z,1714073061.480 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:24:21.480Z,1714073061.480 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-04-25T19:24:54.514Z,1714073094.514 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:27:19.153Z,1714073239.153 [CBIT](INFO): Clearing failed state for component DropWeight
2024-04-25T19:27:19.153Z,1714073239.153 [DropWeight] No Fault, FailCount= 1
2024-04-25T19:27:21.948Z,1714073241.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-04-25T19:27:21.948Z,1714073241.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:27:21.979Z,1714073241.979 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:27:22.365Z,1714073242.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:27:22.365Z,1714073242.365 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-04-25T19:27:33.636Z,1714073253.636 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:27:40.498Z,1714073260.498 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:28:02.716Z,1714073282.716 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:28:12.870Z,1714073292.870 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:28:24.129Z,1714073304.129 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:28:45.137Z,1714073325.137 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:29:06.563Z,1714073346.563 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:29:19.131Z,1714073359.131 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:29:27.960Z,1714073367.960 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:29:44.981Z,1714073384.981 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:29:49.374Z,1714073389.374 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:30:22.935Z,1714073422.935 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-04-25T19:30:22.935Z,1714073422.935 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:30:22.977Z,1714073422.977 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:30:23.357Z,1714073423.357 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:30:23.357Z,1714073423.357 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-04-25T19:32:16.971Z,1714073536.971 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240425T161337/Courier0052.lzma
2024-04-25T19:32:17.969Z,1714073537.969 [DataOverHttps](INFO): Moved sent file to Logs/20240425T161337/Courier0052.lzma.bak
2024-04-25T19:32:17.969Z,1714073537.969 [DataOverHttps](INFO): SBD MOMSN=19432749
2024-04-25T19:32:36.027Z,1714073556.027 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20240425T191204/Courier0000.lzma
2024-04-25T19:32:37.028Z,1714073557.028 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0000.lzma.bak
2024-04-25T19:32:37.028Z,1714073557.028 [DataOverHttps](INFO): SBD MOMSN=19432751
2024-04-25T19:32:38.417Z,1714073558.417 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:32:56.818Z,1714073576.818 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240425T191204/Courier0004.lzma
2024-04-25T19:32:57.821Z,1714073577.821 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0004.lzma.bak
2024-04-25T19:32:57.822Z,1714073577.822 [DataOverHttps](INFO): SBD MOMSN=19432756
2024-04-25T19:33:16.707Z,1714073596.707 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20240425T161337/Express0053.lzma
2024-04-25T19:33:17.708Z,1714073597.708 [DataOverHttps](INFO): Moved sent file to Logs/20240425T161337/Express0053.lzma.bak
2024-04-25T19:33:17.709Z,1714073597.709 [DataOverHttps](INFO): SBD MOMSN=19432758
2024-04-25T19:33:24.015Z,1714073604.015 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-04-25T19:33:24.015Z,1714073604.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:33:24.050Z,1714073604.050 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:33:24.478Z,1714073604.478 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:33:24.478Z,1714073604.478 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-04-25T19:33:30.914Z,1714073610.914 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:33:35.604Z,1714073615.604 [DataOverHttps](INFO): Sending 930 bytes from file Logs/20240425T191204/Express0001.lzma
2024-04-25T19:33:35.759Z,1714073615.759 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:33:36.604Z,1714073616.604 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0001.lzma.bak
2024-04-25T19:33:36.604Z,1714073616.604 [DataOverHttps](INFO): SBD MOMSN=19432764
2024-04-25T19:33:42.999Z,1714073622.999 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-25T19:33:42.999Z,1714073622.999 [NAL9602] Data Fault, FailCount= 2
2024-04-25T19:33:42.999Z,1714073622.999 [NAL9602](ERROR): Data Fault
2024-04-25T19:33:43.067Z,1714073623.067 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-25T19:33:43.376Z,1714073623.376 [NAL9602](INFO): Powering down
2024-04-25T19:33:44.250Z,1714073624.250 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-25T19:33:44.250Z,1714073624.250 [NAL9602] No Fault, FailCount= 2
2024-04-25T19:33:55.267Z,1714073635.267 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20240425T191204/Express0005.lzma
2024-04-25T19:33:56.268Z,1714073636.268 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0005.lzma.bak
2024-04-25T19:33:56.268Z,1714073636.268 [DataOverHttps](INFO): SBD MOMSN=19432786
2024-04-25T19:33:59.564Z,1714073639.564 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T19:33:59.564Z,1714073639.564 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T19:33:59.564Z,1714073639.564 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T19:34:13.680Z,1714073653.680 [NAL9602](INFO): Powering up NAL9602
2024-04-25T19:34:24.588Z,1714073664.588 [NAL9602](INFO): NAL9602 initialized
2024-04-25T19:34:28.684Z,1714073668.684 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:35:41.000Z,1714073741.000 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:36:15.340Z,1714073775.340 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:36:25.045Z,1714073785.045 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-04-25T19:36:25.046Z,1714073785.046 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:36:25.056Z,1714073785.056 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:36:25.449Z,1714073785.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:36:25.449Z,1714073785.449 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-04-25T19:36:58.157Z,1714073818.157 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:37:08.709Z,1714073828.709 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:37:11.488Z,1714073831.488 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-25T19:37:24.417Z,1714073844.417 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:37:45.772Z,1714073865.772 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:38:07.185Z,1714073887.185 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:38:27.839Z,1714073907.839 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:38:48.392Z,1714073928.392 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:39:00.119Z,1714073940.119 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T19:39:00.119Z,1714073940.119 [Default:CheckIn:C.Wait] Stopped
2024-04-25T19:39:00.119Z,1714073940.119 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T19:39:00.119Z,1714073940.119 [Default:CheckIn:D] Running Loop=1
2024-04-25T19:39:00.553Z,1714073940.553 [Default:CheckIn:D] Stopped
2024-04-25T19:39:00.553Z,1714073940.553 [Default:CheckIn:E] Running Loop=1
2024-04-25T19:39:00.970Z,1714073940.970 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.633008 min
2024-04-25T19:39:00.971Z,1714073940.971 [Default:CheckIn:E] Stopped
2024-04-25T19:39:00.971Z,1714073940.971 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T19:39:00.971Z,1714073940.971 [Default:CheckIn] Stopped
2024-04-25T19:39:00.971Z,1714073940.971 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T19:39:00.971Z,1714073940.971 [Default:CheckIn](INFO): Running loop #2
2024-04-25T19:39:00.971Z,1714073940.971 [Default:CheckIn] Running Loop=2
2024-04-25T19:39:00.971Z,1714073940.971 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T19:39:00.971Z,1714073940.971 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T19:39:09.805Z,1714073949.805 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T19:39:25.980Z,1714073965.980 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-04-25T19:39:25.980Z,1714073965.980 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:39:26.010Z,1714073966.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:39:26.425Z,1714073966.425 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:39:26.425Z,1714073966.425 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-04-25T19:39:30.476Z,1714073970.476 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:42:17.725Z,1714074137.725 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-25T19:42:26.984Z,1714074146.984 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-04-25T19:42:26.984Z,1714074146.984 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:42:26.994Z,1714074146.994 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:42:27.424Z,1714074147.424 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:42:27.424Z,1714074147.424 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-04-25T19:43:19.081Z,1714074199.081 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4.
2024-04-25T19:43:19.084Z,1714074199.084 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-04-25T19:44:01.120Z,1714074241.120 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-25T19:39:00.0Z
2024-04-25T19:44:01.120Z,1714074241.120 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T19:44:01.120Z,1714074241.120 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T19:44:09.106Z,1714074249.106 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240425T191204/Courier0007.lzma
2024-04-25T19:44:10.108Z,1714074250.108 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0007.lzma.bak
2024-04-25T19:44:10.108Z,1714074250.108 [DataOverHttps](INFO): SBD MOMSN=19432859
2024-04-25T19:44:13.637Z,1714074253.637 [DVL_micro](ERROR): Failed to parse:
:SA,-02.55,-06.75,192.0
2024-04-25T19:44:26.553Z,1714074266.553 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-25T19:44:26.553Z,1714074266.553 [NAL9602] Data Fault, FailCount= 3
2024-04-25T19:44:26.553Z,1714074266.553 [NAL9602](ERROR): Data Fault
2024-04-25T19:44:26.619Z,1714074266.619 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-25T19:44:26.974Z,1714074266.974 [NAL9602](INFO): Powering down
2024-04-25T19:44:27.783Z,1714074267.783 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20240425T191204/Express0008.lzma
2024-04-25T19:44:27.814Z,1714074267.814 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-25T19:44:27.814Z,1714074267.814 [NAL9602] No Fault, FailCount= 3
2024-04-25T19:44:28.784Z,1714074268.784 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0008.lzma.bak
2024-04-25T19:44:28.784Z,1714074268.784 [DataOverHttps](INFO): SBD MOMSN=19432861
2024-04-25T19:44:31.828Z,1714074271.828 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T19:44:31.828Z,1714074271.828 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T19:44:31.828Z,1714074271.828 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T19:44:57.260Z,1714074297.260 [NAL9602](INFO): Powering up NAL9602
2024-04-25T19:45:08.168Z,1714074308.168 [NAL9602](INFO): NAL9602 initialized
2024-04-25T19:45:28.004Z,1714074328.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-04-25T19:45:28.004Z,1714074328.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:45:28.015Z,1714074328.015 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:45:28.413Z,1714074328.413 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:45:28.413Z,1714074328.413 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-04-25T19:48:28.992Z,1714074508.992 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-04-25T19:48:28.992Z,1714074508.992 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:48:29.003Z,1714074509.003 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:48:29.400Z,1714074509.400 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:48:29.400Z,1714074509.400 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-04-25T19:49:32.416Z,1714074572.416 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T19:49:32.416Z,1714074572.416 [Default:CheckIn:C.Wait] Stopped
2024-04-25T19:49:32.416Z,1714074572.416 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T19:49:32.416Z,1714074572.416 [Default:CheckIn:D] Running Loop=1
2024-04-25T19:49:32.824Z,1714074572.824 [Default:CheckIn:D] Stopped
2024-04-25T19:49:32.824Z,1714074572.824 [Default:CheckIn:E] Running Loop=1
2024-04-25T19:49:33.202Z,1714074573.202 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.170854 min
2024-04-25T19:49:33.202Z,1714074573.202 [Default:CheckIn:E] Stopped
2024-04-25T19:49:33.202Z,1714074573.202 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T19:49:33.202Z,1714074573.202 [Default:CheckIn] Stopped
2024-04-25T19:49:33.202Z,1714074573.202 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T19:49:33.203Z,1714074573.203 [Default:CheckIn](INFO): Running loop #3
2024-04-25T19:49:33.203Z,1714074573.203 [Default:CheckIn] Running Loop=3
2024-04-25T19:49:33.203Z,1714074573.203 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T19:49:33.203Z,1714074573.203 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T19:51:29.973Z,1714074689.973 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-04-25T19:51:29.973Z,1714074689.973 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:51:29.989Z,1714074689.989 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:51:30.398Z,1714074690.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:51:30.398Z,1714074690.398 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-04-25T19:54:30.976Z,1714074870.976 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2024-04-25T19:54:30.976Z,1714074870.976 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:54:31.003Z,1714074871.003 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:54:31.381Z,1714074871.381 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:54:31.381Z,1714074871.381 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2024-04-25T19:54:33.401Z,1714074873.401 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-25T19:49:33.2Z
2024-04-25T19:54:33.401Z,1714074873.401 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T19:54:33.401Z,1714074873.401 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T19:54:40.578Z,1714074880.578 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240425T191204/Courier0010.lzma
2024-04-25T19:54:41.581Z,1714074881.581 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0010.lzma.bak
2024-04-25T19:54:41.581Z,1714074881.581 [DataOverHttps](INFO): SBD MOMSN=19432947
2024-04-25T19:55:00.438Z,1714074900.438 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20240425T191204/Express0011.lzma
2024-04-25T19:55:01.440Z,1714074901.440 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0011.lzma.bak
2024-04-25T19:55:01.440Z,1714074901.440 [DataOverHttps](INFO): SBD MOMSN=19432949
2024-04-25T19:55:04.493Z,1714074904.493 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T19:55:04.493Z,1714074904.493 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T19:55:04.493Z,1714074904.493 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T19:55:09.320Z,1714074909.320 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-25T19:55:09.320Z,1714074909.320 [NAL9602] Data Fault, FailCount= 4
2024-04-25T19:55:09.320Z,1714074909.320 [NAL9602](ERROR): Data Fault
2024-04-25T19:55:09.377Z,1714074909.377 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-25T19:55:09.724Z,1714074909.724 [NAL9602](INFO): Powering down
2024-04-25T19:55:10.601Z,1714074910.601 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-25T19:55:10.601Z,1714074910.601 [NAL9602] No Fault, FailCount= 4
2024-04-25T19:55:40.042Z,1714074940.042 [NAL9602](INFO): Powering up NAL9602
2024-04-25T19:55:50.937Z,1714074950.937 [NAL9602](INFO): NAL9602 initialized
2024-04-25T19:57:20.624Z,1714075040.624 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-04-25T19:57:20.624Z,1714075040.624 [DropWeight] Hardware Fault, FailCount= 1
2024-04-25T19:57:20.624Z,1714075040.624 [DropWeight](ERROR): Hardware Fault
2024-04-25T19:57:20.682Z,1714075040.682 [CBIT](INFO): Critical error at 20240425T195720
2024-04-25T19:57:20.689Z,1714075040.689 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-04-25T19:57:20.689Z,1714075040.689 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-04-25T19:57:21.043Z,1714075041.043 [CBIT](INFO): Critical error at 20240425T195720
2024-04-25T19:57:28.708Z,1714075048.708 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2024-04-25T19:57:31.944Z,1714075051.944 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2024-04-25T19:57:31.944Z,1714075051.944 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T19:57:31.975Z,1714075051.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T19:57:32.356Z,1714075052.356 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T19:57:32.356Z,1714075052.356 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2024-04-25T20:00:05.109Z,1714075205.109 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T20:00:05.109Z,1714075205.109 [Default:CheckIn:C.Wait] Stopped
2024-04-25T20:00:05.109Z,1714075205.109 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T20:00:05.109Z,1714075205.109 [Default:CheckIn:D] Running Loop=1
2024-04-25T20:00:05.489Z,1714075205.489 [Default:CheckIn:D] Stopped
2024-04-25T20:00:05.489Z,1714075205.489 [Default:CheckIn:E] Running Loop=1
2024-04-25T20:00:05.889Z,1714075205.889 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.715263 min
2024-04-25T20:00:05.889Z,1714075205.889 [Default:CheckIn:E] Stopped
2024-04-25T20:00:05.889Z,1714075205.889 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T20:00:05.889Z,1714075205.889 [Default:CheckIn] Stopped
2024-04-25T20:00:05.889Z,1714075205.889 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T20:00:05.890Z,1714075205.890 [Default:CheckIn](INFO): Running loop #4
2024-04-25T20:00:05.890Z,1714075205.890 [Default:CheckIn] Running Loop=4
2024-04-25T20:00:05.890Z,1714075205.890 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T20:00:05.890Z,1714075205.890 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T20:00:32.930Z,1714075232.930 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2024-04-25T20:00:32.930Z,1714075232.930 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:00:32.940Z,1714075232.940 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:00:33.348Z,1714075233.348 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:00:33.349Z,1714075233.349 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2024-04-25T20:03:33.963Z,1714075413.963 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2024-04-25T20:03:33.963Z,1714075413.963 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:03:34.005Z,1714075414.005 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:03:34.432Z,1714075414.432 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:03:34.432Z,1714075414.432 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2024-04-25T20:05:06.057Z,1714075506.057 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-25T20:00:05.9Z
2024-04-25T20:05:06.057Z,1714075506.057 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T20:05:06.057Z,1714075506.057 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T20:05:13.518Z,1714075513.518 [DataOverHttps](INFO): Sending 114 bytes from file Logs/20240425T191204/Courier0013.lzma
2024-04-25T20:05:14.520Z,1714075514.520 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0013.lzma.bak
2024-04-25T20:05:14.521Z,1714075514.521 [DataOverHttps](INFO): SBD MOMSN=19433013
2024-04-25T20:05:32.239Z,1714075532.239 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240425T191204/Express0014.lzma
2024-04-25T20:05:33.240Z,1714075533.240 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0014.lzma.bak
2024-04-25T20:05:33.241Z,1714075533.241 [DataOverHttps](INFO): SBD MOMSN=19433017
2024-04-25T20:05:36.376Z,1714075536.376 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T20:05:36.376Z,1714075536.376 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T20:05:36.376Z,1714075536.376 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T20:05:51.815Z,1714075551.815 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-25T20:05:51.816Z,1714075551.816 [NAL9602] Data Fault, FailCount= 5
2024-04-25T20:05:51.816Z,1714075551.816 [NAL9602](ERROR): Data Fault
2024-04-25T20:05:51.835Z,1714075551.835 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-25T20:05:51.835Z,1714075551.835 [CBIT](CRITICAL): Data Fault in component: NAL9602
2024-04-25T20:05:52.104Z,1714075552.104 [NAL9602](INFO): Powering down
2024-04-25T20:05:52.136Z,1714075552.136 [CBIT](INFO): Critical error at 20240425T200551
2024-04-25T20:06:34.942Z,1714075594.942 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2024-04-25T20:06:34.942Z,1714075594.942 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:06:34.953Z,1714075594.953 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:06:35.364Z,1714075595.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:06:35.365Z,1714075595.365 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2024-04-25T20:07:52.106Z,1714075672.106 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-25T20:07:52.106Z,1714075672.106 [NAL9602] No Fault, FailCount= 5
2024-04-25T20:07:52.500Z,1714075672.500 [NAL9602](INFO): Powering up NAL9602
2024-04-25T20:08:03.399Z,1714075683.399 [NAL9602](INFO): NAL9602 initialized
2024-04-25T20:09:35.932Z,1714075775.932 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2024-04-25T20:09:35.932Z,1714075775.932 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:09:35.943Z,1714075775.943 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:09:36.361Z,1714075776.361 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:09:36.361Z,1714075776.361 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2024-04-25T20:10:36.953Z,1714075836.953 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T20:10:36.953Z,1714075836.953 [Default:CheckIn:C.Wait] Stopped
2024-04-25T20:10:36.953Z,1714075836.953 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T20:10:36.953Z,1714075836.953 [Default:CheckIn:D] Running Loop=1
2024-04-25T20:10:37.357Z,1714075837.357 [Default:CheckIn:D] Stopped
2024-04-25T20:10:37.357Z,1714075837.357 [Default:CheckIn:E] Running Loop=1
2024-04-25T20:10:37.766Z,1714075837.766 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.246399 min
2024-04-25T20:10:37.766Z,1714075837.766 [Default:CheckIn:E] Stopped
2024-04-25T20:10:37.767Z,1714075837.767 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T20:10:37.767Z,1714075837.767 [Default:CheckIn] Stopped
2024-04-25T20:10:37.767Z,1714075837.767 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T20:10:37.767Z,1714075837.767 [Default:CheckIn](INFO): Running loop #5
2024-04-25T20:10:37.767Z,1714075837.767 [Default:CheckIn] Running Loop=5
2024-04-25T20:10:37.768Z,1714075837.768 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T20:10:37.768Z,1714075837.768 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T20:12:20.813Z,1714075940.813 [CBIT](INFO): Clearing failed state for component DropWeight
2024-04-25T20:12:20.813Z,1714075940.813 [DropWeight] No Fault, FailCount= 1
2024-04-25T20:12:36.921Z,1714075956.921 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2024-04-25T20:12:36.921Z,1714075956.921 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:12:36.961Z,1714075956.961 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:12:37.379Z,1714075957.379 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:12:37.379Z,1714075957.379 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2024-04-25T20:15:37.913Z,1714076137.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2024-04-25T20:15:37.913Z,1714076137.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:15:37.914Z,1714076137.914 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-25T20:10:37.8Z
2024-04-25T20:15:37.915Z,1714076137.915 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T20:15:37.915Z,1714076137.915 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T20:15:37.952Z,1714076137.952 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:15:38.357Z,1714076138.357 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:15:38.357Z,1714076138.357 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2024-04-25T20:15:45.203Z,1714076145.203 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20240425T191204/Courier0016.lzma
2024-04-25T20:15:46.205Z,1714076146.205 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0016.lzma.bak
2024-04-25T20:15:46.205Z,1714076146.205 [DataOverHttps](INFO): SBD MOMSN=19433091
2024-04-25T20:16:03.851Z,1714076163.851 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20240425T191204/Express0017.lzma
2024-04-25T20:16:04.853Z,1714076164.853 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0017.lzma.bak
2024-04-25T20:16:04.853Z,1714076164.853 [DataOverHttps](INFO): SBD MOMSN=19433094
2024-04-25T20:16:08.232Z,1714076168.232 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T20:16:08.232Z,1714076168.232 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T20:16:08.232Z,1714076168.232 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T20:16:57.736Z,1714076217.736 [CommandExec](IMPORTANT): got command failComponent
2024-04-25T20:16:57.736Z,1714076217.736 [CommandExec](IMPORTANT): Failed components:
2024-04-25T20:16:57.736Z,1714076217.736 [CommandExec](IMPORTANT): No failed Components.
2024-04-25T20:18:05.369Z,1714076285.369 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-25T20:18:05.369Z,1714076285.369 [NAL9602] Data Fault, FailCount= 1
2024-04-25T20:18:05.369Z,1714076285.369 [NAL9602](ERROR): Data Fault
2024-04-25T20:18:05.390Z,1714076285.390 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-25T20:18:05.788Z,1714076285.788 [NAL9602](INFO): Powering down
2024-04-25T20:18:06.633Z,1714076286.633 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-25T20:18:06.633Z,1714076286.633 [NAL9602] No Fault, FailCount= 1
2024-04-25T20:18:36.117Z,1714076316.117 [NAL9602](INFO): Powering up NAL9602
2024-04-25T20:18:38.951Z,1714076318.951 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2024-04-25T20:18:38.951Z,1714076318.951 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:18:38.962Z,1714076318.962 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:18:39.365Z,1714076319.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:18:39.365Z,1714076319.365 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2024-04-25T20:18:47.025Z,1714076327.025 [NAL9602](INFO): NAL9602 initialized
2024-04-25T20:20:00.698Z,1714076400.698 [CommandExec](IMPORTANT): got command get ESPComponent.loadControl
2024-04-25T20:20:00.698Z,1714076400.698 [CommandExec](IMPORTANT): ESPComponent.loadControl /dev/loadA6
2024-04-25T20:21:08.970Z,1714076468.970 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T20:21:08.970Z,1714076468.970 [Default:CheckIn:C.Wait] Stopped
2024-04-25T20:21:08.970Z,1714076468.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T20:21:08.970Z,1714076468.970 [Default:CheckIn:D] Running Loop=1
2024-04-25T20:21:09.341Z,1714076469.341 [Default:CheckIn:D] Stopped
2024-04-25T20:21:09.341Z,1714076469.341 [Default:CheckIn:E] Running Loop=1
2024-04-25T20:21:09.745Z,1714076469.745 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.779468 min
2024-04-25T20:21:09.745Z,1714076469.745 [Default:CheckIn:E] Stopped
2024-04-25T20:21:09.745Z,1714076469.745 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T20:21:09.745Z,1714076469.745 [Default:CheckIn] Stopped
2024-04-25T20:21:09.745Z,1714076469.745 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T20:21:09.746Z,1714076469.746 [Default:CheckIn](INFO): Running loop #6
2024-04-25T20:21:09.746Z,1714076469.746 [Default:CheckIn] Running Loop=6
2024-04-25T20:21:09.746Z,1714076469.746 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T20:21:09.746Z,1714076469.746 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T20:21:40.082Z,1714076500.082 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2024-04-25T20:21:40.082Z,1714076500.082 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:21:40.116Z,1714076500.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:21:40.461Z,1714076500.461 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:21:40.461Z,1714076500.461 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2024-04-25T20:24:40.997Z,1714076680.997 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2024-04-25T20:24:40.997Z,1714076680.997 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:24:41.031Z,1714076681.031 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:24:41.436Z,1714076681.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:24:41.436Z,1714076681.436 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2024-04-25T20:26:09.878Z,1714076769.878 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-25T20:21:09.7Z
2024-04-25T20:26:09.879Z,1714076769.879 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T20:26:09.879Z,1714076769.879 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T20:26:17.947Z,1714076777.947 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240425T191204/Courier0019.lzma
2024-04-25T20:26:18.949Z,1714076778.949 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0019.lzma.bak
2024-04-25T20:26:18.949Z,1714076778.949 [DataOverHttps](INFO): SBD MOMSN=19433188
2024-04-25T20:26:38.071Z,1714076798.071 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20240425T191204/Express0020.lzma
2024-04-25T20:26:39.073Z,1714076799.073 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0020.lzma.bak
2024-04-25T20:26:39.073Z,1714076799.073 [DataOverHttps](INFO): SBD MOMSN=19433198
2024-04-25T20:26:42.237Z,1714076802.237 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T20:26:42.237Z,1714076802.237 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T20:26:42.237Z,1714076802.237 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T20:27:41.990Z,1714076861.990 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2024-04-25T20:27:41.990Z,1714076861.990 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:27:42.000Z,1714076862.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:27:42.418Z,1714076862.418 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:27:42.418Z,1714076862.418 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2024-04-25T20:28:49.459Z,1714076929.459 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-25T20:28:49.460Z,1714076929.460 [NAL9602] Data Fault, FailCount= 2
2024-04-25T20:28:49.460Z,1714076929.460 [NAL9602](ERROR): Data Fault
2024-04-25T20:28:49.477Z,1714076929.477 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-25T20:28:49.853Z,1714076929.853 [NAL9602](INFO): Powering down
2024-04-25T20:28:50.678Z,1714076930.678 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-25T20:28:50.678Z,1714076930.678 [NAL9602] No Fault, FailCount= 2
2024-04-25T20:29:20.153Z,1714076960.153 [NAL9602](INFO): Powering up NAL9602
2024-04-25T20:29:31.061Z,1714076971.061 [NAL9602](INFO): NAL9602 initialized
2024-04-25T20:30:42.999Z,1714077042.999 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2024-04-25T20:30:42.999Z,1714077042.999 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:30:43.010Z,1714077043.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:30:43.424Z,1714077043.424 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:30:43.424Z,1714077043.424 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2024-04-25T20:31:42.774Z,1714077102.774 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T20:31:42.774Z,1714077102.774 [Default:CheckIn:C.Wait] Stopped
2024-04-25T20:31:42.774Z,1714077102.774 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T20:31:42.774Z,1714077102.774 [Default:CheckIn:D] Running Loop=1
2024-04-25T20:31:43.197Z,1714077103.197 [Default:CheckIn:D] Stopped
2024-04-25T20:31:43.197Z,1714077103.197 [Default:CheckIn:E] Running Loop=1
2024-04-25T20:31:43.602Z,1714077103.602 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.343742 min
2024-04-25T20:31:43.602Z,1714077103.602 [Default:CheckIn:E] Stopped
2024-04-25T20:31:43.602Z,1714077103.602 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T20:31:43.602Z,1714077103.602 [Default:CheckIn] Stopped
2024-04-25T20:31:43.602Z,1714077103.602 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T20:31:43.602Z,1714077103.602 [Default:CheckIn](INFO): Running loop #7
2024-04-25T20:31:43.602Z,1714077103.602 [Default:CheckIn] Running Loop=7
2024-04-25T20:31:43.603Z,1714077103.603 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T20:31:43.603Z,1714077103.603 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T20:33:43.996Z,1714077223.996 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2024-04-25T20:33:43.996Z,1714077223.996 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-25T20:33:44.010Z,1714077224.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-25T20:33:44.425Z,1714077224.425 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-25T20:33:44.425Z,1714077224.425 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2024-04-25T20:34:13.863Z,1714077253.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203410.00,A,3648.16985,N,12147.28289,W,0.039,0.00,250424,,,A*75
2024-04-25T20:34:13.866Z,1714077253.866 [NAL9602](INFO): GPS fix at 20240425T203410: (36.802831, -121.788048)
2024-04-25T20:34:13.894Z,1714077253.894 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T20:34:13.894Z,1714077253.894 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T20:34:26.019Z,1714077266.019 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240425T191204/Courier0022.lzma
2024-04-25T20:34:27.021Z,1714077267.021 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0022.lzma.bak
2024-04-25T20:34:27.021Z,1714077267.021 [DataOverHttps](INFO): SBD MOMSN=19433261
2024-04-25T20:34:46.277Z,1714077286.277 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T20:34:49.843Z,1714077289.843 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20240425T191204/Express0023.lzma
2024-04-25T20:34:50.845Z,1714077290.845 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0023.lzma.bak
2024-04-25T20:34:50.845Z,1714077290.845 [DataOverHttps](INFO): SBD MOMSN=19433268
2024-04-25T20:34:53.986Z,1714077293.986 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T20:34:53.987Z,1714077293.987 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T20:34:53.991Z,1714077293.991 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T20:39:54.670Z,1714077594.670 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T20:39:54.670Z,1714077594.670 [Default:CheckIn:C.Wait] Stopped
2024-04-25T20:39:54.670Z,1714077594.670 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T20:39:54.671Z,1714077594.671 [Default:CheckIn:D] Running Loop=1
2024-04-25T20:39:55.052Z,1714077595.052 [Default:CheckIn:D] Stopped
2024-04-25T20:39:55.052Z,1714077595.052 [Default:CheckIn:E] Running Loop=1
2024-04-25T20:39:55.495Z,1714077595.495 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.541317 min
2024-04-25T20:39:55.496Z,1714077595.496 [Default:CheckIn:E] Stopped
2024-04-25T20:39:55.496Z,1714077595.496 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T20:39:55.496Z,1714077595.496 [Default:CheckIn] Stopped
2024-04-25T20:39:55.496Z,1714077595.496 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T20:39:55.496Z,1714077595.496 [Default:CheckIn](INFO): Running loop #8
2024-04-25T20:39:55.496Z,1714077595.496 [Default:CheckIn] Running Loop=8
2024-04-25T20:39:55.496Z,1714077595.496 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T20:39:55.496Z,1714077595.496 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T20:39:57.451Z,1714077597.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203956.00,A,3648.16768,N,12147.27970,W,0.428,207.60,250424,,,A*72
2024-04-25T20:39:57.469Z,1714077597.469 [NAL9602](INFO): GPS fix at 20240425T203956: (36.802795, -121.787995)
2024-04-25T20:39:57.505Z,1714077597.505 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T20:39:57.505Z,1714077597.505 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T20:39:59.440Z,1714077599.440 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240425T191204/Courier0025.lzma
2024-04-25T20:40:00.445Z,1714077600.445 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0025.lzma.bak
2024-04-25T20:40:00.445Z,1714077600.445 [DataOverHttps](INFO): SBD MOMSN=19433334
2024-04-25T20:40:20.151Z,1714077620.151 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240425T191204/Express0026.lzma
2024-04-25T20:40:21.153Z,1714077621.153 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0026.lzma.bak
2024-04-25T20:40:21.153Z,1714077621.153 [DataOverHttps](INFO): SBD MOMSN=19433337
2024-04-25T20:40:25.988Z,1714077625.988 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T20:40:25.988Z,1714077625.988 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T20:40:25.988Z,1714077625.988 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T20:42:22.260Z,1714077742.260 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-04-25T20:42:22.260Z,1714077742.260 [DropWeight] Hardware Fault, FailCount= 1
2024-04-25T20:42:22.260Z,1714077742.260 [DropWeight](ERROR): Hardware Fault
2024-04-25T20:42:22.336Z,1714077742.336 [CBIT](INFO): Critical error at 20240425T204222
2024-04-25T20:42:22.338Z,1714077742.338 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-04-25T20:42:22.351Z,1714077742.351 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-04-25T20:42:22.716Z,1714077742.716 [CBIT](INFO): Critical error at 20240425T204222
2024-04-25T20:44:59.401Z,1714077899.401 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-25T20:45:26.484Z,1714077926.484 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T20:45:26.484Z,1714077926.484 [Default:CheckIn:C.Wait] Stopped
2024-04-25T20:45:26.484Z,1714077926.484 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T20:45:26.484Z,1714077926.484 [Default:CheckIn:D] Running Loop=1
2024-04-25T20:45:26.896Z,1714077926.896 [Default:CheckIn:D] Stopped
2024-04-25T20:45:26.896Z,1714077926.896 [Default:CheckIn:E] Running Loop=1
2024-04-25T20:45:27.304Z,1714077927.304 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.072046 min
2024-04-25T20:45:27.304Z,1714077927.304 [Default:CheckIn:E] Stopped
2024-04-25T20:45:27.305Z,1714077927.305 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T20:45:27.305Z,1714077927.305 [Default:CheckIn] Stopped
2024-04-25T20:45:27.305Z,1714077927.305 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T20:45:27.305Z,1714077927.305 [Default:CheckIn](INFO): Running loop #9
2024-04-25T20:45:27.305Z,1714077927.305 [Default:CheckIn] Running Loop=9
2024-04-25T20:45:27.305Z,1714077927.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T20:45:27.305Z,1714077927.305 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T20:45:29.291Z,1714077929.291 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204528.00,A,3648.16962,N,12147.27675,W,0.292,207.60,250424,,,A*79
2024-04-25T20:45:29.293Z,1714077929.293 [NAL9602](INFO): GPS fix at 20240425T204528: (36.802827, -121.787946)
2024-04-25T20:45:29.324Z,1714077929.324 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T20:45:29.324Z,1714077929.324 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T20:45:38.211Z,1714077938.211 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20240425T191204/Courier0028.lzma
2024-04-25T20:45:39.213Z,1714077939.213 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0028.lzma.bak
2024-04-25T20:45:39.213Z,1714077939.213 [DataOverHttps](INFO): SBD MOMSN=19433352
2024-04-25T20:46:01.265Z,1714077961.265 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T20:46:03.315Z,1714077963.315 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240425T191204/Express0029.lzma
2024-04-25T20:46:04.317Z,1714077964.317 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0029.lzma.bak
2024-04-25T20:46:04.317Z,1714077964.317 [DataOverHttps](INFO): SBD MOMSN=19433357
2024-04-25T20:46:07.781Z,1714077967.781 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T20:46:07.781Z,1714077967.781 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T20:46:07.782Z,1714077967.782 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T20:49:35.156Z,1714078175.156 [CommandExec](IMPORTANT): got command burn on
2024-04-25T20:49:35.157Z,1714078175.157 [CommandExec](IMPORTANT): Activating dropweight wire
2024-04-25T20:49:49.472Z,1714078189.472 [CommandExec](IMPORTANT): got command burn off
2024-04-25T20:49:49.472Z,1714078189.472 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-04-25T20:50:59.484Z,1714078259.484 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:51:08.396Z,1714078268.396 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T20:51:08.396Z,1714078268.396 [Default:CheckIn:C.Wait] Stopped
2024-04-25T20:51:08.396Z,1714078268.396 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T20:51:08.396Z,1714078268.396 [Default:CheckIn:D] Running Loop=1
2024-04-25T20:51:08.845Z,1714078268.845 [Default:CheckIn:D] Stopped
2024-04-25T20:51:08.845Z,1714078268.845 [Default:CheckIn:E] Running Loop=1
2024-04-25T20:51:09.214Z,1714078269.214 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.771200 min
2024-04-25T20:51:09.214Z,1714078269.214 [Default:CheckIn:E] Stopped
2024-04-25T20:51:09.214Z,1714078269.214 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T20:51:09.214Z,1714078269.214 [Default:CheckIn] Stopped
2024-04-25T20:51:09.214Z,1714078269.214 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T20:51:09.214Z,1714078269.214 [Default:CheckIn](INFO): Running loop #10
2024-04-25T20:51:09.214Z,1714078269.214 [Default:CheckIn] Running Loop=10
2024-04-25T20:51:09.214Z,1714078269.214 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T20:51:09.215Z,1714078269.215 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T20:51:11.205Z,1714078271.205 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205110.00,A,3648.16811,N,12147.27980,W,0.019,207.60,250424,,,A*76
2024-04-25T20:51:11.207Z,1714078271.207 [NAL9602](INFO): GPS fix at 20240425T205110: (36.802802, -121.787997)
2024-04-25T20:51:11.219Z,1714078271.219 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T20:51:11.219Z,1714078271.219 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T20:51:14.432Z,1714078274.432 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:51:19.687Z,1714078279.687 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240425T191204/Courier0031.lzma
2024-04-25T20:51:20.689Z,1714078280.689 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0031.lzma.bak
2024-04-25T20:51:20.689Z,1714078280.689 [DataOverHttps](INFO): SBD MOMSN=19433417
2024-04-25T20:51:29.379Z,1714078289.379 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:51:40.355Z,1714078300.355 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20240425T191204/Express0032.lzma
2024-04-25T20:51:41.357Z,1714078301.357 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0032.lzma.bak
2024-04-25T20:51:41.357Z,1714078301.357 [DataOverHttps](INFO): SBD MOMSN=19433420
2024-04-25T20:51:41.913Z,1714078301.913 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-04-25T20:51:41.991Z,1714078301.991 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-04-25T20:51:43.924Z,1714078303.924 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:51:45.222Z,1714078305.222 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T20:51:45.222Z,1714078305.222 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T20:51:45.222Z,1714078305.222 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T20:51:58.963Z,1714078318.963 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:52:13.918Z,1714078333.918 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:52:28.455Z,1714078348.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:52:43.402Z,1714078363.402 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:52:58.353Z,1714078378.353 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:54:47.112Z,1714078487.112 [CommandExec](IMPORTANT): got command failComponent
2024-04-25T20:54:47.112Z,1714078487.112 [CommandExec](IMPORTANT): Failed components:
2024-04-25T20:54:47.112Z,1714078487.112 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-04-25T20:54:50.671Z,1714078490.671 [CommandExec](IMPORTANT): got command failComponent none DropWeight
2024-04-25T20:54:50.672Z,1714078490.672 [DropWeight] No Fault, FailCount= 1
2024-04-25T20:54:50.672Z,1714078490.672 [CommandExec](IMPORTANT): DropWeight failureMode is No Fault
2024-04-25T20:55:41.172Z,1714078541.172 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T20:56:13.513Z,1714078573.513 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-25T20:56:44.234Z,1714078604.234 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T20:56:45.870Z,1714078605.870 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T20:56:45.870Z,1714078605.870 [Default:CheckIn:C.Wait] Stopped
2024-04-25T20:56:45.870Z,1714078605.870 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T20:56:45.870Z,1714078605.870 [Default:CheckIn:D] Running Loop=1
2024-04-25T20:56:46.252Z,1714078606.252 [Default:CheckIn:D] Stopped
2024-04-25T20:56:46.252Z,1714078606.252 [Default:CheckIn:E] Running Loop=1
2024-04-25T20:56:46.649Z,1714078606.649 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.394645 min
2024-04-25T20:56:46.649Z,1714078606.649 [Default:CheckIn:E] Stopped
2024-04-25T20:56:46.649Z,1714078606.649 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T20:56:46.649Z,1714078606.649 [Default:CheckIn] Stopped
2024-04-25T20:56:46.649Z,1714078606.649 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T20:56:46.649Z,1714078606.649 [Default:CheckIn](INFO): Running loop #11
2024-04-25T20:56:46.649Z,1714078606.649 [Default:CheckIn] Running Loop=11
2024-04-25T20:56:46.649Z,1714078606.649 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T20:56:46.650Z,1714078606.650 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T20:56:48.691Z,1714078608.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205648.00,A,3648.16540,N,12147.28120,W,0.039,0.00,250424,,,A*79
2024-04-25T20:56:48.694Z,1714078608.694 [NAL9602](INFO): GPS fix at 20240425T205648: (36.802757, -121.788020)
2024-04-25T20:56:48.733Z,1714078608.733 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T20:56:48.733Z,1714078608.733 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T20:56:52.194Z,1714078612.194 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240425T191204/Courier0034.lzma
2024-04-25T20:56:53.205Z,1714078613.205 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0034.lzma.bak
2024-04-25T20:56:53.205Z,1714078613.205 [DataOverHttps](INFO): SBD MOMSN=19433427
2024-04-25T20:57:11.547Z,1714078631.547 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20240425T191204/Express0035.lzma
2024-04-25T20:57:12.549Z,1714078632.549 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0035.lzma.bak
2024-04-25T20:57:12.549Z,1714078632.549 [DataOverHttps](INFO): SBD MOMSN=19433430
2024-04-25T20:57:15.846Z,1714078635.846 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T20:57:15.846Z,1714078635.846 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T20:57:15.846Z,1714078635.846 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T20:57:19.457Z,1714078639.457 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-04-25T20:57:19.531Z,1714078639.531 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-04-25T20:59:08.128Z,1714078748.128 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-04-25T21:00:39.432Z,1714078839.432 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:00:42.404Z,1714078842.404 [CommandExec](IMPORTANT): got command failComponent
2024-04-25T21:00:42.404Z,1714078842.404 [CommandExec](IMPORTANT): Failed components:
2024-04-25T21:00:42.404Z,1714078842.404 [CommandExec](IMPORTANT): No failed Components.
2024-04-25T21:00:54.379Z,1714078854.379 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:00:56.183Z,1714078856.183 [CommandExec](IMPORTANT): got command failComponent none DropWeight
2024-04-25T21:00:56.183Z,1714078856.183 [CommandExec](IMPORTANT): DropWeight failureMode is No Fault
2024-04-25T21:01:09.332Z,1714078869.332 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:01:24.275Z,1714078884.275 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:01:39.628Z,1714078899.628 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:01:51.365Z,1714078911.365 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-25T21:01:54.576Z,1714078914.576 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:02:09.523Z,1714078929.523 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:02:16.406Z,1714078936.406 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T21:02:16.406Z,1714078936.406 [Default:CheckIn:C.Wait] Stopped
2024-04-25T21:02:16.407Z,1714078936.407 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T21:02:16.407Z,1714078936.407 [Default:CheckIn:D] Running Loop=1
2024-04-25T21:02:16.815Z,1714078936.815 [Default:CheckIn:D] Stopped
2024-04-25T21:02:16.815Z,1714078936.815 [Default:CheckIn:E] Running Loop=1
2024-04-25T21:02:17.248Z,1714078937.248 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.904028 min
2024-04-25T21:02:17.248Z,1714078937.248 [Default:CheckIn:E] Stopped
2024-04-25T21:02:17.248Z,1714078937.248 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T21:02:17.249Z,1714078937.249 [Default:CheckIn] Stopped
2024-04-25T21:02:17.249Z,1714078937.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T21:02:17.249Z,1714078937.249 [Default:CheckIn](INFO): Running loop #12
2024-04-25T21:02:17.249Z,1714078937.249 [Default:CheckIn] Running Loop=12
2024-04-25T21:02:17.249Z,1714078937.249 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T21:02:17.249Z,1714078937.249 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T21:02:19.223Z,1714078939.223 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210218.00,A,3648.16228,N,12147.28197,W,0.272,71.40,250424,,,A*46
2024-04-25T21:02:19.226Z,1714078939.226 [NAL9602](INFO): GPS fix at 20240425T210218: (36.802705, -121.788033)
2024-04-25T21:02:19.246Z,1714078939.246 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T21:02:19.246Z,1714078939.246 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T21:02:24.503Z,1714078944.503 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:02:31.654Z,1714078951.654 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240425T191204/Courier0037.lzma
2024-04-25T21:02:32.657Z,1714078952.657 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0037.lzma.bak
2024-04-25T21:02:32.657Z,1714078952.657 [DataOverHttps](INFO): SBD MOMSN=19439881
2024-04-25T21:02:39.481Z,1714078959.481 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:02:51.159Z,1714078971.159 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20240425T191204/Express0038.lzma
2024-04-25T21:02:51.215Z,1714078971.215 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T21:02:53.154Z,1714078973.154 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0038.lzma.bak
2024-04-25T21:02:53.155Z,1714078973.155 [DataOverHttps](INFO): SBD MOMSN=19439884
2024-04-25T21:02:54.842Z,1714078974.842 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:02:55.696Z,1714078975.696 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T21:02:55.696Z,1714078975.696 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T21:02:55.696Z,1714078975.696 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T21:03:36.866Z,1714079016.866 [DVL_micro](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BI,-020,+00000,I
2024-04-25T21:03:36.866Z,1714079016.866 [DVL_micro](ERROR): Failed to parse:
:BI,-020,+00000,I
2024-04-25T21:07:56.423Z,1714079276.423 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T21:07:56.423Z,1714079276.423 [Default:CheckIn:C.Wait] Stopped
2024-04-25T21:07:56.423Z,1714079276.423 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T21:07:56.423Z,1714079276.423 [Default:CheckIn:D] Running Loop=1
2024-04-25T21:07:56.827Z,1714079276.827 [Default:CheckIn:D] Stopped
2024-04-25T21:07:56.827Z,1714079276.827 [Default:CheckIn:E] Running Loop=1
2024-04-25T21:07:57.200Z,1714079277.200 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.570500 min
2024-04-25T21:07:57.200Z,1714079277.200 [Default:CheckIn:E] Stopped
2024-04-25T21:07:57.200Z,1714079277.200 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T21:07:57.200Z,1714079277.200 [Default:CheckIn] Stopped
2024-04-25T21:07:57.200Z,1714079277.200 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T21:07:57.201Z,1714079277.201 [Default:CheckIn](INFO): Running loop #13
2024-04-25T21:07:57.201Z,1714079277.201 [Default:CheckIn] Running Loop=13
2024-04-25T21:07:57.201Z,1714079277.201 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T21:07:57.201Z,1714079277.201 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T21:07:59.206Z,1714079279.206 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210758.00,A,3648.16561,N,12147.28094,W,0.019,71.40,250424,,,A*40
2024-04-25T21:07:59.209Z,1714079279.209 [NAL9602](INFO): GPS fix at 20240425T210758: (36.802760, -121.788016)
2024-04-25T21:07:59.220Z,1714079279.220 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T21:07:59.220Z,1714079279.220 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T21:08:08.090Z,1714079288.090 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240425T191204/Courier0040.lzma
2024-04-25T21:08:09.093Z,1714079289.093 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0040.lzma.bak
2024-04-25T21:08:09.093Z,1714079289.093 [DataOverHttps](INFO): SBD MOMSN=19477616
2024-04-25T21:08:30.062Z,1714079310.062 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-04-25T21:08:30.135Z,1714079310.135 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-04-25T21:08:31.315Z,1714079311.315 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240425T191204/Express0041.lzma
2024-04-25T21:08:32.317Z,1714079312.317 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0041.lzma.bak
2024-04-25T21:08:32.317Z,1714079312.317 [DataOverHttps](INFO): SBD MOMSN=19477620
2024-04-25T21:08:35.656Z,1714079315.656 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T21:08:35.656Z,1714079315.656 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T21:08:35.656Z,1714079315.656 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T21:10:30.337Z,1714079430.337 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:10:45.292Z,1714079445.292 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:11:00.230Z,1714079460.230 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:11:15.582Z,1714079475.582 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:11:30.536Z,1714079490.536 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:11:45.479Z,1714079505.479 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:12:00.026Z,1714079520.026 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:12:14.971Z,1714079534.971 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:12:29.564Z,1714079549.564 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:12:44.511Z,1714079564.511 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-25T21:13:01.489Z,1714079581.489 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-25T21:13:32.200Z,1714079612.200 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T21:13:36.240Z,1714079616.240 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T21:13:36.240Z,1714079616.240 [Default:CheckIn:C.Wait] Stopped
2024-04-25T21:13:36.240Z,1714079616.240 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T21:13:36.240Z,1714079616.240 [Default:CheckIn:D] Running Loop=1
2024-04-25T21:13:36.642Z,1714079616.642 [Default:CheckIn:D] Stopped
2024-04-25T21:13:36.643Z,1714079616.643 [Default:CheckIn:E] Running Loop=1
2024-04-25T21:13:37.046Z,1714079617.046 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.234497 min
2024-04-25T21:13:37.046Z,1714079617.046 [Default:CheckIn:E] Stopped
2024-04-25T21:13:37.046Z,1714079617.046 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T21:13:37.046Z,1714079617.046 [Default:CheckIn] Stopped
2024-04-25T21:13:37.046Z,1714079617.046 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T21:13:37.046Z,1714079617.046 [Default:CheckIn](INFO): Running loop #14
2024-04-25T21:13:37.046Z,1714079617.046 [Default:CheckIn] Running Loop=14
2024-04-25T21:13:37.046Z,1714079617.046 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T21:13:37.046Z,1714079617.046 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T21:13:39.054Z,1714079619.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211338.00,A,3648.16862,N,12147.27884,W,0.253,71.40,250424,,,D*42
2024-04-25T21:13:39.056Z,1714079619.056 [NAL9602](INFO): GPS fix at 20240425T211338: (36.802810, -121.787981)
2024-04-25T21:13:39.092Z,1714079619.092 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T21:13:39.092Z,1714079619.092 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T21:13:50.819Z,1714079630.819 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240425T191204/Courier0043.lzma
2024-04-25T21:13:51.817Z,1714079631.817 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0043.lzma.bak
2024-04-25T21:13:51.817Z,1714079631.817 [DataOverHttps](INFO): SBD MOMSN=19515317
2024-04-25T21:14:09.864Z,1714079649.864 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-04-25T21:14:09.947Z,1714079649.947 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-04-25T21:14:13.859Z,1714079653.859 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240425T191204/Express0044.lzma
2024-04-25T21:14:14.863Z,1714079654.863 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0044.lzma.bak
2024-04-25T21:14:14.864Z,1714079654.864 [DataOverHttps](INFO): SBD MOMSN=19515320
2024-04-25T21:14:19.625Z,1714079659.625 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T21:14:19.625Z,1714079659.625 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T21:14:19.625Z,1714079659.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T21:14:19.983Z,1714079659.983 [BPC1](ERROR): Battery stick #3 (s/n: 00BF) reported TERMINATE_CHARGE_ALARM. Status code: 0x42D0.
2024-04-25T21:14:19.984Z,1714079659.984 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4.
2024-04-25T21:14:19.986Z,1714079659.986 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-04-25T21:14:26.918Z,1714079666.918 [NAL9602](INFO): SBD MO Status=2, MOMSN=19470, MT Status=2, MTMSN=0
2024-04-25T21:14:26.918Z,1714079666.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-25T21:14:39.866Z,1714079679.866 [NAL9602](INFO): SBD MO Status=2, MOMSN=19470, MT Status=2, MTMSN=0
2024-04-25T21:14:39.866Z,1714079679.866 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-25T21:18:41.141Z,1714079921.141 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-25T21:19:11.855Z,1714079951.855 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T21:19:20.360Z,1714079960.360 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T21:19:20.360Z,1714079960.360 [Default:CheckIn:C.Wait] Stopped
2024-04-25T21:19:20.360Z,1714079960.360 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T21:19:20.360Z,1714079960.360 [Default:CheckIn:D] Running Loop=1
2024-04-25T21:19:20.747Z,1714079960.747 [Default:CheckIn:D] Stopped
2024-04-25T21:19:20.747Z,1714079960.747 [Default:CheckIn:E] Running Loop=1
2024-04-25T21:19:21.154Z,1714079961.154 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.969572 min
2024-04-25T21:19:21.154Z,1714079961.154 [Default:CheckIn:E] Stopped
2024-04-25T21:19:21.154Z,1714079961.154 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T21:19:21.154Z,1714079961.154 [Default:CheckIn] Stopped
2024-04-25T21:19:21.154Z,1714079961.154 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T21:19:21.154Z,1714079961.154 [Default:CheckIn](INFO): Running loop #15
2024-04-25T21:19:21.155Z,1714079961.155 [Default:CheckIn] Running Loop=15
2024-04-25T21:19:21.155Z,1714079961.155 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T21:19:21.155Z,1714079961.155 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T21:19:23.158Z,1714079963.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211922.00,A,3648.17194,N,12147.28101,W,1.594,344.36,250424,,,D*70
2024-04-25T21:19:23.168Z,1714079963.168 [NAL9602](INFO): GPS fix at 20240425T211922: (36.802866, -121.788017)
2024-04-25T21:19:23.188Z,1714079963.188 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T21:19:23.188Z,1714079963.188 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T21:19:26.220Z,1714079966.220 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240425T191204/Courier0046.lzma
2024-04-25T21:19:27.225Z,1714079967.225 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0046.lzma.bak
2024-04-25T21:19:27.225Z,1714079967.225 [DataOverHttps](INFO): SBD MOMSN=19552962
2024-04-25T21:19:45.684Z,1714079985.684 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20240425T191204/Express0047.lzma
2024-04-25T21:19:48.480Z,1714079988.480 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0047.lzma.bak
2024-04-25T21:19:48.480Z,1714079988.480 [DataOverHttps](INFO): SBD MOMSN=19552966
2024-04-25T21:19:52.793Z,1714079992.793 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T21:19:52.793Z,1714079992.793 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T21:19:52.793Z,1714079992.793 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T21:19:54.039Z,1714079994.039 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-04-25T21:19:54.119Z,1714079994.119 [NAL9602](ERROR): received:
+CSQ:0
OK470, 2, 0, 0, 0
OK
2024-04-25T21:20:25.490Z,1714080025.490 [NAL9602](INFO): SBD MO Status=0, MOMSN=19470, MT Status=0, MTMSN=0
2024-04-25T21:20:25.490Z,1714080025.490 [NAL9602](INFO): No messages in MT queue
2024-04-25T21:20:56.194Z,1714080056.194 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T21:21:27.132Z,1714080087.132 [CommandExec](IMPORTANT): got command ibit
2024-04-25T21:21:27.358Z,1714080087.358 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-04-25T21:21:27.358Z,1714080087.358 [IBIT](IMPORTANT): Beginning control surface checks.
2024-04-25T21:21:27.361Z,1714080087.361 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-25T21:21:28.927Z,1714080088.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212128.00,A,3648.17594,N,12147.27817,W,0.136,344.36,250424,,,D*79
2024-04-25T21:21:28.929Z,1714080088.929 [NAL9602](INFO): GPS fix at 20240425T212128: (36.802932, -121.787970)
2024-04-25T21:21:31.320Z,1714080091.320 [CommandExec](IMPORTANT): got command failComponent
2024-04-25T21:21:31.320Z,1714080091.320 [CommandExec](IMPORTANT): Failed components:
2024-04-25T21:21:31.320Z,1714080091.320 [CommandExec](IMPORTANT): No failed Components.
2024-04-25T21:21:38.236Z,1714080098.236 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.027759
CHAN A1 (24V): 0.025385
CHAN A2 (12V): -0.010890
CHAN A3 (5V): -0.020806
CHAN B0 (3.3V): -0.011591
CHAN B1 (3.15aV): -0.007459
CHAN B2 (3.15bV): -0.003770
CHAN B3 (GND): -0.010501
OPEN: 0.005083
Full Scale: +/- 1 mA
2024-04-25T21:21:51.950Z,1714080111.950 [NAL9602](INFO): SBD MO Status=2, MOMSN=19471, MT Status=2, MTMSN=0
2024-04-25T21:21:51.950Z,1714080111.950 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-25T21:22:01.255Z,1714080121.255 [NAL9602](INFO): SBD MO Status=0, MOMSN=19471, MT Status=0, MTMSN=0
2024-04-25T21:22:01.255Z,1714080121.255 [NAL9602](INFO): No messages in MT queue
2024-04-25T21:22:01.255Z,1714080121.255 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2024-04-25T21:22:02.451Z,1714080122.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212201.00,A,3648.17790,N,12147.27906,W,0.117,344.36,250424,,,D*75
2024-04-25T21:22:02.453Z,1714080122.453 [NAL9602](INFO): GPS fix at 20240425T212201: (36.802965, -121.787984)
2024-04-25T21:22:04.876Z,1714080124.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212204.00,A,3648.17809,N,12147.27909,W,0.078,344.36,250424,,,D*78
2024-04-25T21:22:04.878Z,1714080124.878 [NAL9602](INFO): GPS fix at 20240425T212204: (36.802968, -121.787985)
2024-04-25T21:22:08.114Z,1714080128.114 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212207.00,A,3648.17821,N,12147.27938,W,0.039,344.36,250424,,,D*76
2024-04-25T21:22:08.117Z,1714080128.117 [NAL9602](INFO): GPS fix at 20240425T212207: (36.802970, -121.787990)
2024-04-25T21:22:10.935Z,1714080130.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212210.00,A,3648.17856,N,12147.27953,W,0.194,344.36,250424,,,D*7B
2024-04-25T21:22:10.937Z,1714080130.937 [NAL9602](INFO): GPS fix at 20240425T212210: (36.802976, -121.787992)
2024-04-25T21:22:13.809Z,1714080133.809 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802975 Longitude: -121.787994
2024-04-25T21:22:14.173Z,1714080134.173 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212213.00,A,3648.17819,N,12147.28018,W,0.272,344.36,250424,,,D*71
2024-04-25T21:22:14.175Z,1714080134.175 [NAL9602](INFO): GPS fix at 20240425T212213: (36.802970, -121.788003)
2024-04-25T21:22:14.191Z,1714080134.191 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.580000
2024-04-25T21:22:14.192Z,1714080134.192 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-04-25T21:22:14.192Z,1714080134.192 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-04-25T21:22:14.629Z,1714080134.629 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-04-25T21:22:14.629Z,1714080134.629 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-04-25T21:22:14.630Z,1714080134.630 [IBIT](IMPORTANT): Pressure:10.027157 PSI
2024-04-25T21:22:14.630Z,1714080134.630 [IBIT](IMPORTANT): Humidity:24.258333 %
2024-04-25T21:22:14.996Z,1714080134.996 [IBIT](IMPORTANT): Vehicle Pitch:-2.351502 degrees
2024-04-25T21:22:14.997Z,1714080134.997 [IBIT](IMPORTANT): Vehicle Roll:-3.024786 degrees
2024-04-25T21:22:14.997Z,1714080134.997 [IBIT](IMPORTANT): Vehicle Heading:33.119942 degrees
2024-04-25T21:22:15.409Z,1714080135.409 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-04-25T21:22:15.409Z,1714080135.409 [IBIT](IMPORTANT): buoyancyNeutral: 150.000000 cc
2024-04-25T21:22:15.409Z,1714080135.409 [IBIT](IMPORTANT): massDefault: 1.240687 cm
2024-04-25T21:22:15.410Z,1714080135.410 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2024-04-25T21:22:15.410Z,1714080135.410 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-04-25T21:22:15.410Z,1714080135.410 [IBIT](IMPORTANT): IBIT PASSED
2024-04-25T21:22:46.502Z,1714080166.502 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T21:22:52.397Z,1714080172.397 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute
2024-04-25T21:22:52.401Z,1714080172.401 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2024-04-25T21:22:52.593Z,1714080172.593 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-04-25T21:23:04.481Z,1714080184.481 [CommandExec](IMPORTANT): got command maintain clear
2024-04-25T21:23:04.780Z,1714080184.780 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-04-25T21:24:51.800Z,1714080291.800 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-04-25T21:24:51.800Z,1714080291.800 [DropWeight] Hardware Fault, FailCount= 2
2024-04-25T21:24:51.800Z,1714080291.800 [DropWeight](ERROR): Hardware Fault
2024-04-25T21:24:51.818Z,1714080291.818 [CBIT](INFO): Critical error at 20240425T212451
2024-04-25T21:24:51.821Z,1714080291.821 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-04-25T21:24:51.821Z,1714080291.821 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-04-25T21:24:52.246Z,1714080292.246 [CBIT](INFO): Critical error at 20240425T212451
2024-04-25T21:24:53.453Z,1714080293.453 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T21:24:53.453Z,1714080293.453 [Default:CheckIn:C.Wait] Stopped
2024-04-25T21:24:53.453Z,1714080293.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T21:24:53.453Z,1714080293.453 [Default:CheckIn:D] Running Loop=1
2024-04-25T21:24:53.855Z,1714080293.855 [Default:CheckIn:D] Stopped
2024-04-25T21:24:53.855Z,1714080293.855 [Default:CheckIn:E] Running Loop=1
2024-04-25T21:24:54.262Z,1714080294.262 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.521297 min
2024-04-25T21:24:54.262Z,1714080294.262 [Default:CheckIn:E] Stopped
2024-04-25T21:24:54.262Z,1714080294.262 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T21:24:54.262Z,1714080294.262 [Default:CheckIn] Stopped
2024-04-25T21:24:54.262Z,1714080294.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T21:24:54.262Z,1714080294.262 [Default:CheckIn](INFO): Running loop #16
2024-04-25T21:24:54.262Z,1714080294.262 [Default:CheckIn] Running Loop=16
2024-04-25T21:24:54.262Z,1714080294.262 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T21:24:54.263Z,1714080294.263 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T21:24:56.259Z,1714080296.259 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212455.00,A,3648.17216,N,12147.27309,W,2.371,123.50,250424,,,D*7F
2024-04-25T21:24:56.262Z,1714080296.262 [NAL9602](INFO): GPS fix at 20240425T212455: (36.802869, -121.787885)
2024-04-25T21:24:56.273Z,1714080296.273 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T21:24:56.273Z,1714080296.273 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T21:25:06.441Z,1714080306.441 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240425T191204/Courier0049.lzma
2024-04-25T21:25:07.441Z,1714080307.441 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0049.lzma.bak
2024-04-25T21:25:07.441Z,1714080307.441 [DataOverHttps](INFO): SBD MOMSN=19590667
2024-04-25T21:25:25.469Z,1714080325.469 [DataOverHttps](INFO): Sending 869 bytes from file Logs/20240425T191204/Express0050.lzma
2024-04-25T21:25:26.469Z,1714080326.469 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0050.lzma.bak
2024-04-25T21:25:26.469Z,1714080326.469 [DataOverHttps](INFO): SBD MOMSN=19590672
2024-04-25T21:25:29.807Z,1714080329.807 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T21:25:29.807Z,1714080329.807 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T21:25:29.807Z,1714080329.807 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T21:27:43.546Z,1714080463.546 [NAL9602](INFO): SBD MO Status=2, MOMSN=19472, MT Status=2, MTMSN=0
2024-04-25T21:27:43.547Z,1714080463.547 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-25T21:28:42.934Z,1714080522.934 [NAL9602](INFO): SBD MO Status=2, MOMSN=19472, MT Status=2, MTMSN=0
2024-04-25T21:28:42.935Z,1714080522.935 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-25T21:28:56.666Z,1714080536.666 [NAL9602](INFO): SBD MO Status=0, MOMSN=19472, MT Status=0, MTMSN=0
2024-04-25T21:28:56.666Z,1714080536.666 [NAL9602](INFO): No messages in MT queue
2024-04-25T21:29:27.368Z,1714080567.368 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T21:30:30.464Z,1714080630.464 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-25T21:30:30.464Z,1714080630.464 [Default:CheckIn:C.Wait] Stopped
2024-04-25T21:30:30.464Z,1714080630.464 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T21:30:30.464Z,1714080630.464 [Default:CheckIn:D] Running Loop=1
2024-04-25T21:30:30.861Z,1714080630.861 [Default:CheckIn:D] Stopped
2024-04-25T21:30:30.861Z,1714080630.861 [Default:CheckIn:E] Running Loop=1
2024-04-25T21:30:31.273Z,1714080631.273 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.138135 min
2024-04-25T21:30:31.274Z,1714080631.274 [Default:CheckIn:E] Stopped
2024-04-25T21:30:31.274Z,1714080631.274 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-25T21:30:31.274Z,1714080631.274 [Default:CheckIn] Stopped
2024-04-25T21:30:31.274Z,1714080631.274 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T21:30:31.274Z,1714080631.274 [Default:CheckIn](INFO): Running loop #17
2024-04-25T21:30:31.274Z,1714080631.274 [Default:CheckIn] Running Loop=17
2024-04-25T21:30:31.274Z,1714080631.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-25T21:30:31.274Z,1714080631.274 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-25T21:30:33.278Z,1714080633.278 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213032.00,A,3648.14502,N,12147.22859,W,0.058,35.07,250424,,,D*4F
2024-04-25T21:30:33.281Z,1714080633.281 [NAL9602](INFO): GPS fix at 20240425T213032: (36.802417, -121.787143)
2024-04-25T21:30:33.304Z,1714080633.304 [Default:CheckIn:Read_GPS] Stopped
2024-04-25T21:30:33.304Z,1714080633.304 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-25T21:30:42.106Z,1714080642.106 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240425T191204/Courier0052.lzma
2024-04-25T21:30:43.109Z,1714080643.109 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Courier0052.lzma.bak
2024-04-25T21:30:43.109Z,1714080643.109 [DataOverHttps](INFO): SBD MOMSN=19590724
2024-04-25T21:31:01.491Z,1714080661.491 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240425T191204/Express0053.lzma
2024-04-25T21:31:02.493Z,1714080662.493 [DataOverHttps](INFO): Moved sent file to Logs/20240425T191204/Express0053.lzma.bak
2024-04-25T21:31:02.493Z,1714080662.493 [DataOverHttps](INFO): SBD MOMSN=19590784
2024-04-25T21:31:06.019Z,1714080666.019 [Default:CheckIn:Read_Iridium] Stopped
2024-04-25T21:31:06.019Z,1714080666.019 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-25T21:31:06.019Z,1714080666.019 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-25T21:31:13.279Z,1714080673.279 [NAL9602](INFO): SBD MO Status=0, MOMSN=19473, MT Status=0, MTMSN=0
2024-04-25T21:31:13.280Z,1714080673.280 [NAL9602](INFO): No messages in MT queue
2024-04-25T21:31:43.981Z,1714080703.981 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-25T21:34:23.453Z,1714080863.453 [DataOverHttps](IMPORTANT): SBD MTMSN=20240425T213422
2024-04-25T21:34:32.328Z,1714080872.328 [DataOverHttps](INFO): Received command: configSet DropWeight.loadAtStartup 0 bool persist;restart app
2024-04-25T21:34:32.999Z,1714080872.999 [CommandExec](IMPORTANT): got command configSet DropWeight.loadAtStartup 0 bool persist
2024-04-25T21:34:32.999Z,1714080872.999 [CommandExec](IMPORTANT): configSet DropWeight.loadAtStartup requires a restart to take effect.
2024-04-25T21:34:33.108Z,1714080873.108 [CommandExec](IMPORTANT): got command restart application
2024-04-25T21:34:34.111Z,1714080874.111 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:34.111Z,1714080874.111 [CommandExec](INFO): Uninitializing the command executive.
2024-04-25T21:34:34.111Z,1714080874.111 [CommandExec](INFO): Uninitializing the command scheduler.
2024-04-25T21:34:34.111Z,1714080874.111 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:34.279Z,1714080874.279 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-04-25T21:34:34.279Z,1714080874.279 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-04-25T21:34:34.279Z,1714080874.279 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:34.280Z,1714080874.280 [NavChartDb](INFO): Join timeout helper Thread ID is 1800
2024-04-25T21:34:34.308Z,1714080874.308 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:34.309Z,1714080874.309 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:34.319Z,1714080874.319 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-04-25T21:34:34.319Z,1714080874.319 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:34.320Z,1714080874.320 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1801
2024-04-25T21:34:34.448Z,1714080874.448 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:34.448Z,1714080874.448 [WetLabsBB2FL](INFO): Powering down
2024-04-25T21:34:34.449Z,1714080874.449 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:34.463Z,1714080874.463 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-04-25T21:34:34.463Z,1714080874.463 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:34.463Z,1714080874.463 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1802
2024-04-25T21:34:35.275Z,1714080875.275 [CTD_Seabird](INFO): Powering down
2024-04-25T21:34:35.287Z,1714080875.287 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:35.287Z,1714080875.287 [CTD_Seabird](INFO): Powering down
2024-04-25T21:34:35.299Z,1714080875.299 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:35.315Z,1714080875.315 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-04-25T21:34:35.315Z,1714080875.315 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:35.316Z,1714080875.316 [DAT](INFO): Join timeout helper Thread ID is 1803
2024-04-25T21:34:35.411Z,1714080875.411 [DAT](INFO): Powering down
2024-04-25T21:34:35.483Z,1714080875.483 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:35.484Z,1714080875.484 [DAT](INFO): Powering down
2024-04-25T21:34:35.484Z,1714080875.484 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:35.491Z,1714080875.491 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-04-25T21:34:35.491Z,1714080875.491 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:35.491Z,1714080875.491 [Radio_Surface](INFO): Join timeout helper Thread ID is 1804
2024-04-25T21:34:35.495Z,1714080875.495 [Radio_Surface](INFO): Powering down
2024-04-25T21:34:35.496Z,1714080875.496 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:35.496Z,1714080875.496 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:35.512Z,1714080875.512 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-04-25T21:34:35.512Z,1714080875.512 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:35.513Z,1714080875.513 [Onboard](INFO): Join timeout helper Thread ID is 1805
2024-04-25T21:34:36.340Z,1714080876.340 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-04-25T21:34:37.963Z,1714080877.963 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:37.963Z,1714080877.963 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:37.976Z,1714080877.976 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-04-25T21:34:37.976Z,1714080877.976 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:37.976Z,1714080877.976 [DataOverHttps](INFO): Join timeout helper Thread ID is 1806
2024-04-25T21:34:38.347Z,1714080878.347 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:38.347Z,1714080878.347 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:38.368Z,1714080878.368 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-04-25T21:34:38.368Z,1714080878.368 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:38.368Z,1714080878.368 [BackseatComponent](INFO): Join timeout helper Thread ID is 1807
2024-04-25T21:34:38.448Z,1714080878.448 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:38.448Z,1714080878.448 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:38.468Z,1714080878.468 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-04-25T21:34:38.468Z,1714080878.468 [logger ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:38.468Z,1714080878.468 [logger](INFO): Join timeout helper Thread ID is 1808
2024-04-25T21:34:38.536Z,1714080878.536 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:38.536Z,1714080878.536 [logger ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:38.548Z,1714080878.548 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-04-25T21:34:38.548Z,1714080878.548 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:38.548Z,1714080878.548 [CommandLine](INFO): Join timeout helper Thread ID is 1809
2024-04-25T21:34:38.623Z,1714080878.623 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:38.624Z,1714080878.624 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:38.636Z,1714080878.636 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-04-25T21:34:38.636Z,1714080878.636 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:38.637Z,1714080878.637 [CommandExec](INFO): Join timeout helper Thread ID is 1810
2024-04-25T21:34:38.638Z,1714080878.638 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-04-25T21:34:38.639Z,1714080878.639 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:38.639Z,1714080878.639 [controlThread](INFO): Join timeout helper Thread ID is 1811
2024-04-25T21:34:38.915Z,1714080878.915 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-25T21:34:38.915Z,1714080878.915 [controlThread](DEBUG): Uninitializing ControlThread
2024-04-25T21:34:38.916Z,1714080878.916 [AHRS_M2](INFO): Powering down
2024-04-25T21:34:39.083Z,1714080879.083 [DVL_micro](INFO): Powering down
2024-04-25T21:34:39.084Z,1714080879.084 [NAL9602](INFO): Powering down
2024-04-25T21:34:39.085Z,1714080879.085 [Sonardyne_Nano](INFO): Powering down
2024-04-25T21:34:39.205Z,1714080879.205 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-04-25T21:34:39.206Z,1714080879.206 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-04-25T21:34:39.206Z,1714080879.206 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-04-25T21:34:39.207Z,1714080879.207 [MissionManager](INFO): Uninitializing Mission Default
2024-04-25T21:34:39.207Z,1714080879.207 [Default] Stopped
2024-04-25T21:34:39.207Z,1714080879.207 [Default](DEBUG): Aggregate::uninitialize Default
2024-04-25T21:34:39.207Z,1714080879.207 [Default:B.GoToSurface] Stopped
2024-04-25T21:34:39.207Z,1714080879.207 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-25T21:34:39.207Z,1714080879.207 [Default:CheckIn] Stopped
2024-04-25T21:34:39.207Z,1714080879.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-25T21:34:39.207Z,1714080879.207 [Default:CheckIn:C.Wait] Stopped
2024-04-25T21:34:39.207Z,1714080879.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-25T21:34:39.210Z,1714080879.210 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-04-25T21:34:39.210Z,1714080879.210 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-04-25T21:34:39.211Z,1714080879.211 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-04-25T21:34:39.211Z,1714080879.211 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-04-25T21:34:39.211Z,1714080879.211 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-04-25T21:34:39.212Z,1714080879.212 [BuoyancyServo](INFO): Powering down
2024-04-25T21:34:39.223Z,1714080879.223 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-04-25T21:34:39.223Z,1714080879.223 [ElevatorServo](INFO): Powering down
2024-04-25T21:34:39.224Z,1714080879.224 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-04-25T21:34:39.224Z,1714080879.224 [MassServo](INFO): Powering down
2024-04-25T21:34:39.225Z,1714080879.225 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-04-25T21:34:39.225Z,1714080879.225 [RudderServo](INFO): Powering down
2024-04-25T21:34:39.226Z,1714080879.226 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-04-25T21:34:39.226Z,1714080879.226 [ThrusterHE](INFO): Powering down
2024-04-25T21:34:39.227Z,1714080879.227 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-04-25T21:34:39.227Z,1714080879.227 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-04-25T21:34:39.228Z,1714080879.228 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-04-25T21:34:39.228Z,1714080879.228 [CBIT](DEBUG): Powering off loads.
2024-04-25T21:34:39.239Z,1714080879.239 [CBIT](DEBUG): Disabling WDT.
2024-04-25T21:34:39.251Z,1714080879.251 [CBIT](DEBUG): Opening all GF detection circuits.
2024-04-25T21:34:39.252Z,1714080879.252 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:39.296Z,1714080879.296 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:39.302Z,1714080879.302 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:39.304Z,1714080879.304 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:39.312Z,1714080879.312 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:39.356Z,1714080879.356 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:39.409Z,1714080879.409 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:39.415Z,1714080879.415 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:39.437Z,1714080879.437 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-25T21:34:39.512Z,1714080879.512 [logger ThreadHandler](INFO): Thread cancelled.