2024-12-17T17:14:39.307Z,1734455679.307 [Supervisor](DEBUG): Initializing supervisor.
2024-12-17T17:14:39.312Z,1734455679.312 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-12-17T17:14:39.312Z,1734455679.312 [SyncHandler](INFO): Protected caller Thread ID is 7013
2024-12-17T17:14:39.313Z,1734455679.313 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-12-17T17:14:39.314Z,1734455679.314 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-12-17T17:14:39.314Z,1734455679.314 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7014
2024-12-17T17:14:39.318Z,1734455679.318 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-12-17T17:14:39.335Z,1734455679.335 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-12-17T17:14:39.336Z,1734455679.336 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-12-17T17:14:39.337Z,1734455679.337 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7015
2024-12-17T17:14:39.341Z,1734455679.341 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-12-17T17:14:39.342Z,1734455679.342 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-12-17T17:14:39.342Z,1734455679.342 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7016
2024-12-17T17:14:39.344Z,1734455679.344 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-12-17T17:14:39.345Z,1734455679.345 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-12-17T17:14:39.345Z,1734455679.345 [logger ThreadHandler](INFO): Protected caller Thread ID is 7017
2024-12-17T17:14:39.349Z,1734455679.349 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-12-17T17:14:39.349Z,1734455679.349 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-12-17T17:14:39.351Z,1734455679.351 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-12-17T17:14:39.754Z,1734455679.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-12-17T17:14:39.755Z,1734455679.755 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-12-17T17:14:39.846Z,1734455679.846 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-12-17T17:14:39.963Z,1734455679.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-12-17T17:14:39.964Z,1734455679.964 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-12-17T17:14:40.102Z,1734455680.102 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-12-17T17:14:40.103Z,1734455680.103 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-12-17T17:14:40.450Z,1734455680.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-12-17T17:14:40.450Z,1734455680.450 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-12-17T17:14:40.558Z,1734455680.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-12-17T17:14:40.558Z,1734455680.558 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-12-17T17:14:40.970Z,1734455680.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-12-17T17:14:40.971Z,1734455680.971 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-12-17T17:14:41.189Z,1734455681.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-12-17T17:14:41.189Z,1734455681.189 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-12-17T17:14:41.280Z,1734455681.280 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-12-17T17:14:41.522Z,1734455681.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-12-17T17:14:41.523Z,1734455681.523 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-12-17T17:14:41.749Z,1734455681.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-12-17T17:14:41.749Z,1734455681.749 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-12-17T17:14:42.270Z,1734455682.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-12-17T17:14:42.271Z,1734455682.271 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-12-17T17:14:42.464Z,1734455682.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-12-17T17:14:42.464Z,1734455682.464 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-12-17T17:14:42.754Z,1734455682.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-12-17T17:14:42.755Z,1734455682.755 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-12-17T17:14:43.640Z,1734455683.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-12-17T17:14:43.640Z,1734455683.640 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-12-17T17:14:44.232Z,1734455684.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-12-17T17:14:44.234Z,1734455684.234 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2024-12-17T17:14:44.235Z,1734455684.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2024-12-17T17:14:44.344Z,1734455684.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2024-12-17T17:14:44.480Z,1734455684.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2024-12-17T17:14:44.575Z,1734455684.575 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2024-12-17T17:14:44.692Z,1734455684.692 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2024-12-17T17:14:44.790Z,1734455684.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2024-12-17T17:14:45.054Z,1734455685.054 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-12-17T17:14:45.055Z,1734455685.055 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2024-12-17T17:14:45.174Z,1734455685.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2024-12-17T17:14:45.282Z,1734455685.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2024-12-17T17:14:45.431Z,1734455685.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2024-12-17T17:14:45.527Z,1734455685.527 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-12-17T17:14:45.544Z,1734455685.544 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-12-17T17:14:45.682Z,1734455685.682 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-12-17T17:14:45.682Z,1734455685.682 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-12-17T17:14:45.735Z,1734455685.735 [VerticalControl](DEBUG): Construct VerticalControl.
2024-12-17T17:14:45.795Z,1734455685.795 [VerticalControl] Loaded
2024-12-17T17:14:45.796Z,1734455685.796 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-12-17T17:14:45.798Z,1734455685.798 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-12-17T17:14:45.847Z,1734455685.847 [HorizontalControl] Loaded
2024-12-17T17:14:45.848Z,1734455685.848 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-12-17T17:14:45.850Z,1734455685.850 [SpeedControl](DEBUG): Construct SpeedControl.
2024-12-17T17:14:45.853Z,1734455685.853 [SpeedControl] Loaded
2024-12-17T17:14:45.853Z,1734455685.853 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-12-17T17:14:45.856Z,1734455685.856 [LoopControl](DEBUG): Construct LoopControl.
2024-12-17T17:14:45.856Z,1734455685.856 [LoopControl] Loaded
2024-12-17T17:14:45.856Z,1734455685.856 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-12-17T17:14:45.857Z,1734455685.857 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-12-17T17:14:45.857Z,1734455685.857 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-12-17T17:14:45.921Z,1734455685.921 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-12-17T17:14:45.922Z,1734455685.922 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-12-17T17:14:46.511Z,1734455686.511 [AHRS_M2] Loaded
2024-12-17T17:14:46.511Z,1734455686.511 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-12-17T17:14:46.767Z,1734455686.767 [BackseatComponent] Loaded
2024-12-17T17:14:46.767Z,1734455686.767 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-12-17T17:14:46.768Z,1734455686.768 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B54E0
2024-12-17T17:14:46.768Z,1734455686.768 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7097
2024-12-17T17:14:46.771Z,1734455686.771 [LcmUniversalReporter] Loaded
2024-12-17T17:14:46.771Z,1734455686.771 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-12-17T17:14:47.602Z,1734455687.602 [BPC1] Loaded
2024-12-17T17:14:47.603Z,1734455687.603 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-12-17T17:14:47.697Z,1734455687.697 [DAT] Loaded
2024-12-17T17:14:47.697Z,1734455687.697 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-12-17T17:14:47.698Z,1734455687.698 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408E54E0
2024-12-17T17:14:47.699Z,1734455687.699 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7098
2024-12-17T17:14:47.795Z,1734455687.795 [DataOverHttps] Loaded
2024-12-17T17:14:47.795Z,1734455687.795 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-12-17T17:14:47.796Z,1734455687.796 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409154E0
2024-12-17T17:14:47.796Z,1734455687.796 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7099
2024-12-17T17:14:47.819Z,1734455687.819 [Depth_Keller] Loaded
2024-12-17T17:14:47.819Z,1734455687.819 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-12-17T17:14:47.824Z,1734455687.824 [DropWeight] Loaded
2024-12-17T17:14:47.824Z,1734455687.824 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-12-17T17:14:47.887Z,1734455687.887 [NAL9602] Loaded
2024-12-17T17:14:47.887Z,1734455687.887 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-12-17T17:14:47.916Z,1734455687.916 [Onboard] Loaded
2024-12-17T17:14:47.917Z,1734455687.917 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-12-17T17:14:47.918Z,1734455687.918 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409454E0
2024-12-17T17:14:47.918Z,1734455687.918 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7100
2024-12-17T17:14:47.931Z,1734455687.931 [Power24vConverter] Loaded
2024-12-17T17:14:47.932Z,1734455687.932 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-12-17T17:14:47.947Z,1734455687.947 [Radio_Surface] Loaded
2024-12-17T17:14:47.947Z,1734455687.947 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-12-17T17:14:47.948Z,1734455687.948 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409754E0
2024-12-17T17:14:47.949Z,1734455687.949 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7101
2024-12-17T17:14:47.964Z,1734455687.964 [Sonardyne_Nano] Loaded
2024-12-17T17:14:47.964Z,1734455687.964 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-12-17T17:14:47.987Z,1734455687.987 [Waterlinked] Loaded
2024-12-17T17:14:47.987Z,1734455687.987 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-12-17T17:14:47.988Z,1734455687.988 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-12-17T17:14:47.988Z,1734455687.988 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-12-17T17:14:47.998Z,1734455687.998 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-12-17T17:14:47.999Z,1734455687.999 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-12-17T17:14:48.027Z,1734455688.027 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-12-17T17:14:48.028Z,1734455688.028 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-12-17T17:14:48.167Z,1734455688.167 [SBIT](DEBUG): Construct Startup Built In Test.
2024-12-17T17:14:48.175Z,1734455688.175 [SBIT] Loaded
2024-12-17T17:14:48.176Z,1734455688.176 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-12-17T17:14:48.178Z,1734455688.178 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-12-17T17:14:48.191Z,1734455688.191 [IBIT] Loaded
2024-12-17T17:14:48.191Z,1734455688.191 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-12-17T17:14:48.197Z,1734455688.197 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-12-17T17:14:48.299Z,1734455688.299 [CBIT] Loaded
2024-12-17T17:14:48.299Z,1734455688.299 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-12-17T17:14:48.300Z,1734455688.300 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-12-17T17:14:48.300Z,1734455688.300 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-12-17T17:14:48.644Z,1734455688.644 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-12-17T17:14:48.644Z,1734455688.644 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-12-17T17:14:48.710Z,1734455688.710 [DepthRateCalculator] Loaded
2024-12-17T17:14:48.710Z,1734455688.710 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-12-17T17:14:48.715Z,1734455688.715 [PitchRateCalculator] Loaded
2024-12-17T17:14:48.715Z,1734455688.715 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-12-17T17:14:48.729Z,1734455688.729 [SpeedCalculator] Loaded
2024-12-17T17:14:48.729Z,1734455688.729 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-12-17T17:14:48.734Z,1734455688.734 [YawRateCalculator] Loaded
2024-12-17T17:14:48.734Z,1734455688.734 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-12-17T17:14:48.752Z,1734455688.752 [ElevatorOffsetCalculator] Loaded
2024-12-17T17:14:48.753Z,1734455688.753 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-12-17T17:14:48.753Z,1734455688.753 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-12-17T17:14:48.754Z,1734455688.754 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-12-17T17:14:48.932Z,1734455688.932 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-12-17T17:14:48.933Z,1734455688.933 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-12-17T17:14:49.120Z,1734455689.120 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-12-17T17:14:49.120Z,1734455689.120 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-12-17T17:14:49.134Z,1734455689.134 [NavChart] Loaded
2024-12-17T17:14:49.134Z,1734455689.134 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-12-17T17:14:49.139Z,1734455689.139 [UniversalFixResidualReporter] Loaded
2024-12-17T17:14:49.140Z,1734455689.140 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-12-17T17:14:49.140Z,1734455689.140 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-12-17T17:14:49.140Z,1734455689.140 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-12-17T17:14:49.274Z,1734455689.274 [BuoyancyServo] Loaded
2024-12-17T17:14:49.275Z,1734455689.275 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-12-17T17:14:49.297Z,1734455689.297 [ElevatorServo] Loaded
2024-12-17T17:14:49.297Z,1734455689.297 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-12-17T17:14:49.319Z,1734455689.319 [MassServo] Loaded
2024-12-17T17:14:49.319Z,1734455689.319 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-12-17T17:14:49.339Z,1734455689.339 [RudderServo] Loaded
2024-12-17T17:14:49.339Z,1734455689.339 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-12-17T17:14:49.356Z,1734455689.356 [ThrusterHE] Loaded
2024-12-17T17:14:49.356Z,1734455689.356 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-12-17T17:14:49.356Z,1734455689.356 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-12-17T17:14:49.357Z,1734455689.357 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-12-17T17:14:49.537Z,1734455689.537 [CTD_Seabird] Loaded
2024-12-17T17:14:49.537Z,1734455689.537 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-12-17T17:14:49.539Z,1734455689.539 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BF04E0
2024-12-17T17:14:49.540Z,1734455689.540 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7103
2024-12-17T17:14:49.575Z,1734455689.575 [ESPComponent] Loaded
2024-12-17T17:14:49.576Z,1734455689.576 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-12-17T17:14:49.597Z,1734455689.597 [PAR_Licor] Loaded
2024-12-17T17:14:49.597Z,1734455689.597 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-12-17T17:14:49.643Z,1734455689.643 [WetLabsBB2FL] Loaded
2024-12-17T17:14:49.643Z,1734455689.643 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-12-17T17:14:49.644Z,1734455689.644 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C204E0
2024-12-17T17:14:49.644Z,1734455689.644 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7104
2024-12-17T17:14:49.645Z,1734455689.645 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-12-17T17:14:49.651Z,1734455689.651 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-12-17T17:14:49.654Z,1734455689.654 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-12-17T17:14:49.666Z,1734455689.666 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-12-17T17:14:49.667Z,1734455689.667 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C504E0
2024-12-17T17:14:49.667Z,1734455689.667 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7105
2024-12-17T17:14:49.671Z,1734455689.671 [Supervisor](INFO): Main Thread ID is 7012
2024-12-17T17:14:49.672Z,1734455689.672 [Supervisor](DEBUG): Running supervisor.
2024-12-17T17:14:49.672Z,1734455689.672 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7106
2024-12-17T17:14:49.673Z,1734455689.673 [CommandExec](INFO): Initializing the command executive.
2024-12-17T17:14:49.674Z,1734455689.674 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7107
2024-12-17T17:14:49.676Z,1734455689.676 [controlThread ThreadHandler](INFO): Handler Thread ID is 7108
2024-12-17T17:14:49.676Z,1734455689.676 [controlThread](DEBUG): Initializing ControlThread
2024-12-17T17:14:49.677Z,1734455689.677 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-12-17T17:14:49.679Z,1734455689.679 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-12-17T17:14:49.680Z,1734455689.680 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-12-17T17:14:49.680Z,1734455689.680 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-12-17T17:14:49.687Z,1734455689.687 [SBIT](INFO): Initialize SBIT Component.
2024-12-17T17:14:49.688Z,1734455689.688 [SBIT](IMPORTANT): git: 2024-12-17_A
2024-12-17T17:14:49.688Z,1734455689.688 [SBIT](INFO): git hash: 35d3830406fd08eef8bec72d532b8ec94802bb61
2024-12-17T17:14:49.688Z,1734455689.688 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-12-17T17:14:49.690Z,1734455689.690 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2024-12-17T17:14:49.691Z,1734455689.691 [SBIT](INFO): Beginning SBIT in 74.000000 seconds.
2024-12-17T17:14:49.692Z,1734455689.692 [IBIT](INFO): Initialize IBIT Component.
2024-12-17T17:14:49.692Z,1734455689.692 [CBIT](DEBUG): Initialize CBIT Component.
2024-12-17T17:14:49.694Z,1734455689.694 [logger ThreadHandler](INFO): Handler Thread ID is 7109
2024-12-17T17:14:49.705Z,1734455689.705 [CBIT](DEBUG): Initialized mux pins.
2024-12-17T17:14:49.705Z,1734455689.705 [CBIT](DEBUG): Initializing the watchdog timer.
2024-12-17T17:14:49.714Z,1734455689.714 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7110
2024-12-17T17:14:49.725Z,1734455689.725 [DAT ThreadHandler](INFO): Handler Thread ID is 7111
2024-12-17T17:14:49.726Z,1734455689.726 [DAT](INFO): Powering up
2024-12-17T17:14:49.726Z,1734455689.726 [DAT](DEBUG): Initializing DAT.
2024-12-17T17:14:49.729Z,1734455689.729 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-12-17T17:14:49.730Z,1734455689.730 [CBIT](DEBUG): Initializing heartbeat.
2024-12-17T17:14:49.731Z,1734455689.731 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7113
2024-12-17T17:14:49.733Z,1734455689.733 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-12-17T17:14:49.746Z,1734455689.746 [Onboard ThreadHandler](INFO): Handler Thread ID is 7114
2024-12-17T17:14:49.763Z,1734455689.763 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7115
2024-12-17T17:14:49.789Z,1734455689.789 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7116
2024-12-17T17:14:49.790Z,1734455689.790 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-12-17T17:14:49.794Z,1734455689.794 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7118
2024-12-17T17:14:49.798Z,1734455689.798 [WetLabsBB2FL](INFO): Powering up
2024-12-17T17:14:49.799Z,1734455689.799 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7120
2024-12-17T17:14:49.801Z,1734455689.801 [CBIT](DEBUG): Deactivating GF circuits.
2024-12-17T17:14:49.801Z,1734455689.801 [CBIT](DEBUG): Deactivating emergency mode.
2024-12-17T17:14:49.807Z,1734455689.807 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-12-17T17:14:49.807Z,1734455689.807 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-12-17T17:14:49.807Z,1734455689.807 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-12-17T17:14:49.807Z,1734455689.807 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-12-17T17:14:49.808Z,1734455689.808 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-12-17T17:14:49.808Z,1734455689.808 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-12-17T17:14:49.808Z,1734455689.808 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-12-17T17:14:49.808Z,1734455689.808 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-12-17T17:14:49.841Z,1734455689.841 [CBIT](DEBUG): Backplane powered.
2024-12-17T17:14:49.841Z,1734455689.841 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-12-17T17:14:49.842Z,1734455689.842 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-12-17T17:14:49.842Z,1734455689.842 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-12-17T17:14:49.842Z,1734455689.842 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-12-17T17:14:49.843Z,1734455689.843 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-12-17T17:14:49.844Z,1734455689.844 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-12-17T17:14:49.844Z,1734455689.844 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-12-17T17:14:49.850Z,1734455689.850 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-12-17T17:14:49.851Z,1734455689.851 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-12-17T17:14:49.851Z,1734455689.851 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-12-17T17:14:49.853Z,1734455689.853 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-12-17T17:14:49.928Z,1734455689.928 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-12-17T17:14:49.953Z,1734455689.953 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-12-17T17:14:49.986Z,1734455689.986 [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-12-17T17:14:49.987Z,1734455689.987 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-12-17T17:14:49.987Z,1734455689.987 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-12-17T17:14:49.989Z,1734455689.989 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-12-17T17:14:50.169Z,1734455690.169 [Radio_Surface](INFO): Powering up
2024-12-17T17:14:50.356Z,1734455690.356 [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-12-17T17:14:50.407Z,1734455690.407 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-12-17T17:14:50.408Z,1734455690.408 [Default:A.Wait](DEBUG): Construct Wait.
2024-12-17T17:14:50.424Z,1734455690.424 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-12-17T17:14:50.456Z,1734455690.456 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-12-17T17:14:50.483Z,1734455690.483 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-12-17T17:14:50.506Z,1734455690.506 [Default:E.Execute](DEBUG): Construct Execute.
2024-12-17T17:14:50.509Z,1734455690.509 [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-12-17T17:14:50.521Z,1734455690.521 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,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-12-17T17:14:50.529Z,1734455690.529 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-12-17T17:14:50.970Z,1734455690.970 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2024-12-17T17:14:50.971Z,1734455690.971 [Power24vConverter](INFO): Powering up.
2024-12-17T17:14:50.972Z,1734455690.972 [Sonardyne_Nano](INFO): Initializing.
2024-12-17T17:14:51.028Z,1734455691.028 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-12-17T17:14:51.083Z,1734455691.083 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-12-17T17:14:51.089Z,1734455691.089 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-12-17T17:14:51.091Z,1734455691.091 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-12-17T17:14:51.098Z,1734455691.098 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-12-17T17:14:51.099Z,1734455691.099 [MassServo](DEBUG): Initializing EZServoServo.
2024-12-17T17:14:51.105Z,1734455691.105 [MassServo](DEBUG): Initializing MassServo.
2024-12-17T17:14:51.106Z,1734455691.106 [RudderServo](DEBUG): Initializing EZServoServo.
2024-12-17T17:14:51.114Z,1734455691.114 [RudderServo](DEBUG): Initializing RudderServo.
2024-12-17T17:14:51.115Z,1734455691.115 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-17T17:14:51.121Z,1734455691.121 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-17T17:14:51.374Z,1734455691.374 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-12-17T17:14:51.374Z,1734455691.374 [DropWeight] Hardware Fault, FailCount= 1
2024-12-17T17:14:51.374Z,1734455691.374 [DropWeight](ERROR): Hardware Fault
2024-12-17T17:14:51.410Z,1734455691.410 [CommandExec](FAULT): Scheduling is paused
2024-12-17T17:14:51.410Z,1734455691.410 [CBIT](INFO): Critical error at 20241217T171451
2024-12-17T17:14:51.411Z,1734455691.411 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-12-17T17:14:51.421Z,1734455691.421 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-12-17T17:14:51.422Z,1734455691.422 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-12-17T17:14:51.579Z,1734455691.579 [CBIT](INFO): Critical error at 20241217T171451
2024-12-17T17:14:51.905Z,1734455691.905 [WetLabsBB2FL](INFO): Powering down
2024-12-17T17:14:53.160Z,1734455693.160 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-12-17T17:14:54.414Z,1734455694.414 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2024-12-17T17:14:54.828Z,1734455694.829 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-12-17T17:14:56.658Z,1734455696.658 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-12-17T17:14:56.822Z,1734455696.822 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-12-17T17:15:03.334Z,1734455703.334 [DAT](INFO): DAT read:
2024-12-17T17:15:03.336Z,1734455703.336 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-12-17T17:15:03.964Z,1734455703.964 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.013143
2024-12-17T17:15:05.098Z,1734455705.098 [DAT](INFO): DAT read: MF Frequency Band
2024-12-17T17:15:05.099Z,1734455705.099 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-12-17T17:15:05.100Z,1734455705.100 [DAT](INFO): DAT read: Dec 3 2024 23:39:30
2024-12-17T17:15:06.106Z,1734455706.106 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-12-17T17:15:06.107Z,1734455706.107 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-12-17T17:15:06.108Z,1734455706.108 [DAT](INFO): commRate: 800
2024-12-17T17:15:08.177Z,1734455708.177 [DAT](INFO): entering command mode
2024-12-17T17:15:08.374Z,1734455708.374 [DAT](INFO): DAT read:
2024-12-17T17:15:08.374Z,1734455708.374 [DAT](INFO): DAT read: user:1>
2024-12-17T17:15:08.375Z,1734455708.375 [DAT](INFO): setting verbose to 3
2024-12-17T17:15:08.626Z,1734455708.626 [DAT](INFO): DAT read: user:1>
2024-12-17T17:15:08.627Z,1734455708.627 [DAT](INFO): DAT read: Verbose | 3
2024-12-17T17:15:08.627Z,1734455708.627 [DAT](INFO): set verbose to 3
2024-12-17T17:15:08.627Z,1734455708.627 [DAT](INFO): setting DatVerbose to 27440
2024-12-17T17:15:08.878Z,1734455708.878 [DAT](INFO): DAT read: user:2>
2024-12-17T17:15:08.879Z,1734455708.879 [DAT](INFO): DAT read: DatVerbose | 27440
2024-12-17T17:15:08.879Z,1734455708.879 [DAT](INFO): set DatVerbose to 27440
2024-12-17T17:15:08.879Z,1734455708.879 [DAT](INFO): setting transmit power to 8
2024-12-17T17:15:09.130Z,1734455709.130 [DAT](INFO): DAT read: user:3>
2024-12-17T17:15:09.131Z,1734455709.131 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-12-17T17:15:09.131Z,1734455709.131 [DAT](INFO): set transmit power to 8
2024-12-17T17:15:09.131Z,1734455709.131 [DAT](INFO): setting local address to 1
2024-12-17T17:15:09.382Z,1734455709.382 [DAT](INFO): DAT read: user:4>
2024-12-17T17:15:09.383Z,1734455709.383 [DAT](INFO): DAT read: LocalAddr | 1
2024-12-17T17:15:09.383Z,1734455709.383 [DAT](INFO): set local address to 1
2024-12-17T17:15:09.384Z,1734455709.384 [DAT](INFO): Setting time to: 17:15:9 And date to:12/17/2024
2024-12-17T17:15:09.634Z,1734455709.634 [DAT](INFO): DAT read: user:5>
2024-12-17T17:15:09.635Z,1734455709.635 [DAT](INFO): DAT read: Tue Dec 17, 2024 17:15:09
2024-12-17T17:15:09.635Z,1734455709.635 [DAT](INFO): Local DAT time set to Tue Dec 17, 2024 17:15:09
2024-12-17T17:15:17.997Z,1734455717.997 [NAL9602](INFO): Powering up NAL9602
2024-12-17T17:15:26.704Z,1734455726.704 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, +3, -2, +0,A
2024-12-17T17:15:29.003Z,1734455729.003 [NAL9602](INFO): NAL9602 initialized
2024-12-17T17:15:56.318Z,1734455756.318 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:15:58.367Z,1734455758.367 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-12-17T17:16:04.143Z,1734455764.143 [SBIT](IMPORTANT): Beginning Startup BIT
2024-12-17T17:16:04.148Z,1734455764.148 [CBIT](IMPORTANT): Beginning ground fault scan
2024-12-17T17:16:06.589Z,1734455766.589 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:16:15.557Z,1734455775.557 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005358
CHAN A1 (24V): 0.000789
CHAN A2 (12V): 0.000652
CHAN A3 (5V): -0.000819
CHAN B0 (3.3V): 0.000037
CHAN B1 (3.15aV): -0.000363
CHAN B2 (3.15bV): -0.000029
CHAN B3 (GND): -0.000447
OPEN: 0.006795
Full Scale: +/- 1 mA
2024-12-17T17:16:16.473Z,1734455776.473 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:16:26.126Z,1734455786.126 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-12-17T17:16:26.126Z,1734455786.126 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2024-12-17T17:16:35.185Z,1734455795.185 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019570398, 0.0, 0.0,147
2024-12-17T17:16:36.466Z,1734455796.466 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:16:43.345Z,1734455803.345 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.00+0.00, +0.00, 0.08, 0.00
2024-12-17T17:16:46.579Z,1734455806.579 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:16:47.739Z,1734455807.739 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019570, +0.0, 0.0,1475.0, 0
2024-12-17T17:16:55.375Z,1734455815.375 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-12-17T17:16:55.378Z,1734455815.378 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2024-12-17T17:16:57.912Z,1734455817.912 [SBIT](IMPORTANT): SBIT PASSED
2024-12-17T17:16:57.912Z,1734455817.912 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-12-17T17:16:57.918Z,1734455817.918 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count;
2024-12-17T17:16:57.918Z,1734455817.918 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2024-12-17T17:16:57.918Z,1734455817.918 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute;
2024-12-17T17:16:57.918Z,1734455817.918 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2024-12-17T17:16:57.918Z,1734455817.918 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2024-12-17T17:16:57.918Z,1734455817.918 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 10.000000 count_per_second;
2024-12-17T17:16:57.920Z,1734455817.920 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 0.020000 count_per_second;
2024-12-17T17:16:57.920Z,1734455817.920 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_caseHumidity 10.000000 percent;
2024-12-17T17:16:57.920Z,1734455817.920 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_diatoms 30.000000 count_per_milliliter;
2024-12-17T17:16:57.920Z,1734455817.920 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_dinoflagellates 30.000000 count_per_milliliter;
2024-12-17T17:16:57.920Z,1734455817.920 [SBIT](IMPORTANT): Express linearApproximation _.predict_plankton_data_pct_dinos_sqerr 0.100000 none;
2024-12-17T17:16:57.921Z,1734455817.921 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2024-12-17T17:16:57.921Z,1734455817.921 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;
2024-12-17T17:16:57.921Z,1734455817.921 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-12-17T17:16:57.926Z,1734455817.926 [SBIT](IMPORTANT): Normal all _.predict_plankton_data_pct_dinos_sqerr;
2024-12-17T17:16:57.927Z,1734455817.927 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=270.915869 cubic_centimeter;
2024-12-17T17:16:57.927Z,1734455817.927 [SBIT](IMPORTANT): VerticalControl.massDefault=13.541485 millimeter;
2024-12-17T17:16:58.264Z,1734455818.264 [MissionManager](IMPORTANT): Started mission Startup
2024-12-17T17:16:58.264Z,1734455818.264 [Startup] Running Loop=1
2024-12-17T17:16:58.264Z,1734455818.264 [Startup](DEBUG): Aggregate::initialize Startup
2024-12-17T17:16:58.264Z,1734455818.264 [Startup:A.GoToSurface] Running Loop=1
2024-12-17T17:16:58.264Z,1734455818.264 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-12-17T17:16:58.265Z,1734455818.265 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-12-17T17:16:58.265Z,1734455818.265 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-12-17T17:16:58.266Z,1734455818.266 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-12-17T17:16:58.266Z,1734455818.266 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-12-17T17:16:58.266Z,1734455818.266 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-12-17T17:16:58.267Z,1734455818.267 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-12-17T17:16:58.268Z,1734455818.268 [Startup:StartupSatComms] Running Loop=1
2024-12-17T17:16:58.268Z,1734455818.268 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-12-17T17:16:58.269Z,1734455818.269 [Startup:StartupSatComms:A] Running Loop=1
2024-12-17T17:16:58.659Z,1734455818.659 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-12-17T17:17:01.513Z,1734455821.513 [Waterlinked](INFO): Powering down
2024-12-17T17:17:05.536Z,1734455825.536 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:17:23.702Z,1734455843.702 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:17:40.220Z,1734455860.220 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:17:49.966Z,1734455869.966 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-12-17T17:17:49.966Z,1734455869.966 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:17:49.978Z,1734455869.978 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:17:50.364Z,1734455870.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:17:50.364Z,1734455870.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-12-17T17:17:56.379Z,1734455876.379 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:17:58.474Z,1734455878.474 [Startup:StartupSatComms:A](INFO): Timed out from 2024-12-17T17:16:58.3Z
2024-12-17T17:17:58.474Z,1734455878.474 [Startup:StartupSatComms:A] Stopped
2024-12-17T17:17:58.475Z,1734455878.475 [Startup:StartupSatComms:B] Running Loop=1
2024-12-17T17:17:58.847Z,1734455878.847 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-12-17T17:18:06.357Z,1734455886.357 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20241217T171439/Courier0000.lzma
2024-12-17T17:18:07.359Z,1734455887.359 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Courier0000.lzma.bak
2024-12-17T17:18:07.359Z,1734455887.359 [DataOverHttps](INFO): SBD MOMSN=23805911
2024-12-17T17:18:10.606Z,1734455890.606 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-12-17T17:18:13.346Z,1734455893.346 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:18:23.022Z,1734455903.022 [DataOverHttps](INFO): Sending 1182 bytes from file Logs/20241217T171439/Express0001.lzma
2024-12-17T17:18:24.024Z,1734455904.024 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Express0001.lzma.bak
2024-12-17T17:18:24.024Z,1734455904.024 [DataOverHttps](INFO): SBD MOMSN=23805917
2024-12-17T17:18:25.079Z,1734455905.079 [Startup:StartupSatComms:B] Stopped
2024-12-17T17:18:25.079Z,1734455905.079 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-12-17T17:18:25.079Z,1734455905.079 [Startup:StartupSatComms] Stopped
2024-12-17T17:18:25.079Z,1734455905.079 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-12-17T17:18:25.080Z,1734455905.080 [Startup](INFO): Completed Startup
2024-12-17T17:18:25.080Z,1734455905.080 [MissionManager](INFO): Startup is completed.
2024-12-17T17:18:25.080Z,1734455905.080 [MissionManager](INFO): Uninitializing Mission Startup
2024-12-17T17:18:25.081Z,1734455905.081 [Startup] Stopped
2024-12-17T17:18:25.081Z,1734455905.081 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-12-17T17:18:25.081Z,1734455905.081 [Startup:A.GoToSurface] Stopped
2024-12-17T17:18:25.081Z,1734455905.081 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-12-17T17:18:25.483Z,1734455905.483 [MissionManager](IMPORTANT): Started mission Default
2024-12-17T17:18:25.483Z,1734455905.483 [Default] Running Loop=1
2024-12-17T17:18:25.483Z,1734455905.483 [Default](DEBUG): Aggregate::initialize Default
2024-12-17T17:18:25.484Z,1734455905.484 [Default:B.GoToSurface] Running Loop=1
2024-12-17T17:18:25.484Z,1734455905.484 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-12-17T17:18:25.484Z,1734455905.484 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-12-17T17:18:25.484Z,1734455905.484 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-12-17T17:18:25.485Z,1734455905.485 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-12-17T17:18:25.485Z,1734455905.485 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-12-17T17:18:25.485Z,1734455905.485 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-12-17T17:18:25.486Z,1734455905.486 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-12-17T17:18:25.486Z,1734455905.486 [Default:A.Wait] Running Loop=1
2024-12-17T17:18:25.486Z,1734455905.486 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-12-17T17:18:29.177Z,1734455909.177 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:18:38.810Z,1734455918.810 [Default:A.Wait](INFO): Done Waiting.
2024-12-17T17:18:38.810Z,1734455918.810 [Default:A.Wait] Stopped
2024-12-17T17:18:38.810Z,1734455918.810 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-12-17T17:18:39.224Z,1734455919.224 [Default:CheckIn] Running Loop=1
2024-12-17T17:18:39.224Z,1734455919.224 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-17T17:18:39.225Z,1734455919.225 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-17T17:18:39.630Z,1734455919.630 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-12-17T17:18:40.884Z,1734455920.884 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-12-17T17:18:46.070Z,1734455926.070 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:19:02.231Z,1734455942.231 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:19:16.012Z,1734455956.012 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:19:31.771Z,1734455971.771 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-17T17:20:33.136Z,1734456033.136 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-12-17T17:20:50.942Z,1734456050.942 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-12-17T17:20:50.942Z,1734456050.942 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:20:50.954Z,1734456050.954 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:20:51.350Z,1734456051.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:20:51.350Z,1734456051.350 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-12-17T17:21:57.566Z,1734456117.566 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:22:04.031Z,1734456124.031 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:22:18.978Z,1734456138.978 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:22:33.926Z,1734456153.926 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:22:48.470Z,1734456168.470 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:23:03.419Z,1734456183.419 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:23:18.367Z,1734456198.367 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:23:33.718Z,1734456213.718 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:23:39.419Z,1734456219.419 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-12-17T17:18:39.2Z
2024-12-17T17:23:39.419Z,1734456219.419 [Default:CheckIn:Read_GPS] Stopped
2024-12-17T17:23:39.419Z,1734456219.419 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-17T17:23:39.812Z,1734456219.812 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-12-17T17:23:46.564Z,1734456226.564 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20241217T171439/Courier0004.lzma
2024-12-17T17:23:47.567Z,1734456227.567 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Courier0004.lzma.bak
2024-12-17T17:23:47.567Z,1734456227.567 [DataOverHttps](INFO): SBD MOMSN=23805962
2024-12-17T17:23:48.668Z,1734456228.668 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:23:51.957Z,1734456231.957 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-12-17T17:23:51.957Z,1734456231.957 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:23:51.980Z,1734456231.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:23:52.348Z,1734456232.348 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:23:52.348Z,1734456232.348 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-12-17T17:24:03.145Z,1734456243.145 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20241217T171439/Express0005.lzma
2024-12-17T17:24:04.147Z,1734456244.147 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Express0005.lzma.bak
2024-12-17T17:24:04.147Z,1734456244.147 [DataOverHttps](INFO): SBD MOMSN=23805964
2024-12-17T17:24:04.826Z,1734456244.826 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-12-17T17:24:05.256Z,1734456245.256 [Default:CheckIn:Read_Iridium] Stopped
2024-12-17T17:24:05.256Z,1734456245.256 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-17T17:24:05.256Z,1734456245.256 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-17T17:24:18.571Z,1734456258.571 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2024-12-17T17:24:18.572Z,1734456258.572 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2024-12-17T17:24:18.575Z,1734456258.575 [BPC1](INFO): Received data from all battery sticks.
2024-12-17T17:25:32.099Z,1734456332.099 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-12-17T17:25:32.099Z,1734456332.099 [NAL9602] Data Fault, FailCount= 1
2024-12-17T17:25:32.099Z,1734456332.099 [NAL9602](ERROR): Data Fault
2024-12-17T17:25:32.137Z,1734456332.137 [CBIT](ERROR): Data Fault in component: NAL9602
2024-12-17T17:25:32.498Z,1734456332.498 [NAL9602](INFO): Powering down
2024-12-17T17:25:33.335Z,1734456333.335 [CBIT](INFO): Clearing failed state for component NAL9602
2024-12-17T17:25:33.335Z,1734456333.335 [NAL9602] No Fault, FailCount= 1
2024-12-17T17:26:02.802Z,1734456362.802 [NAL9602](INFO): Powering up NAL9602
2024-12-17T17:26:13.710Z,1734456373.710 [NAL9602](INFO): NAL9602 initialized
2024-12-17T17:26:52.902Z,1734456412.902 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-12-17T17:26:52.902Z,1734456412.902 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:26:52.950Z,1734456412.950 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:26:53.324Z,1734456413.324 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:26:53.324Z,1734456413.324 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-12-17T17:29:05.835Z,1734456545.835 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-17T17:29:05.835Z,1734456545.835 [Default:CheckIn:C.Wait] Stopped
2024-12-17T17:29:05.836Z,1734456545.836 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-17T17:29:05.836Z,1734456545.836 [Default:CheckIn:D] Running Loop=1
2024-12-17T17:29:06.229Z,1734456546.229 [Default:CheckIn:D] Stopped
2024-12-17T17:29:06.229Z,1734456546.229 [Default:CheckIn:E] Running Loop=1
2024-12-17T17:29:06.648Z,1734456546.648 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.679089 min
2024-12-17T17:29:06.648Z,1734456546.648 [Default:CheckIn:E] Stopped
2024-12-17T17:29:06.648Z,1734456546.648 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-17T17:29:06.648Z,1734456546.648 [Default:CheckIn] Stopped
2024-12-17T17:29:06.648Z,1734456546.648 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-17T17:29:06.648Z,1734456546.648 [Default:CheckIn](INFO): Running loop #2
2024-12-17T17:29:06.648Z,1734456546.648 [Default:CheckIn] Running Loop=2
2024-12-17T17:29:06.648Z,1734456546.648 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-17T17:29:06.648Z,1734456546.648 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-17T17:29:51.501Z,1734456591.501 [CBIT](INFO): Clearing failed state for component DropWeight
2024-12-17T17:29:51.501Z,1734456591.501 [DropWeight] No Fault, FailCount= 1
2024-12-17T17:29:53.898Z,1734456593.898 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-12-17T17:29:53.898Z,1734456593.898 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:29:53.909Z,1734456593.909 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:29:54.317Z,1734456594.317 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:29:54.317Z,1734456594.317 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-12-17T17:32:54.892Z,1734456774.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-12-17T17:32:54.892Z,1734456774.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:32:54.903Z,1734456774.903 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:32:55.314Z,1734456775.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:32:55.314Z,1734456775.314 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-12-17T17:34:06.805Z,1734456846.805 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-12-17T17:29:06.6Z
2024-12-17T17:34:06.805Z,1734456846.805 [Default:CheckIn:Read_GPS] Stopped
2024-12-17T17:34:06.806Z,1734456846.806 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-17T17:34:14.097Z,1734456854.097 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20241217T171439/Courier0007.lzma
2024-12-17T17:34:15.099Z,1734456855.099 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Courier0007.lzma.bak
2024-12-17T17:34:15.099Z,1734456855.099 [DataOverHttps](INFO): SBD MOMSN=23806032
2024-12-17T17:34:29.669Z,1734456869.669 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20241217T171439/Express0008.lzma
2024-12-17T17:34:30.683Z,1734456870.683 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Express0008.lzma.bak
2024-12-17T17:34:30.684Z,1734456870.684 [DataOverHttps](INFO): SBD MOMSN=23806034
2024-12-17T17:34:31.863Z,1734456871.863 [Default:CheckIn:Read_Iridium] Stopped
2024-12-17T17:34:31.863Z,1734456871.863 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-17T17:34:31.863Z,1734456871.863 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-17T17:35:55.883Z,1734456955.883 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-12-17T17:35:55.883Z,1734456955.883 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:35:55.894Z,1734456955.894 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:35:56.302Z,1734456956.302 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:35:56.302Z,1734456956.302 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-12-17T17:36:14.863Z,1734456974.863 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-12-17T17:36:14.863Z,1734456974.863 [NAL9602] Data Fault, FailCount= 2
2024-12-17T17:36:14.863Z,1734456974.863 [NAL9602](ERROR): Data Fault
2024-12-17T17:36:14.902Z,1734456974.902 [CBIT](ERROR): Data Fault in component: NAL9602
2024-12-17T17:36:15.266Z,1734456975.266 [NAL9602](INFO): Powering down
2024-12-17T17:36:16.100Z,1734456976.100 [CBIT](INFO): Clearing failed state for component NAL9602
2024-12-17T17:36:16.100Z,1734456976.100 [NAL9602] No Fault, FailCount= 2
2024-12-17T17:36:45.567Z,1734457005.567 [NAL9602](INFO): Powering up NAL9602
2024-12-17T17:36:56.468Z,1734457016.468 [NAL9602](INFO): NAL9602 initialized
2024-12-17T17:38:56.884Z,1734457136.884 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-12-17T17:38:56.884Z,1734457136.884 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:38:56.895Z,1734457136.895 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:38:57.308Z,1734457137.308 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:38:57.308Z,1734457137.308 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-12-17T17:39:32.455Z,1734457172.455 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-17T17:39:32.455Z,1734457172.455 [Default:CheckIn:C.Wait] Stopped
2024-12-17T17:39:32.455Z,1734457172.455 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-17T17:39:32.456Z,1734457172.456 [Default:CheckIn:D] Running Loop=1
2024-12-17T17:39:32.831Z,1734457172.831 [Default:CheckIn:D] Stopped
2024-12-17T17:39:32.831Z,1734457172.831 [Default:CheckIn:E] Running Loop=1
2024-12-17T17:39:33.237Z,1734457173.237 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.122459 min
2024-12-17T17:39:33.237Z,1734457173.237 [Default:CheckIn:E] Stopped
2024-12-17T17:39:33.237Z,1734457173.237 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-17T17:39:33.237Z,1734457173.237 [Default:CheckIn] Stopped
2024-12-17T17:39:33.237Z,1734457173.237 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-17T17:39:33.237Z,1734457173.237 [Default:CheckIn](INFO): Running loop #3
2024-12-17T17:39:33.238Z,1734457173.238 [Default:CheckIn] Running Loop=3
2024-12-17T17:39:33.238Z,1734457173.238 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-17T17:39:33.238Z,1734457173.238 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-17T17:41:57.878Z,1734457317.878 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-12-17T17:41:57.878Z,1734457317.878 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:41:57.888Z,1734457317.888 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:41:58.279Z,1734457318.279 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:41:58.280Z,1734457318.280 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-12-17T17:43:47.270Z,1734457427.270 [CommandExec](IMPORTANT): got command burn on
2024-12-17T17:43:47.270Z,1734457427.270 [CommandExec](IMPORTANT): Activating dropweight wire
2024-12-17T17:43:53.202Z,1734457433.202 [CommandExec](IMPORTANT): got command ubat off
2024-12-17T17:43:53.202Z,1734457433.202 [CommandExec](FAULT): UBAT is not configured to load at startup.
2024-12-17T17:44:33.412Z,1734457473.412 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-12-17T17:39:33.2Z
2024-12-17T17:44:33.412Z,1734457473.412 [Default:CheckIn:Read_GPS] Stopped
2024-12-17T17:44:33.412Z,1734457473.412 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-17T17:44:40.556Z,1734457480.556 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20241217T171439/Courier0010.lzma
2024-12-17T17:44:41.567Z,1734457481.567 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Courier0010.lzma.bak
2024-12-17T17:44:41.567Z,1734457481.567 [DataOverHttps](INFO): SBD MOMSN=23806141
2024-12-17T17:44:46.374Z,1734457486.374 [CommandExec](IMPORTANT): got command failComponent none DropWeight
2024-12-17T17:44:46.374Z,1734457486.374 [CommandExec](IMPORTANT): DropWeight failureMode is No Fault
2024-12-17T17:44:57.217Z,1734457497.217 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20241217T171439/Express0011.lzma
2024-12-17T17:44:58.219Z,1734457498.219 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Express0011.lzma.bak
2024-12-17T17:44:58.219Z,1734457498.219 [DataOverHttps](INFO): SBD MOMSN=23806175
2024-12-17T17:44:58.886Z,1734457498.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-12-17T17:44:58.886Z,1734457498.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:44:58.898Z,1734457498.898 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:44:59.291Z,1734457499.291 [Default:CheckIn:Read_Iridium] Stopped
2024-12-17T17:44:59.291Z,1734457499.291 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-17T17:44:59.291Z,1734457499.291 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-17T17:44:59.300Z,1734457499.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:44:59.300Z,1734457499.300 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-12-17T17:45:54.151Z,1734457554.151 [DAT](INFO): DAT read: user:6>Rx Time:17:45:53.1420
2024-12-17T17:45:54.151Z,1734457554.151 [DAT](INFO): Rx dataTimestamp_ set to:1734457554.150747
2024-12-17T17:45:54.658Z,1734457554.658 [DAT](INFO): DAT read: 17:45:53.1420 LVL= 19360, 21489, 22162, 23763, AGC= 82, IDX= 417, 0.34,-1.636, 0.002,-0.964,-0.550, PHS=-1.120, 0.537,-0.426, RAW= 305.3, 13.9, CAL= 305.2, 15.5, ROT= 204.8, -15.5
2024-12-17T17:45:54.659Z,1734457554.659 [DAT](INFO): got valid direction response:
17:45:53.1420 LVL= 19360, 21489, 22162, 23763, AGC= 82, IDX= 417, 0.34,-1.636, 0.002,-0.964,-0.550, PHS=-1.120, 0.537,-0.426, RAW= 305.3, 13.9, CAL= 305.2, 15.5, ROT= 204.8, -15.5
2024-12-17T17:45:54.660Z,1734457554.660 [DAT](INFO): DAT read:
2024-12-17T17:45:54.660Z,1734457554.660 [DAT](INFO): DAT read: $Packet for address 6
2024-12-17T17:45:54.661Z,1734457554.661 [DAT](INFO): received a packet notification
2024-12-17T17:45:54.678Z,1734457554.678 [DAT](INFO): #Rx 1: Read direction message, but no range.
2024-12-17T17:45:54.679Z,1734457554.679 [DAT](INFO): direction in FSK: [-0.874762,-0.404197,0.267238]
2024-12-17T17:46:58.439Z,1734457618.439 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-12-17T17:46:58.439Z,1734457618.439 [NAL9602] Data Fault, FailCount= 3
2024-12-17T17:46:58.439Z,1734457618.439 [NAL9602](ERROR): Data Fault
2024-12-17T17:46:58.485Z,1734457618.485 [CBIT](ERROR): Data Fault in component: NAL9602
2024-12-17T17:46:58.843Z,1734457618.843 [NAL9602](INFO): Powering down
2024-12-17T17:46:59.674Z,1734457619.674 [CBIT](INFO): Clearing failed state for component NAL9602
2024-12-17T17:46:59.674Z,1734457619.674 [NAL9602] No Fault, FailCount= 3
2024-12-17T17:47:29.136Z,1734457649.136 [NAL9602](INFO): Powering up NAL9602
2024-12-17T17:47:40.051Z,1734457660.051 [NAL9602](INFO): NAL9602 initialized
2024-12-17T17:47:59.855Z,1734457679.855 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-12-17T17:47:59.855Z,1734457679.855 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:47:59.866Z,1734457679.866 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:48:00.289Z,1734457680.289 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:48:00.289Z,1734457680.289 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-12-17T17:49:26.123Z,1734457766.123 [CommandExec](IMPORTANT): got command strobe off
2024-12-17T17:49:26.123Z,1734457766.123 [CommandExec](IMPORTANT): Deactivating strobe
2024-12-17T17:49:59.843Z,1734457799.843 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-17T17:49:59.844Z,1734457799.844 [Default:CheckIn:C.Wait] Stopped
2024-12-17T17:49:59.844Z,1734457799.844 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-17T17:49:59.844Z,1734457799.844 [Default:CheckIn:D] Running Loop=1
2024-12-17T17:50:00.259Z,1734457800.259 [Default:CheckIn:D] Stopped
2024-12-17T17:50:00.259Z,1734457800.259 [Default:CheckIn:E] Running Loop=1
2024-12-17T17:50:00.672Z,1734457800.672 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.579600 min
2024-12-17T17:50:00.672Z,1734457800.672 [Default:CheckIn:E] Stopped
2024-12-17T17:50:00.672Z,1734457800.672 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-17T17:50:00.672Z,1734457800.672 [Default:CheckIn] Stopped
2024-12-17T17:50:00.672Z,1734457800.672 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-17T17:50:00.672Z,1734457800.672 [Default:CheckIn](INFO): Running loop #4
2024-12-17T17:50:00.672Z,1734457800.672 [Default:CheckIn] Running Loop=4
2024-12-17T17:50:00.672Z,1734457800.672 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-17T17:50:00.672Z,1734457800.672 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-17T17:51:00.846Z,1734457860.846 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-12-17T17:51:00.846Z,1734457860.846 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:51:00.857Z,1734457860.857 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:51:01.280Z,1734457861.280 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:51:01.280Z,1734457861.280 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-12-17T17:54:01.862Z,1734458041.862 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-12-17T17:54:01.862Z,1734458041.862 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-17T17:54:01.874Z,1734458041.874 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-17T17:54:02.276Z,1734458042.276 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-17T17:54:02.276Z,1734458042.276 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-12-17T17:55:00.824Z,1734458100.824 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-12-17T17:50:00.7Z
2024-12-17T17:55:00.824Z,1734458100.824 [Default:CheckIn:Read_GPS] Stopped
2024-12-17T17:55:00.824Z,1734458100.824 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-17T17:55:07.584Z,1734458107.584 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241217T171439/Courier0013.lzma
2024-12-17T17:55:08.587Z,1734458108.587 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Courier0013.lzma.bak
2024-12-17T17:55:08.587Z,1734458108.587 [DataOverHttps](INFO): SBD MOMSN=23806275
2024-12-17T17:55:16.862Z,1734458116.862 [DataOverHttps](IMPORTANT): SBD MTMSN=20241217T175515
2024-12-17T17:55:24.141Z,1734458124.141 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20241217T171439/Express0014.lzma
2024-12-17T17:55:24.147Z,1734458124.147 [DataOverHttps](INFO): Received command: ibit
2024-12-17T17:55:24.194Z,1734458124.194 [CommandExec](IMPORTANT): got command ibit
2024-12-17T17:55:24.262Z,1734458124.262 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-12-17T17:55:24.262Z,1734458124.262 [IBIT](IMPORTANT): Beginning control surface checks.
2024-12-17T17:55:24.285Z,1734458124.285 [CBIT](IMPORTANT): Beginning ground fault scan
2024-12-17T17:55:25.320Z,1734458125.320 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Express0014.lzma.bak
2024-12-17T17:55:25.320Z,1734458125.320 [DataOverHttps](INFO): SBD MOMSN=23806278
2024-12-17T17:55:25.865Z,1734458125.865 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175524.00,A,3648.14854,N,12147.25634,W,1.905,34.31,171224,,,A*45
2024-12-17T17:55:25.898Z,1734458125.898 [NAL9602](INFO): GPS fix at 20241217T175524: (36.802476, -121.787606)
2024-12-17T17:55:28.696Z,1734458128.696 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175527.00,A,3648.14610,N,12147.25593,W,2.916,34.31,171224,,,A*47
2024-12-17T17:55:28.707Z,1734458128.707 [NAL9602](INFO): GPS fix at 20241217T175527: (36.802435, -121.787599)
2024-12-17T17:55:31.948Z,1734458131.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175530.00,A,3648.14443,N,12147.25397,W,2.430,176.87,171224,,,A*74
2024-12-17T17:55:31.951Z,1734458131.951 [NAL9602](INFO): GPS fix at 20241217T175530: (36.802407, -121.787566)
2024-12-17T17:55:34.471Z,1734458134.471 [DataOverHttps](IMPORTANT): SBD MTMSN=20241217T175533
2024-12-17T17:55:34.767Z,1734458134.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175533.00,A,3648.14342,N,12147.25541,W,2.216,179.89,171224,,,A*7F
2024-12-17T17:55:34.770Z,1734458134.770 [NAL9602](INFO): GPS fix at 20241217T175533: (36.802390, -121.787590)
2024-12-17T17:55:35.357Z,1734458135.357 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000478
CHAN A1 (24V): 0.001077
CHAN A2 (12V): 0.000043
CHAN A3 (5V): -0.001244
CHAN B0 (3.3V): 0.000631
CHAN B1 (3.15aV): 0.000207
CHAN B2 (3.15bV): 0.000017
CHAN B3 (GND): -0.000842
OPEN: 0.006554
Full Scale: +/- 1 mA
2024-12-17T17:55:38.000Z,1734458138.000 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175536.00,A,3648.14256,N,12147.25601,W,2.022,163.30,171224,,,A*75
2024-12-17T17:55:38.002Z,1734458138.002 [NAL9602](INFO): GPS fix at 20241217T175536: (36.802376, -121.787600)
2024-12-17T17:55:40.828Z,1734458140.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175539.00,A,3648.14180,N,12147.25533,W,2.080,150.26,171224,,,A*7F
2024-12-17T17:55:40.831Z,1734458140.831 [NAL9602](INFO): GPS fix at 20241217T175539: (36.802363, -121.787589)
2024-12-17T17:55:42.200Z,1734458142.200 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20241217T171439/Courier0016.lzma
2024-12-17T17:55:42.203Z,1734458142.203 [DataOverHttps](INFO): Received command: burn off
2024-12-17T17:55:42.204Z,1734458142.204 [CommandExec](IMPORTANT): got command burn off
2024-12-17T17:55:42.205Z,1734458142.205 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-12-17T17:55:43.475Z,1734458143.475 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Courier0016.lzma.bak
2024-12-17T17:55:43.475Z,1734458143.475 [DataOverHttps](INFO): SBD MOMSN=23806289
2024-12-17T17:55:43.661Z,1734458143.661 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175542.00,A,3648.14110,N,12147.25360,W,1.905,128.00,171224,,,A*76
2024-12-17T17:55:43.663Z,1734458143.663 [NAL9602](INFO): GPS fix at 20241217T175542: (36.802352, -121.787560)
2024-12-17T17:55:46.888Z,1734458146.888 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175545.00,A,3648.14048,N,12147.25224,W,2.119,122.91,171224,,,A*78
2024-12-17T17:55:46.891Z,1734458146.891 [NAL9602](INFO): GPS fix at 20241217T175545: (36.802341, -121.787537)
2024-12-17T17:55:49.724Z,1734458149.724 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175548.00,A,3648.13999,N,12147.25101,W,1.127,122.91,171224,,,A*7D
2024-12-17T17:55:49.727Z,1734458149.727 [NAL9602](INFO): GPS fix at 20241217T175548: (36.802333, -121.787517)
2024-12-17T17:55:52.958Z,1734458152.958 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175551.00,A,3648.13917,N,12147.24994,W,1.011,122.91,171224,,,A*72
2024-12-17T17:55:52.960Z,1734458152.960 [NAL9602](INFO): GPS fix at 20241217T175551: (36.802320, -121.787499)
2024-12-17T17:55:55.780Z,1734458155.780 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175554.00,A,3648.13779,N,12147.24861,W,2.663,122.91,171224,,,A*7A
2024-12-17T17:55:55.782Z,1734458155.782 [NAL9602](INFO): GPS fix at 20241217T175554: (36.802296, -121.787477)
2024-12-17T17:55:59.010Z,1734458159.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175557.00,A,3648.13672,N,12147.24569,W,3.479,108.97,171224,,,A*70
2024-12-17T17:55:59.012Z,1734458159.012 [NAL9602](INFO): GPS fix at 20241217T175557: (36.802279, -121.787428)
2024-12-17T17:55:59.517Z,1734458159.517 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20241217T171439/Courier0019.lzma
2024-12-17T17:56:00.519Z,1734458160.519 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Courier0019.lzma.bak
2024-12-17T17:56:00.519Z,1734458160.519 [DataOverHttps](INFO): SBD MOMSN=23806292
2024-12-17T17:56:01.840Z,1734458161.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175600.00,A,3648.13555,N,12147.24157,W,4.004,110.35,171224,,,A*76
2024-12-17T17:56:01.842Z,1734458161.842 [NAL9602](INFO): GPS fix at 20241217T175600: (36.802259, -121.787359)
2024-12-17T17:56:04.668Z,1734458164.668 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175603.00,A,3648.13541,N,12147.23712,W,3.829,105.23,171224,,,A*73
2024-12-17T17:56:04.670Z,1734458164.670 [NAL9602](INFO): GPS fix at 20241217T175603: (36.802257, -121.787285)
2024-12-17T17:56:07.896Z,1734458167.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175606.00,A,3648.13508,N,12147.23450,W,2.780,109.61,171224,,,A*79
2024-12-17T17:56:07.898Z,1734458167.898 [NAL9602](INFO): GPS fix at 20241217T175606: (36.802251, -121.787242)
2024-12-17T17:56:10.728Z,1734458170.728 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175609.00,A,3648.13475,N,12147.23152,W,2.741,112.49,171224,,,A*77
2024-12-17T17:56:10.738Z,1734458170.738 [NAL9602](INFO): GPS fix at 20241217T175609: (36.802246, -121.787192)
2024-12-17T17:56:13.964Z,1734458173.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175612.00,A,3648.13433,N,12147.22848,W,2.955,108.14,171224,,,A*74
2024-12-17T17:56:13.967Z,1734458173.967 [NAL9602](INFO): GPS fix at 20241217T175612: (36.802239, -121.787141)
2024-12-17T17:56:16.377Z,1734458176.377 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20241217T171439/Express0017.lzma
2024-12-17T17:56:16.806Z,1734458176.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175615.00,A,3648.13383,N,12147.22589,W,2.819,106.08,171224,,,A*75
2024-12-17T17:56:16.809Z,1734458176.809 [NAL9602](INFO): GPS fix at 20241217T175615: (36.802231, -121.787098)
2024-12-17T17:56:17.379Z,1734458177.379 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Express0017.lzma.bak
2024-12-17T17:56:17.379Z,1734458177.379 [DataOverHttps](INFO): SBD MOMSN=23806295
2024-12-17T17:56:20.019Z,1734458180.019 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175618.00,A,3648.13357,N,12147.22365,W,2.313,100.59,171224,,,A*76
2024-12-17T17:56:20.022Z,1734458180.022 [NAL9602](INFO): GPS fix at 20241217T175618: (36.802226, -121.787061)
2024-12-17T17:56:22.848Z,1734458182.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175621.00,A,3648.13354,N,12147.22129,W,2.158,96.33,171224,,,A*4A
2024-12-17T17:56:22.850Z,1734458182.850 [NAL9602](INFO): GPS fix at 20241217T175621: (36.802226, -121.787021)
2024-12-17T17:56:25.680Z,1734458185.680 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175624.00,A,3648.13357,N,12147.21915,W,2.119,87.26,171224,,,A*49
2024-12-17T17:56:25.683Z,1734458185.683 [NAL9602](INFO): GPS fix at 20241217T175624: (36.802226, -121.786986)
2024-12-17T17:56:28.919Z,1734458188.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175627.00,A,3648.13403,N,12147.21714,W,2.022,73.03,171224,,,A*46
2024-12-17T17:56:28.925Z,1734458188.925 [NAL9602](INFO): GPS fix at 20241217T175627: (36.802234, -121.786952)
2024-12-17T17:56:31.736Z,1734458191.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175630.00,A,3648.13498,N,12147.21635,W,0.661,80.10,171224,,,A*4D
2024-12-17T17:56:31.738Z,1734458191.738 [NAL9602](INFO): GPS fix at 20241217T175630: (36.802250, -121.786939)
2024-12-17T17:56:33.333Z,1734458193.333 [DataOverHttps](INFO): Sending 433 bytes from file Logs/20241217T171439/Express0020.lzma
2024-12-17T17:56:34.335Z,1734458194.335 [DataOverHttps](INFO): Moved sent file to Logs/20241217T171439/Express0020.lzma.bak
2024-12-17T17:56:34.335Z,1734458194.335 [DataOverHttps](INFO): SBD MOMSN=23806302
2024-12-17T17:56:34.967Z,1734458194.967 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175633.00,A,3648.13514,N,12147.21674,W,0.078,80.10,171224,,,A*40
2024-12-17T17:56:34.970Z,1734458194.970 [NAL9602](INFO): GPS fix at 20241217T175633: (36.802252, -121.786946)
2024-12-17T17:56:37.796Z,1734458197.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175636.00,A,3648.13492,N,12147.21666,W,0.019,80.10,171224,,,A*4E
2024-12-17T17:56:37.798Z,1734458197.798 [NAL9602](INFO): GPS fix at 20241217T175636: (36.802249, -121.786944)
2024-12-17T17:56:41.028Z,1734458201.028 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175639.00,A,3648.13465,N,12147.21633,W,0.058,80.10,171224,,,A*4C
2024-12-17T17:56:41.031Z,1734458201.031 [NAL9602](INFO): GPS fix at 20241217T175639: (36.802244, -121.786939)
2024-12-17T17:56:43.860Z,1734458203.860 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175642.00,A,3648.13444,N,12147.21594,W,0.019,80.10,171224,,,A*48
2024-12-17T17:56:43.862Z,1734458203.862 [NAL9602](INFO): GPS fix at 20241217T175642: (36.802241, -121.786932)
2024-12-17T17:56:47.088Z,1734458207.088 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175645.00,A,3648.13433,N,12147.21591,W,0.156,80.10,171224,,,A*40
2024-12-17T17:56:47.090Z,1734458207.090 [NAL9602](INFO): GPS fix at 20241217T175645: (36.802239, -121.786932)
2024-12-17T17:56:49.950Z,1734458209.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175648.00,A,3648.13423,N,12147.21600,W,0.078,80.10,171224,,,A*4A
2024-12-17T17:56:49.952Z,1734458209.952 [NAL9602](INFO): GPS fix at 20241217T175648: (36.802237, -121.786933)
2024-12-17T17:56:52.767Z,1734458212.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175651.00,A,3648.13409,N,12147.21611,W,0.078,80.10,171224,,,A*4A
2024-12-17T17:56:52.770Z,1734458212.770 [NAL9602](INFO): GPS fix at 20241217T175651: (36.802235, -121.786935)
2024-12-17T17:56:56.002Z,1734458216.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175654.00,A,3648.13399,N,12147.21640,W,0.019,80.10,171224,,,A*42
2024-12-17T17:56:56.004Z,1734458216.004 [NAL9602](INFO): GPS fix at 20241217T175654: (36.802233, -121.786940)
2024-12-17T17:56:58.832Z,1734458218.832 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175657.00,A,3648.13395,N,12147.21663,W,0.039,80.10,171224,,,A*4E
2024-12-17T17:56:58.834Z,1734458218.834 [NAL9602](INFO): GPS fix at 20241217T175657: (36.802233, -121.786944)
2024-12-17T17:57:02.062Z,1734458222.062 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175700.00,A,3648.13393,N,12147.21681,W,0.039,80.10,171224,,,A*47
2024-12-17T17:57:02.064Z,1734458222.064 [NAL9602](INFO): GPS fix at 20241217T175700: (36.802232, -121.786947)
2024-12-17T17:57:04.887Z,1734458224.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175703.00,A,3648.13389,N,12147.21689,W,0.019,80.10,171224,,,A*45
2024-12-17T17:57:04.890Z,1734458224.890 [NAL9602](INFO): GPS fix at 20241217T175703: (36.802231, -121.786948)
2024-12-17T17:57:07.716Z,1734458227.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175706.00,A,3648.13389,N,12147.21686,W,0.019,80.10,171224,,,A*4F
2024-12-17T17:57:07.718Z,1734458227.718 [NAL9602](INFO): GPS fix at 20241217T175706: (36.802231, -121.786948)
2024-12-17T17:57:10.947Z,1734458230.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175709.00,A,3648.13387,N,12147.21689,W,0.019,80.10,171224,,,A*41
2024-12-17T17:57:10.951Z,1734458230.951 [NAL9602](INFO): GPS fix at 20241217T175709: (36.802231, -121.786948)
2024-12-17T17:57:13.776Z,1734458233.776 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175712.00,A,3648.13388,N,12147.21693,W,0.019,80.10,171224,,,A*4F
2024-12-17T17:57:13.779Z,1734458233.779 [NAL9602](INFO): GPS fix at 20241217T175712: (36.802231, -121.786949)
2024-12-17T17:57:15.044Z,1734458235.044 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-12-17T17:57:15.428Z,1734458235.428 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.690001
2024-12-17T17:57:15.429Z,1734458235.429 [IBIT](IMPORTANT): batteryCapacityThreshold: 20.000000 Ah
2024-12-17T17:57:15.433Z,1734458235.433 [IBIT](IMPORTANT): batteryVoltageThreshold: 12.000000 V
2024-12-17T17:57:15.850Z,1734458235.850 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-12-17T17:57:15.851Z,1734458235.851 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-12-17T17:57:15.851Z,1734458235.851 [IBIT](IMPORTANT): Pressure:10.025532 PSI
2024-12-17T17:57:15.851Z,1734458235.851 [IBIT](IMPORTANT): Humidity:19.399342 %
2024-12-17T17:57:16.253Z,1734458236.253 [IBIT](IMPORTANT): Vehicle Pitch:-0.667865 degrees
2024-12-17T17:57:16.253Z,1734458236.253 [IBIT](IMPORTANT): Vehicle Roll:-2.469852 degrees
2024-12-17T17:57:16.254Z,1734458236.254 [IBIT](IMPORTANT): Vehicle Heading:228.477631 degrees
2024-12-17T17:57:16.667Z,1734458236.667 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-12-17T17:57:16.668Z,1734458236.668 [IBIT](IMPORTANT): buoyancyNeutral: 270.915863 cc
2024-12-17T17:57:16.668Z,1734458236.668 [IBIT](IMPORTANT): massDefault: 1.354149 cm
2024-12-17T17:57:16.668Z,1734458236.668 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2024-12-17T17:57:16.669Z,1734458236.669 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-12-17T17:57:16.669Z,1734458236.669 [IBIT](IMPORTANT): IBIT FAILED
2024-12-17T17:57:17.017Z,1734458237.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175715.00,A,3648.13395,N,12147.21698,W,0.019,80.10,171224,,,A*4F
2024-12-17T17:57:17.023Z,1734458237.023 [NAL9602](INFO): GPS fix at 20241217T175715: (36.802233, -121.786950)
2024-12-17T17:57:17.047Z,1734458237.047 [Default:CheckIn:Read_Iridium] Stopped
2024-12-17T17:57:17.047Z,1734458237.047 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-17T17:57:17.047Z,1734458237.047 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-17T17:57:49.332Z,1734458269.332 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-17T18:01:02.470Z,1734458462.470 [DataOverHttps](IMPORTANT): SBD MTMSN=20241217T180101
2024-12-17T18:01:10.009Z,1734458470.009 [DataOverHttps](INFO): Received command: ibit
2024-12-17T18:01:10.042Z,1734458470.042 [CommandExec](IMPORTANT): got command ibit
2024-12-17T18:01:10.160Z,1734458470.160 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-12-17T18:01:10.160Z,1734458470.160 [IBIT](IMPORTANT): Beginning control surface checks.
2024-12-17T18:01:10.164Z,1734458470.164 [CBIT](IMPORTANT): Beginning ground fault scan
2024-12-17T18:01:11.743Z,1734458471.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180111.00,A,3648.13544,N,12147.21725,W,0.000,80.10,171224,,,A*42
2024-12-17T18:01:11.756Z,1734458471.756 [NAL9602](INFO): GPS fix at 20241217T180111: (36.802257, -121.786954)
2024-12-17T18:01:21.205Z,1734458481.205 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000374
CHAN A1 (24V): 0.000788
CHAN A2 (12V): 0.000230
CHAN A3 (5V): -0.001219
CHAN B0 (3.3V): 0.000429
CHAN B1 (3.15aV): 0.000122
CHAN B2 (3.15bV): -0.000396
CHAN B3 (GND): -0.000288
OPEN: 0.006647
Full Scale: +/- 1 mA
2024-12-17T18:01:32.751Z,1734458492.751 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=19839, MT Status=1, MTMSN=554
2024-12-17T18:01:32.751Z,1734458492.751 [NAL9602](INFO): Data available in MT queue
2024-12-17T18:01:33.237Z,1734458493.237 [NAL9602](INFO): Received command: restart sys
2024-12-17T18:01:33.255Z,1734458493.255 [CommandExec](IMPORTANT): got command restart system
2024-12-17T18:01:34.373Z,1734458494.373 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180133.00,A,3648.13567,N,12147.21754,W,0.000,80.10,171224,,,A*45
2024-12-17T18:01:34.375Z,1734458494.375 [NAL9602](INFO): GPS fix at 20241217T180133: (36.802261, -121.786959)
2024-12-17T18:01:35.426Z,1734458495.426 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-12-17T18:01:35.426Z,1734458495.426 [CommandExec](INFO): Uninitializing the command executive.
2024-12-17T18:01:35.426Z,1734458495.426 [CommandExec](INFO): Uninitializing the command scheduler.
2024-12-17T18:01:35.426Z,1734458495.426 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-12-17T18:01:35.581Z,1734458495.581 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-12-17T18:01:35.581Z,1734458495.581 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-12-17T18:01:35.582Z,1734458495.582 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-12-17T18:01:35.582Z,1734458495.582 [NavChartDb](INFO): Join timeout helper Thread ID is 7263
2024-12-17T18:01:35.833Z,1734458495.833 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-12-17T18:01:35.834Z,1734458495.834 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-12-17T18:01:35.856Z,1734458495.856 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-12-17T18:01:35.856Z,1734458495.856 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-12-17T18:01:35.857Z,1734458495.857 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7264
2024-12-17T18:01:35.869Z,1734458495.869 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-12-17T18:01:35.870Z,1734458495.870 [WetLabsBB2FL](INFO): Powering down
2024-12-17T18:01:35.870Z,1734458495.870 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-12-17T18:01:35.879Z,1734458495.879 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-12-17T18:01:35.879Z,1734458495.879 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-12-17T18:01:35.879Z,1734458495.879 [CTD_Seabird](INFO): Join timeout helper Threa