2023-11-13T15:13:54.002Z,1699888434.002 [Supervisor](DEBUG): Initializing supervisor.
2023-11-13T15:13:54.007Z,1699888434.007 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-11-13T15:13:54.007Z,1699888434.007 [SyncHandler](INFO): Protected caller Thread ID is 1948
2023-11-13T15:13:54.008Z,1699888434.008 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-11-13T15:13:54.008Z,1699888434.008 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-11-13T15:13:54.009Z,1699888434.009 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1949
2023-11-13T15:13:54.013Z,1699888434.013 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-11-13T15:13:54.030Z,1699888434.030 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-11-13T15:13:54.031Z,1699888434.031 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-11-13T15:13:54.031Z,1699888434.031 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1950
2023-11-13T15:13:54.035Z,1699888434.035 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-11-13T15:13:54.036Z,1699888434.036 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-11-13T15:13:54.037Z,1699888434.037 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1951
2023-11-13T15:13:54.039Z,1699888434.039 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-11-13T15:13:54.040Z,1699888434.040 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-11-13T15:13:54.040Z,1699888434.040 [logger ThreadHandler](INFO): Protected caller Thread ID is 1952
2023-11-13T15:13:54.044Z,1699888434.044 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-11-13T15:13:54.044Z,1699888434.044 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-11-13T15:13:54.046Z,1699888434.046 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-11-13T15:13:54.305Z,1699888434.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-11-13T15:13:54.305Z,1699888434.305 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-11-13T15:13:54.390Z,1699888434.390 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-11-13T15:13:54.845Z,1699888434.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-11-13T15:13:54.846Z,1699888434.846 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-11-13T15:13:55.155Z,1699888435.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-11-13T15:13:55.156Z,1699888435.156 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-11-13T15:13:55.251Z,1699888435.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-11-13T15:13:55.252Z,1699888435.252 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-11-13T15:13:55.631Z,1699888435.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-11-13T15:13:55.632Z,1699888435.632 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-11-13T15:13:55.914Z,1699888435.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-11-13T15:13:55.915Z,1699888435.915 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-11-13T15:13:56.424Z,1699888436.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-11-13T15:13:56.425Z,1699888436.425 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-11-13T15:13:56.559Z,1699888436.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-11-13T15:13:56.560Z,1699888436.560 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-11-13T15:13:56.639Z,1699888436.639 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-11-13T15:13:57.391Z,1699888437.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-11-13T15:13:57.392Z,1699888437.392 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-11-13T15:13:57.752Z,1699888437.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-11-13T15:13:57.752Z,1699888437.752 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-11-13T15:13:57.975Z,1699888437.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-11-13T15:13:57.976Z,1699888437.976 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-11-13T15:13:58.133Z,1699888438.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-11-13T15:13:58.133Z,1699888438.133 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-11-13T15:13:58.519Z,1699888438.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-11-13T15:13:58.520Z,1699888438.520 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-11-13T15:13:58.760Z,1699888438.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-11-13T15:13:58.762Z,1699888438.762 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-11-13T15:13:58.763Z,1699888438.763 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-11-13T15:13:58.855Z,1699888438.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-11-13T15:13:58.941Z,1699888438.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-11-13T15:13:59.042Z,1699888439.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-11-13T15:13:59.126Z,1699888439.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-11-13T15:13:59.229Z,1699888439.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-11-13T15:13:59.332Z,1699888439.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-11-13T15:13:59.444Z,1699888439.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-11-13T15:13:59.521Z,1699888439.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-11-13T15:13:59.666Z,1699888439.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-11-13T15:13:59.803Z,1699888439.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-11-13T15:13:59.912Z,1699888439.912 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-11-13T15:14:00.176Z,1699888440.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-11-13T15:14:00.178Z,1699888440.178 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2023-11-13T15:14:00.178Z,1699888440.178 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-11-13T15:14:00.187Z,1699888440.187 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-11-13T15:14:00.239Z,1699888440.239 [VerticalControl](DEBUG): Construct VerticalControl.
2023-11-13T15:14:00.298Z,1699888440.298 [VerticalControl] Loaded
2023-11-13T15:14:00.299Z,1699888440.299 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-11-13T15:14:00.302Z,1699888440.302 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-11-13T15:14:00.342Z,1699888440.342 [HorizontalControl] Loaded
2023-11-13T15:14:00.342Z,1699888440.342 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-11-13T15:14:00.344Z,1699888440.344 [SpeedControl](DEBUG): Construct SpeedControl.
2023-11-13T15:14:00.347Z,1699888440.347 [SpeedControl] Loaded
2023-11-13T15:14:00.348Z,1699888440.348 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-11-13T15:14:00.350Z,1699888440.350 [LoopControl](DEBUG): Construct LoopControl.
2023-11-13T15:14:00.351Z,1699888440.351 [LoopControl] Loaded
2023-11-13T15:14:00.351Z,1699888440.351 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-11-13T15:14:00.351Z,1699888440.351 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-11-13T15:14:00.352Z,1699888440.352 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-11-13T15:14:00.362Z,1699888440.362 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-11-13T15:14:00.363Z,1699888440.363 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-11-13T15:14:00.511Z,1699888440.511 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-11-13T15:14:00.511Z,1699888440.511 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-11-13T15:14:00.688Z,1699888440.688 [BuoyancyServo] Loaded
2023-11-13T15:14:00.688Z,1699888440.688 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-11-13T15:14:00.708Z,1699888440.708 [ElevatorServo] Loaded
2023-11-13T15:14:00.709Z,1699888440.709 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-11-13T15:14:00.728Z,1699888440.728 [MassServo] Loaded
2023-11-13T15:14:00.728Z,1699888440.728 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-11-13T15:14:00.746Z,1699888440.746 [RudderServo] Loaded
2023-11-13T15:14:00.747Z,1699888440.747 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-11-13T15:14:00.762Z,1699888440.762 [ThrusterHE] Loaded
2023-11-13T15:14:00.762Z,1699888440.762 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-11-13T15:14:00.762Z,1699888440.762 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-11-13T15:14:00.763Z,1699888440.763 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-11-13T15:14:00.882Z,1699888440.882 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-11-13T15:14:00.882Z,1699888440.882 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-11-13T15:14:00.896Z,1699888440.896 [NavChart] Loaded
2023-11-13T15:14:00.896Z,1699888440.896 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-11-13T15:14:00.901Z,1699888440.901 [UniversalFixResidualReporter] Loaded
2023-11-13T15:14:00.902Z,1699888440.902 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-11-13T15:14:00.902Z,1699888440.902 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-11-13T15:14:00.903Z,1699888440.903 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-11-13T15:14:00.964Z,1699888440.964 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-11-13T15:14:00.964Z,1699888440.964 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-11-13T15:14:01.436Z,1699888441.436 [AHRS_M2] Loaded
2023-11-13T15:14:01.436Z,1699888441.436 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-11-13T15:14:02.265Z,1699888442.265 [BPC1] Loaded
2023-11-13T15:14:02.265Z,1699888442.265 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-11-13T15:14:02.339Z,1699888442.339 [DataOverHttps] Loaded
2023-11-13T15:14:02.339Z,1699888442.339 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-11-13T15:14:02.340Z,1699888442.340 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2023-11-13T15:14:02.340Z,1699888442.340 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2038
2023-11-13T15:14:02.361Z,1699888442.361 [Depth_Keller] Loaded
2023-11-13T15:14:02.362Z,1699888442.362 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-11-13T15:14:02.367Z,1699888442.367 [DropWeight] Loaded
2023-11-13T15:14:02.367Z,1699888442.367 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-11-13T15:14:02.429Z,1699888442.429 [NAL9602] Loaded
2023-11-13T15:14:02.429Z,1699888442.429 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-11-13T15:14:02.460Z,1699888442.460 [Onboard] Loaded
2023-11-13T15:14:02.460Z,1699888442.460 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-11-13T15:14:02.461Z,1699888442.461 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2023-11-13T15:14:02.461Z,1699888442.461 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2039
2023-11-13T15:14:02.475Z,1699888442.475 [Power24vConverter] Loaded
2023-11-13T15:14:02.475Z,1699888442.475 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-11-13T15:14:02.488Z,1699888442.488 [Radio_Surface] Loaded
2023-11-13T15:14:02.489Z,1699888442.489 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-11-13T15:14:02.490Z,1699888442.490 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2023-11-13T15:14:02.490Z,1699888442.490 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2040
2023-11-13T15:14:02.504Z,1699888442.504 [Sonardyne_Nano] Loaded
2023-11-13T15:14:02.504Z,1699888442.504 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-11-13T15:14:02.505Z,1699888442.505 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-11-13T15:14:02.505Z,1699888442.505 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-11-13T15:14:02.557Z,1699888442.557 [DepthRateCalculator] Loaded
2023-11-13T15:14:02.558Z,1699888442.558 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-11-13T15:14:02.563Z,1699888442.563 [PitchRateCalculator] Loaded
2023-11-13T15:14:02.563Z,1699888442.563 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-11-13T15:14:02.572Z,1699888442.572 [SpeedCalculator] Loaded
2023-11-13T15:14:02.572Z,1699888442.572 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-11-13T15:14:02.576Z,1699888442.576 [YawRateCalculator] Loaded
2023-11-13T15:14:02.576Z,1699888442.576 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-11-13T15:14:02.595Z,1699888442.595 [ElevatorOffsetCalculator] Loaded
2023-11-13T15:14:02.595Z,1699888442.595 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-11-13T15:14:02.595Z,1699888442.595 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-11-13T15:14:02.596Z,1699888442.596 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-11-13T15:14:02.742Z,1699888442.742 [CANONSampler] Loaded
2023-11-13T15:14:02.742Z,1699888442.742 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-11-13T15:14:02.804Z,1699888442.804 [CTD_Seabird] Loaded
2023-11-13T15:14:02.804Z,1699888442.804 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-11-13T15:14:02.805Z,1699888442.805 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A294E0
2023-11-13T15:14:02.806Z,1699888442.806 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2041
2023-11-13T15:14:02.826Z,1699888442.826 [PAR_Licor] Loaded
2023-11-13T15:14:02.826Z,1699888442.826 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-11-13T15:14:02.866Z,1699888442.866 [WetLabsBB2FL] Loaded
2023-11-13T15:14:02.866Z,1699888442.866 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-11-13T15:14:02.867Z,1699888442.867 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0
2023-11-13T15:14:02.868Z,1699888442.868 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2042
2023-11-13T15:14:02.868Z,1699888442.868 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-11-13T15:14:02.869Z,1699888442.869 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-11-13T15:14:02.898Z,1699888442.898 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-11-13T15:14:02.899Z,1699888442.899 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-11-13T15:14:03.222Z,1699888443.222 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-11-13T15:14:03.223Z,1699888443.223 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-11-13T15:14:04.056Z,1699888444.056 [SBIT](DEBUG): Construct Startup Built In Test.
2023-11-13T15:14:04.065Z,1699888444.065 [SBIT] Loaded
2023-11-13T15:14:04.065Z,1699888444.065 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-11-13T15:14:04.068Z,1699888444.068 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-11-13T15:14:04.082Z,1699888444.082 [IBIT] Loaded
2023-11-13T15:14:04.082Z,1699888444.082 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-11-13T15:14:04.088Z,1699888444.088 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-11-13T15:14:04.239Z,1699888444.239 [CBIT] Loaded
2023-11-13T15:14:04.239Z,1699888444.239 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-11-13T15:14:04.240Z,1699888444.240 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-11-13T15:14:04.240Z,1699888444.240 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-11-13T15:14:04.408Z,1699888444.408 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-11-13T15:14:04.415Z,1699888444.415 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-11-13T15:14:04.417Z,1699888444.417 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-11-13T15:14:04.428Z,1699888444.428 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-11-13T15:14:04.429Z,1699888444.429 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B944E0
2023-11-13T15:14:04.430Z,1699888444.430 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2043
2023-11-13T15:14:04.434Z,1699888444.434 [Supervisor](INFO): Main Thread ID is 1947
2023-11-13T15:14:04.434Z,1699888444.434 [Supervisor](DEBUG): Running supervisor.
2023-11-13T15:14:04.435Z,1699888444.435 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2044
2023-11-13T15:14:04.435Z,1699888444.435 [CommandExec](INFO): Initializing the command executive.
2023-11-13T15:14:04.437Z,1699888444.437 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2045
2023-11-13T15:14:04.439Z,1699888444.439 [controlThread ThreadHandler](INFO): Handler Thread ID is 2046
2023-11-13T15:14:04.440Z,1699888444.440 [controlThread](DEBUG): Initializing ControlThread
2023-11-13T15:14:04.441Z,1699888444.441 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-11-13T15:14:04.442Z,1699888444.442 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-11-13T15:14:04.443Z,1699888444.443 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-11-13T15:14:04.444Z,1699888444.444 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-11-13T15:14:04.446Z,1699888444.446 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-11-13T15:14:04.446Z,1699888444.446 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-11-13T15:14:04.452Z,1699888444.452 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-11-13T15:14:04.452Z,1699888444.452 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-11-13T15:14:04.452Z,1699888444.452 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-11-13T15:14:04.453Z,1699888444.453 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-11-13T15:14:04.453Z,1699888444.453 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-11-13T15:14:04.458Z,1699888444.458 [SBIT](INFO): Initialize SBIT Component.
2023-11-13T15:14:04.459Z,1699888444.459 [SBIT](IMPORTANT): git: 2023-10-12
2023-11-13T15:14:04.459Z,1699888444.459 [SBIT](INFO): git hash: f38038725fad7dfee583a1d6cb07fccd712c5d54
2023-11-13T15:14:04.459Z,1699888444.459 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-11-13T15:14:04.460Z,1699888444.460 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-11-13T15:14:04.461Z,1699888444.461 [SBIT](INFO): Beginning SBIT in 53.000000 seconds.
2023-11-13T15:14:04.462Z,1699888444.462 [IBIT](INFO): Initialize IBIT Component.
2023-11-13T15:14:04.463Z,1699888444.463 [CBIT](DEBUG): Initialize CBIT Component.
2023-11-13T15:14:04.464Z,1699888444.464 [logger ThreadHandler](INFO): Handler Thread ID is 2047
2023-11-13T15:14:04.474Z,1699888444.474 [CBIT](DEBUG): Initialized mux pins.
2023-11-13T15:14:04.474Z,1699888444.474 [CBIT](DEBUG): Initializing the watchdog timer.
2023-11-13T15:14:04.482Z,1699888444.482 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2048
2023-11-13T15:14:04.483Z,1699888444.483 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-11-13T15:14:04.494Z,1699888444.494 [Onboard ThreadHandler](INFO): Handler Thread ID is 2049
2023-11-13T15:14:04.498Z,1699888444.498 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-11-13T15:14:04.499Z,1699888444.499 [CBIT](DEBUG): Initializing heartbeat.
2023-11-13T15:14:04.515Z,1699888444.515 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2050
2023-11-13T15:14:04.530Z,1699888444.530 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2051
2023-11-13T15:14:04.531Z,1699888444.531 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-11-13T15:14:04.535Z,1699888444.535 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2053
2023-11-13T15:14:04.539Z,1699888444.539 [WetLabsBB2FL](INFO): Powering up
2023-11-13T15:14:04.540Z,1699888444.540 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2055
2023-11-13T15:14:04.549Z,1699888444.549 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-11-13T15:14:04.549Z,1699888444.549 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-11-13T15:14:04.550Z,1699888444.550 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-11-13T15:14:04.550Z,1699888444.550 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-11-13T15:14:04.550Z,1699888444.550 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-11-13T15:14:04.550Z,1699888444.550 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-11-13T15:14:04.550Z,1699888444.550 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-11-13T15:14:04.551Z,1699888444.551 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-11-13T15:14:04.570Z,1699888444.570 [CBIT](DEBUG): Deactivating GF circuits.
2023-11-13T15:14:04.570Z,1699888444.570 [CBIT](DEBUG): Deactivating emergency mode.
2023-11-13T15:14:04.610Z,1699888444.610 [CBIT](DEBUG): Backplane powered.
2023-11-13T15:14:04.611Z,1699888444.611 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-11-13T15:14:04.623Z,1699888444.623 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-13T15:14:04.650Z,1699888444.650 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-11-13T15:14:04.651Z,1699888444.651 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-11-13T15:14:04.725Z,1699888444.725 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-11-13T15:14:04.726Z,1699888444.726 [Default:A.Wait](DEBUG): Construct Wait.
2023-11-13T15:14:04.728Z,1699888444.728 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-13T15:14:04.788Z,1699888444.788 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-11-13T15:14:04.791Z,1699888444.791 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-11-13T15:14:04.804Z,1699888444.804 [Default:E.Execute](DEBUG): Construct Execute.
2023-11-13T15:14:04.808Z,1699888444.808 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-11-13T15:14:04.820Z,1699888444.820 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,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,Reporter,LogSplitter,
2023-11-13T15:14:04.837Z,1699888444.837 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-11-13T15:14:04.916Z,1699888444.916 [Power24vConverter](INFO): Powering up.
2023-11-13T15:14:04.917Z,1699888444.917 [Sonardyne_Nano](INFO): Initializing.
2023-11-13T15:14:04.918Z,1699888444.918 [Radio_Surface](INFO): Powering up
2023-11-13T15:14:04.942Z,1699888444.942 [CANONSampler](INFO): Powering down
2023-11-13T15:14:05.012Z,1699888445.012 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-11-13T15:14:05.014Z,1699888445.014 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-11-13T15:14:05.015Z,1699888445.015 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-11-13T15:14:05.030Z,1699888445.030 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-11-13T15:14:05.031Z,1699888445.031 [MassServo](DEBUG): Initializing EZServoServo.
2023-11-13T15:14:05.039Z,1699888445.039 [MassServo](DEBUG): Initializing MassServo.
2023-11-13T15:14:05.040Z,1699888445.040 [RudderServo](DEBUG): Initializing EZServoServo.
2023-11-13T15:14:05.046Z,1699888445.046 [RudderServo](DEBUG): Initializing RudderServo.
2023-11-13T15:14:05.047Z,1699888445.047 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-11-13T15:14:05.054Z,1699888445.054 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-11-13T15:14:06.848Z,1699888446.848 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-11-13T15:14:07.150Z,1699888447.150 [WetLabsBB2FL](INFO): Powering down
2023-11-13T15:14:08.491Z,1699888448.491 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2023-11-13T15:14:10.707Z,1699888450.707 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-11-13T15:14:11.563Z,1699888451.563 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-11-13T15:14:18.240Z,1699888458.240 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004797
2023-11-13T15:14:32.707Z,1699888472.707 [NAL9602](INFO): Powering up NAL9602
2023-11-13T15:14:43.620Z,1699888483.620 [NAL9602](INFO): NAL9602 initialized
2023-11-13T15:14:58.205Z,1699888498.205 [SBIT](IMPORTANT): Beginning Startup BIT
2023-11-13T15:14:58.209Z,1699888498.209 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T15:15:09.133Z,1699888509.133 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T15:15:09.505Z,1699888509.505 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T15:15:20.435Z,1699888520.435 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T15:15:20.840Z,1699888520.840 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T15:15:31.740Z,1699888531.740 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002672
CHAN A1 (24V): 0.000513
CHAN A2 (12V): -0.001868
CHAN A3 (5V): -0.001595
CHAN B0 (3.3V): 0.000240
CHAN B1 (3.15aV): 0.000117
CHAN B2 (3.15bV): -0.000367
CHAN B3 (GND): 0.000344
OPEN: -0.000451
Full Scale: +/- 1 mA
2023-11-13T15:15:52.400Z,1699888552.400 [SBIT](IMPORTANT): SBIT PASSED
2023-11-13T15:15:52.400Z,1699888552.400 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-11-13T15:15:52.401Z,1699888552.401 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-11-13T15:15:52.401Z,1699888552.401 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;

2023-11-13T15:15:52.402Z,1699888552.402 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-11-13T15:15:52.402Z,1699888552.402 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-11-13T15:15:52.402Z,1699888552.402 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-11-13T15:15:52.402Z,1699888552.402 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-11-13T15:15:52.402Z,1699888552.402 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent;

2023-11-13T15:15:52.402Z,1699888552.402 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=211.377075 cubic_centimeter;

2023-11-13T15:15:52.402Z,1699888552.402 [SBIT](IMPORTANT): VerticalControl.massDefault=-8.477228 millimeter;

2023-11-13T15:15:52.725Z,1699888552.725 [MissionManager](IMPORTANT): Started mission Startup
2023-11-13T15:15:52.725Z,1699888552.725 [Startup] Running Loop=1
2023-11-13T15:15:52.725Z,1699888552.725 [Startup](DEBUG): Aggregate::initialize Startup
2023-11-13T15:15:52.725Z,1699888552.725 [Startup:A.GoToSurface] Running Loop=1
2023-11-13T15:15:52.726Z,1699888552.726 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-13T15:15:52.726Z,1699888552.726 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-13T15:15:52.727Z,1699888552.727 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-13T15:15:52.727Z,1699888552.727 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-13T15:15:52.727Z,1699888552.727 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-13T15:15:52.728Z,1699888552.728 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-13T15:15:52.729Z,1699888552.729 [Startup:StartupSatComms] Running Loop=1
2023-11-13T15:15:52.729Z,1699888552.729 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-11-13T15:15:52.729Z,1699888552.729 [Startup:StartupSatComms:A] Running Loop=1
2023-11-13T15:15:53.140Z,1699888553.140 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-11-13T15:16:16.082Z,1699888576.082 [CommandExec](IMPORTANT): got command failComponent
2023-11-13T15:16:16.083Z,1699888576.083 [CommandExec](IMPORTANT): Failed components:
2023-11-13T15:16:16.083Z,1699888576.083 [CommandExec](IMPORTANT): No failed Components.
2023-11-13T15:16:42.386Z,1699888602.386 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:16:52.924Z,1699888612.924 [Startup:StartupSatComms:A](INFO): Timed out from 2023-11-13T15:15:52.7Z
2023-11-13T15:16:52.924Z,1699888612.924 [Startup:StartupSatComms:A] Stopped
2023-11-13T15:16:52.925Z,1699888612.925 [Startup:StartupSatComms:B] Running Loop=1
2023-11-13T15:16:53.332Z,1699888613.332 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-13T15:16:56.122Z,1699888616.122 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:16:59.918Z,1699888619.918 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20231113T151353/Courier0000.lzma
2023-11-13T15:17:00.920Z,1699888620.920 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0000.lzma.bak
2023-11-13T15:17:00.920Z,1699888620.920 [DataOverHttps](INFO): SBD MOMSN=19061391
2023-11-13T15:17:04.642Z,1699888624.642 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-11-13T15:17:04.642Z,1699888624.642 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:17:04.710Z,1699888624.710 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:17:05.068Z,1699888625.068 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:17:05.068Z,1699888625.068 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-11-13T15:17:09.454Z,1699888629.454 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:17:16.532Z,1699888636.532 [DataOverHttps](INFO): Sending 898 bytes from file Logs/20231113T151353/Express0001.lzma
2023-11-13T15:17:17.532Z,1699888637.532 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0001.lzma.bak
2023-11-13T15:17:17.532Z,1699888637.532 [DataOverHttps](INFO): SBD MOMSN=19061394
2023-11-13T15:17:18.784Z,1699888638.784 [Startup:StartupSatComms:B] Stopped
2023-11-13T15:17:18.784Z,1699888638.784 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-11-13T15:17:18.784Z,1699888638.784 [Startup:StartupSatComms] Stopped
2023-11-13T15:17:18.784Z,1699888638.784 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-11-13T15:17:18.785Z,1699888638.785 [Startup](INFO): Completed Startup
2023-11-13T15:17:18.785Z,1699888638.785 [MissionManager](INFO): Startup is completed.
2023-11-13T15:17:18.785Z,1699888638.785 [MissionManager](INFO): Uninitializing Mission Startup
2023-11-13T15:17:18.785Z,1699888638.785 [Startup] Stopped
2023-11-13T15:17:18.785Z,1699888638.785 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-11-13T15:17:18.814Z,1699888638.814 [Startup:A.GoToSurface] Stopped
2023-11-13T15:17:18.814Z,1699888638.814 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-13T15:17:19.192Z,1699888639.192 [MissionManager](IMPORTANT): Started mission Default
2023-11-13T15:17:19.192Z,1699888639.192 [Default] Running Loop=1
2023-11-13T15:17:19.192Z,1699888639.192 [Default](DEBUG): Aggregate::initialize Default
2023-11-13T15:17:19.192Z,1699888639.192 [Default:B.GoToSurface] Running Loop=1
2023-11-13T15:17:19.192Z,1699888639.192 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-13T15:17:19.193Z,1699888639.193 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-13T15:17:19.193Z,1699888639.193 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-13T15:17:19.193Z,1699888639.193 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-13T15:17:19.194Z,1699888639.194 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-13T15:17:19.194Z,1699888639.194 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-13T15:17:19.194Z,1699888639.194 [Default:A.Wait] Running Loop=1
2023-11-13T15:17:19.195Z,1699888639.195 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-11-13T15:17:23.192Z,1699888643.192 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:17:32.508Z,1699888652.508 [Default:A.Wait](INFO): Done Waiting.
2023-11-13T15:17:32.509Z,1699888652.509 [Default:A.Wait] Stopped
2023-11-13T15:17:32.509Z,1699888652.509 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T15:17:32.924Z,1699888652.924 [Default:CheckIn] Running Loop=1
2023-11-13T15:17:32.925Z,1699888652.925 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T15:17:32.925Z,1699888652.925 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T15:17:33.308Z,1699888653.308 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-11-13T15:17:37.331Z,1699888657.331 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:17:51.073Z,1699888671.073 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:18:05.206Z,1699888685.206 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:18:18.943Z,1699888698.943 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:18:33.082Z,1699888713.082 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:18:46.414Z,1699888726.414 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T15:19:47.024Z,1699888787.024 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-13T15:20:05.625Z,1699888805.625 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-11-13T15:20:05.626Z,1699888805.626 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:20:05.684Z,1699888805.684 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:20:06.049Z,1699888806.049 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:20:06.049Z,1699888806.049 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-11-13T15:21:23.983Z,1699888883.983 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-11-13T15:21:23.986Z,1699888883.986 [BPC1](INFO): Received data from all battery sticks.
2023-11-13T15:22:25.994Z,1699888945.994 [CommandExec](IMPORTANT): got command failComponent
2023-11-13T15:22:25.995Z,1699888945.995 [CommandExec](IMPORTANT): Failed components:
2023-11-13T15:22:25.995Z,1699888945.995 [CommandExec](IMPORTANT): No failed Components.
2023-11-13T15:22:33.094Z,1699888953.094 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-13T15:17:32.9Z
2023-11-13T15:22:33.094Z,1699888953.094 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T15:22:33.094Z,1699888953.094 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T15:22:33.499Z,1699888953.499 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-13T15:22:40.502Z,1699888960.502 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20231113T151353/Courier0004.lzma
2023-11-13T15:22:41.504Z,1699888961.504 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0004.lzma.bak
2023-11-13T15:22:41.504Z,1699888961.504 [DataOverHttps](INFO): SBD MOMSN=19061420
2023-11-13T15:22:56.986Z,1699888976.986 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20231113T151353/Express0005.lzma
2023-11-13T15:22:57.988Z,1699888977.988 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0005.lzma.bak
2023-11-13T15:22:57.988Z,1699888977.988 [DataOverHttps](INFO): SBD MOMSN=19061422
2023-11-13T15:22:59.356Z,1699888979.356 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T15:22:59.356Z,1699888979.356 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T15:22:59.356Z,1699888979.356 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T15:23:06.620Z,1699888986.620 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-11-13T15:23:06.620Z,1699888986.620 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:23:06.631Z,1699888986.631 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:23:07.020Z,1699888987.020 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:23:07.020Z,1699888987.020 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-11-13T15:24:05.988Z,1699889045.988 [Power24vConverter](INFO): Powering down.
2023-11-13T15:24:45.579Z,1699889085.579 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-13T15:24:45.579Z,1699889085.579 [NAL9602] Data Fault, FailCount= 1
2023-11-13T15:24:45.579Z,1699889085.579 [NAL9602](ERROR): Data Fault
2023-11-13T15:24:45.624Z,1699889085.624 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-13T15:24:45.983Z,1699889085.983 [NAL9602](INFO): Powering down
2023-11-13T15:24:46.813Z,1699889086.813 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-13T15:24:46.813Z,1699889086.813 [NAL9602] No Fault, FailCount= 1
2023-11-13T15:24:47.267Z,1699889087.267 [CommandExec](IMPORTANT): got command burn on
2023-11-13T15:24:47.267Z,1699889087.267 [CommandExec](IMPORTANT): Activating dropweight wire
2023-11-13T15:25:16.283Z,1699889116.283 [NAL9602](INFO): Powering up NAL9602
2023-11-13T15:25:27.192Z,1699889127.192 [NAL9602](INFO): NAL9602 initialized
2023-11-13T15:26:07.602Z,1699889167.602 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-11-13T15:26:07.602Z,1699889167.602 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:26:07.613Z,1699889167.613 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:26:08.023Z,1699889168.023 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:26:08.023Z,1699889168.023 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-11-13T15:26:30.127Z,1699889190.127 [CommandExec](IMPORTANT): got command burn off
2023-11-13T15:26:30.127Z,1699889190.127 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-11-13T15:27:59.929Z,1699889279.929 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T15:27:59.929Z,1699889279.929 [Default:CheckIn:C.Wait] Stopped
2023-11-13T15:27:59.929Z,1699889279.929 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T15:27:59.929Z,1699889279.929 [Default:CheckIn:D] Running Loop=1
2023-11-13T15:28:00.321Z,1699889280.321 [Default:CheckIn:D] Stopped
2023-11-13T15:28:00.321Z,1699889280.321 [Default:CheckIn:E] Running Loop=1
2023-11-13T15:28:00.729Z,1699889280.729 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.685473 min 
2023-11-13T15:28:00.729Z,1699889280.729 [Default:CheckIn:E] Stopped
2023-11-13T15:28:00.729Z,1699889280.729 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T15:28:00.729Z,1699889280.729 [Default:CheckIn] Stopped
2023-11-13T15:28:00.730Z,1699889280.730 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T15:28:00.730Z,1699889280.730 [Default:CheckIn](INFO): Running loop #2
2023-11-13T15:28:00.730Z,1699889280.730 [Default:CheckIn] Running Loop=2
2023-11-13T15:28:00.730Z,1699889280.730 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T15:28:00.730Z,1699889280.730 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T15:28:24.975Z,1699889304.975 [CommandExec](IMPORTANT): got command show variable sonardyne
2023-11-13T15:28:25.111Z,1699889305.111 [CommandExec](IMPORTANT): Sonardyne_Nano.loadControl (none)
2023-11-13T15:28:25.111Z,1699889305.111 [CommandExec](IMPORTANT): Sonardyne_Nano.uart (none)
2023-11-13T15:28:25.111Z,1699889305.111 [CommandExec](IMPORTANT): Sonardyne_Nano.baud (bit_per_second)
2023-11-13T15:28:25.130Z,1699889305.130 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup (bool)
2023-11-13T15:28:25.131Z,1699889305.131 [CommandExec](IMPORTANT): Sonardyne_Nano.simulateHardware (bool)
2023-11-13T15:28:25.131Z,1699889305.131 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMin (percent)
2023-11-13T15:28:25.132Z,1699889305.132 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMax (percent)
2023-11-13T15:28:25.229Z,1699889305.229 [CommandExec](IMPORTANT): Sonardyne_Nano.enableBroadcast (bool)
2023-11-13T15:28:25.229Z,1699889305.229 [CommandExec](IMPORTANT): Sonardyne_Nano.component_voltage (volt)
2023-11-13T15:28:25.230Z,1699889305.230 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgVoltage (volt)
2023-11-13T15:28:25.230Z,1699889305.230 [CommandExec](IMPORTANT): Sonardyne_Nano.component_current (milliampere)
2023-11-13T15:28:25.231Z,1699889305.231 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent (milliampere)
2023-11-13T15:28:25.231Z,1699889305.231 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent (percent)
2023-11-13T15:29:08.590Z,1699889348.590 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-11-13T15:29:08.590Z,1699889348.590 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:29:08.625Z,1699889348.625 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:29:09.005Z,1699889349.005 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:29:09.005Z,1699889349.005 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-11-13T15:29:24.850Z,1699889364.850 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2023-11-13T15:29:24.851Z,1699889364.851 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 88.000000 %
2023-11-13T15:32:09.674Z,1699889529.674 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-11-13T15:32:09.674Z,1699889529.674 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:32:09.685Z,1699889529.685 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:32:10.056Z,1699889530.056 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:32:10.057Z,1699889530.057 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-11-13T15:32:35.054Z,1699889555.054 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:33:00.955Z,1699889580.955 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-13T15:28:00.7Z
2023-11-13T15:33:00.956Z,1699889580.956 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T15:33:00.956Z,1699889580.956 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T15:33:06.191Z,1699889586.191 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:33:25.941Z,1699889605.941 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231113T151353/Courier0007.lzma
2023-11-13T15:33:26.944Z,1699889606.944 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0007.lzma.bak
2023-11-13T15:33:26.944Z,1699889606.944 [DataOverHttps](INFO): SBD MOMSN=19061440
2023-11-13T15:33:42.391Z,1699889622.391 [DataOverHttps](INFO): Sending 511 bytes from file Logs/20231113T151353/Express0008.lzma
2023-11-13T15:33:43.392Z,1699889623.392 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0008.lzma.bak
2023-11-13T15:33:43.392Z,1699889623.392 [DataOverHttps](INFO): SBD MOMSN=19061446
2023-11-13T15:33:44.633Z,1699889624.633 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T15:33:44.633Z,1699889624.633 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T15:33:44.634Z,1699889624.634 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T15:34:44.782Z,1699889684.782 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:35:10.678Z,1699889710.678 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-11-13T15:35:10.678Z,1699889710.678 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:35:10.716Z,1699889710.716 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:35:11.118Z,1699889711.118 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:35:11.118Z,1699889711.118 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-11-13T15:35:15.906Z,1699889715.906 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:35:28.848Z,1699889728.848 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-13T15:35:28.848Z,1699889728.848 [NAL9602] Data Fault, FailCount= 2
2023-11-13T15:35:28.848Z,1699889728.848 [NAL9602](ERROR): Data Fault
2023-11-13T15:35:28.896Z,1699889728.896 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-13T15:35:29.255Z,1699889729.255 [NAL9602](INFO): Powering down
2023-11-13T15:35:30.080Z,1699889730.080 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-13T15:35:30.080Z,1699889730.080 [NAL9602] No Fault, FailCount= 2
2023-11-13T15:35:47.030Z,1699889747.030 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:35:59.555Z,1699889759.555 [NAL9602](INFO): Powering up NAL9602
2023-11-13T15:36:10.471Z,1699889770.471 [NAL9602](INFO): NAL9602 initialized
2023-11-13T15:36:18.154Z,1699889778.154 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:36:49.278Z,1699889809.278 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:36:52.290Z,1699889812.290 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.719437
2023-11-13T15:37:50.530Z,1699889870.530 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:38:11.684Z,1699889891.684 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-11-13T15:38:11.684Z,1699889891.684 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:38:11.694Z,1699889891.694 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:38:12.084Z,1699889892.084 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:38:12.084Z,1699889892.084 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-11-13T15:38:21.666Z,1699889901.666 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:38:45.217Z,1699889925.217 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T15:38:45.218Z,1699889925.218 [Default:CheckIn:C.Wait] Stopped
2023-11-13T15:38:45.218Z,1699889925.218 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T15:38:45.218Z,1699889925.218 [Default:CheckIn:D] Running Loop=1
2023-11-13T15:38:45.631Z,1699889925.631 [Default:CheckIn:D] Stopped
2023-11-13T15:38:45.631Z,1699889925.631 [Default:CheckIn:E] Running Loop=1
2023-11-13T15:38:46.038Z,1699889926.038 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.440651 min 
2023-11-13T15:38:46.038Z,1699889926.038 [Default:CheckIn:E] Stopped
2023-11-13T15:38:46.038Z,1699889926.038 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T15:38:46.038Z,1699889926.038 [Default:CheckIn] Stopped
2023-11-13T15:38:46.038Z,1699889926.038 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T15:38:46.039Z,1699889926.039 [Default:CheckIn](INFO): Running loop #3
2023-11-13T15:38:46.039Z,1699889926.039 [Default:CheckIn] Running Loop=3
2023-11-13T15:38:46.039Z,1699889926.039 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T15:38:46.039Z,1699889926.039 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T15:38:52.790Z,1699889932.790 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:39:23.914Z,1699889963.914 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:39:55.038Z,1699889995.038 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:40:26.162Z,1699890026.162 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:40:57.290Z,1699890057.290 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:41:12.687Z,1699890072.687 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-11-13T15:41:12.687Z,1699890072.687 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:41:12.698Z,1699890072.698 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:41:13.104Z,1699890073.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:41:13.105Z,1699890073.105 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-11-13T15:41:28.414Z,1699890088.414 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:41:59.538Z,1699890119.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:42:30.662Z,1699890150.662 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:43:01.794Z,1699890181.794 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:43:32.918Z,1699890212.918 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:43:46.201Z,1699890226.201 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-13T15:38:46.0Z
2023-11-13T15:43:46.201Z,1699890226.201 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T15:43:46.201Z,1699890226.201 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T15:44:04.042Z,1699890244.042 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:44:13.743Z,1699890253.743 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-11-13T15:44:13.743Z,1699890253.743 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:44:13.754Z,1699890253.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:44:16.109Z,1699890256.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:44:16.109Z,1699890256.109 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-11-13T15:44:23.017Z,1699890263.017 [NAL9602](INFO): SBD MO Status=2, MOMSN=45460, MT Status=2, MTMSN=0
2023-11-13T15:44:23.017Z,1699890263.017 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-13T15:44:35.182Z,1699890275.182 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:45:06.311Z,1699890306.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-13T15:45:35.631Z,1699890335.631 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003410
2023-11-13T15:45:42.869Z,1699890342.869 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231113T151353/Courier0010.lzma
2023-11-13T15:45:43.872Z,1699890343.872 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0010.lzma.bak
2023-11-13T15:45:43.872Z,1699890343.872 [DataOverHttps](INFO): SBD MOMSN=19061477
2023-11-13T15:45:59.329Z,1699890359.329 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231113T151353/Express0011.lzma
2023-11-13T15:46:00.332Z,1699890360.332 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0011.lzma.bak
2023-11-13T15:46:00.332Z,1699890360.332 [DataOverHttps](INFO): SBD MOMSN=19061479
2023-11-13T15:46:01.599Z,1699890361.599 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T15:46:01.599Z,1699890361.599 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T15:46:01.599Z,1699890361.599 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T15:46:12.499Z,1699890372.499 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-13T15:46:12.499Z,1699890372.499 [NAL9602] Data Fault, FailCount= 3
2023-11-13T15:46:12.499Z,1699890372.499 [NAL9602](ERROR): Data Fault
2023-11-13T15:46:12.517Z,1699890372.517 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-13T15:46:12.907Z,1699890372.907 [NAL9602](INFO): Powering down
2023-11-13T15:46:13.744Z,1699890373.744 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-13T15:46:13.744Z,1699890373.744 [NAL9602] No Fault, FailCount= 3
2023-11-13T15:46:43.204Z,1699890403.204 [NAL9602](INFO): Powering up NAL9602
2023-11-13T15:46:54.108Z,1699890414.108 [NAL9602](INFO): NAL9602 initialized
2023-11-13T15:47:16.351Z,1699890436.351 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-11-13T15:47:16.351Z,1699890436.351 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-13T15:47:16.362Z,1699890436.362 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-13T15:47:16.769Z,1699890436.769 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-13T15:47:16.769Z,1699890436.769 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-11-13T15:48:01.990Z,1699890481.990 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154800.00,A,3648.13564,N,12147.24672,W,0.058,0.00,131123,,,A*72
2023-11-13T15:48:01.993Z,1699890481.993 [NAL9602](INFO): GPS fix at 20231113T154800: (36.802261, -121.787445)
2023-11-13T15:48:34.306Z,1699890514.306 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T15:49:10.551Z,1699890550.551 [DataOverHttps](IMPORTANT): SBD MTMSN=20231113T154909
2023-11-13T15:49:17.847Z,1699890557.847 [DataOverHttps](INFO): Received command: ibit
2023-11-13T15:49:17.935Z,1699890557.935 [CommandExec](IMPORTANT): got command ibit
2023-11-13T15:49:17.972Z,1699890557.972 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-11-13T15:49:17.973Z,1699890557.973 [IBIT](IMPORTANT): Beginning control surface checks.
2023-11-13T15:49:17.980Z,1699890557.980 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T15:49:19.565Z,1699890559.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154917.00,A,3648.13829,N,12147.23022,W,0.000,80.37,131123,,,A*44
2023-11-13T15:49:19.577Z,1699890559.577 [NAL9602](INFO): GPS fix at 20231113T154917: (36.802305, -121.787170)
2023-11-13T15:49:20.867Z,1699890560.867 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T15:49:28.927Z,1699890568.927 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T15:49:29.335Z,1699890569.335 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T15:49:32.159Z,1699890572.159 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T15:49:40.246Z,1699890580.246 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T15:49:40.661Z,1699890580.661 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T15:49:43.469Z,1699890583.469 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T15:49:48.348Z,1699890588.348 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.799875
2023-11-13T15:49:48.348Z,1699890588.348 [IBIT](FAULT): Control surface position failure.
2023-11-13T15:49:51.571Z,1699890591.571 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T15:49:51.571Z,1699890591.571 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-11-13T15:50:04.016Z,1699890604.016 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802303 Longitude: -121.787170
2023-11-13T15:50:04.427Z,1699890604.427 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-11-13T15:50:04.427Z,1699890604.427 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-11-13T15:50:04.428Z,1699890604.428 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-11-13T15:50:04.831Z,1699890604.831 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-11-13T15:50:04.831Z,1699890604.831 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-11-13T15:50:04.832Z,1699890604.832 [IBIT](IMPORTANT): Pressure:9.759391 PSI
2023-11-13T15:50:04.832Z,1699890604.832 [IBIT](IMPORTANT): Humidity:12.409962 %
2023-11-13T15:50:05.238Z,1699890605.238 [IBIT](IMPORTANT): Vehicle Pitch:-0.614248 degrees
2023-11-13T15:50:05.238Z,1699890605.238 [IBIT](IMPORTANT): Vehicle  Roll:2.475645 degrees
2023-11-13T15:50:05.239Z,1699890605.239 [IBIT](IMPORTANT): Vehicle  Heading:28.209852 degrees
2023-11-13T15:50:05.636Z,1699890605.636 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-11-13T15:50:05.636Z,1699890605.636 [IBIT](IMPORTANT): buoyancyNeutral: 211.377075 cc
2023-11-13T15:50:05.637Z,1699890605.637 [IBIT](IMPORTANT): massDefault: -0.847723 cm
2023-11-13T15:50:05.637Z,1699890605.637 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-11-13T15:50:05.637Z,1699890605.637 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-11-13T15:50:05.637Z,1699890605.637 [IBIT](IMPORTANT): IBIT FAILED
2023-11-13T15:50:14.100Z,1699890614.100 [NAL9602](INFO): SBD MO Status=0, MOMSN=45460, MT Status=0, MTMSN=0
2023-11-13T15:50:14.101Z,1699890614.101 [NAL9602](INFO): No messages in MT queue
2023-11-13T15:50:44.839Z,1699890644.839 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T15:51:02.208Z,1699890662.208 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T15:51:02.208Z,1699890662.208 [Default:CheckIn:C.Wait] Stopped
2023-11-13T15:51:02.208Z,1699890662.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T15:51:02.208Z,1699890662.208 [Default:CheckIn:D] Running Loop=1
2023-11-13T15:51:02.617Z,1699890662.617 [Default:CheckIn:D] Stopped
2023-11-13T15:51:02.617Z,1699890662.617 [Default:CheckIn:E] Running Loop=1
2023-11-13T15:51:03.031Z,1699890663.031 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.723741 min 
2023-11-13T15:51:03.031Z,1699890663.031 [Default:CheckIn:E] Stopped
2023-11-13T15:51:03.031Z,1699890663.031 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T15:51:03.031Z,1699890663.031 [Default:CheckIn] Stopped
2023-11-13T15:51:03.031Z,1699890663.031 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T15:51:03.031Z,1699890663.031 [Default:CheckIn](INFO): Running loop #4
2023-11-13T15:51:03.032Z,1699890663.032 [Default:CheckIn] Running Loop=4
2023-11-13T15:51:03.032Z,1699890663.032 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T15:51:03.032Z,1699890663.032 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T15:51:05.037Z,1699890665.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155103.00,A,3648.13590,N,12147.23158,W,0.136,80.37,131123,,,D*4A
2023-11-13T15:51:05.039Z,1699890665.039 [NAL9602](INFO): GPS fix at 20231113T155103: (36.802265, -121.787193)
2023-11-13T15:51:05.049Z,1699890665.049 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T15:51:05.050Z,1699890665.050 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T15:51:12.018Z,1699890672.018 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20231113T151353/Courier0013.lzma
2023-11-13T15:51:13.020Z,1699890673.020 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0013.lzma.bak
2023-11-13T15:51:13.020Z,1699890673.020 [DataOverHttps](INFO): SBD MOMSN=19061491
2023-11-13T15:51:22.005Z,1699890682.005 [NAL9602](INFO): SBD MO Status=0, MOMSN=45461, MT Status=0, MTMSN=0
2023-11-13T15:51:22.005Z,1699890682.005 [NAL9602](INFO): No messages in MT queue
2023-11-13T15:51:28.779Z,1699890688.779 [DataOverHttps](INFO): Sending 980 bytes from file Logs/20231113T151353/Express0014.lzma
2023-11-13T15:51:29.780Z,1699890689.780 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0014.lzma.bak
2023-11-13T15:51:29.780Z,1699890689.780 [DataOverHttps](INFO): SBD MOMSN=19061494
2023-11-13T15:51:30.892Z,1699890690.892 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T15:51:30.892Z,1699890690.892 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T15:51:30.892Z,1699890690.892 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T15:51:52.700Z,1699890712.700 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T15:54:10.495Z,1699890850.495 [DataOverHttps](IMPORTANT): SBD MTMSN=20231113T155409
2023-11-13T15:54:17.803Z,1699890857.803 [DataOverHttps](INFO): Received command: failc
2023-11-13T15:54:17.838Z,1699890857.838 [CommandExec](IMPORTANT): got command failComponent
2023-11-13T15:54:17.838Z,1699890857.838 [CommandExec](IMPORTANT): Failed components:
2023-11-13T15:54:17.839Z,1699890857.839 [CommandExec](IMPORTANT): No failed Components.
2023-11-13T15:56:31.521Z,1699890991.521 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T15:56:31.521Z,1699890991.521 [Default:CheckIn:C.Wait] Stopped
2023-11-13T15:56:31.521Z,1699890991.521 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T15:56:31.521Z,1699890991.521 [Default:CheckIn:D] Running Loop=1
2023-11-13T15:56:31.926Z,1699890991.926 [Default:CheckIn:D] Stopped
2023-11-13T15:56:31.926Z,1699890991.926 [Default:CheckIn:E] Running Loop=1
2023-11-13T15:56:32.326Z,1699890992.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.212223 min 
2023-11-13T15:56:32.326Z,1699890992.326 [Default:CheckIn:E] Stopped
2023-11-13T15:56:32.326Z,1699890992.326 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T15:56:32.326Z,1699890992.326 [Default:CheckIn] Stopped
2023-11-13T15:56:32.326Z,1699890992.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T15:56:32.326Z,1699890992.326 [Default:CheckIn](INFO): Running loop #5
2023-11-13T15:56:32.326Z,1699890992.326 [Default:CheckIn] Running Loop=5
2023-11-13T15:56:32.326Z,1699890992.326 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T15:56:32.327Z,1699890992.327 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T15:56:34.327Z,1699890994.327 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155633.00,A,3648.13850,N,12147.23084,W,0.078,80.37,131123,,,D*44
2023-11-13T15:56:34.330Z,1699890994.330 [NAL9602](INFO): GPS fix at 20231113T155633: (36.802308, -121.787181)
2023-11-13T15:56:34.341Z,1699890994.341 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T15:56:34.341Z,1699890994.341 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T15:56:41.969Z,1699891001.969 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231113T151353/Courier0016.lzma
2023-11-13T15:56:42.972Z,1699891002.972 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0016.lzma.bak
2023-11-13T15:56:42.972Z,1699891002.972 [DataOverHttps](INFO): SBD MOMSN=19061529
2023-11-13T15:56:46.448Z,1699891006.448 [NAL9602](INFO): SBD MO Status=0, MOMSN=45462, MT Status=0, MTMSN=0
2023-11-13T15:56:46.448Z,1699891006.448 [NAL9602](INFO): No messages in MT queue
2023-11-13T15:57:01.946Z,1699891021.946 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20231113T151353/Express0018.lzma
2023-11-13T15:57:02.948Z,1699891022.948 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0018.lzma.bak
2023-11-13T15:57:02.948Z,1699891022.948 [DataOverHttps](INFO): SBD MOMSN=19061532
2023-11-13T15:57:17.195Z,1699891037.195 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T15:57:18.821Z,1699891038.821 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20231113T151353/Express0023.lzma
2023-11-13T15:57:19.824Z,1699891039.824 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0023.lzma.bak
2023-11-13T15:57:19.824Z,1699891039.824 [DataOverHttps](INFO): SBD MOMSN=19061541
2023-11-13T15:57:20.866Z,1699891040.866 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T15:57:20.866Z,1699891040.866 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T15:57:20.866Z,1699891040.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:02:21.479Z,1699891341.479 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:02:21.480Z,1699891341.480 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:02:21.480Z,1699891341.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:02:21.480Z,1699891341.480 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:02:21.881Z,1699891341.881 [Default:CheckIn:D] Stopped
2023-11-13T16:02:21.881Z,1699891341.881 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:02:22.283Z,1699891342.283 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.044816 min 
2023-11-13T16:02:22.283Z,1699891342.283 [Default:CheckIn:E] Stopped
2023-11-13T16:02:22.283Z,1699891342.283 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:02:22.283Z,1699891342.283 [Default:CheckIn] Stopped
2023-11-13T16:02:22.283Z,1699891342.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:02:22.284Z,1699891342.284 [Default:CheckIn](INFO): Running loop #6
2023-11-13T16:02:22.284Z,1699891342.284 [Default:CheckIn] Running Loop=6
2023-11-13T16:02:22.284Z,1699891342.284 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:02:22.284Z,1699891342.284 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:02:24.293Z,1699891344.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160223.00,A,3648.13978,N,12147.23042,W,0.058,80.37,131123,,,D*44
2023-11-13T16:02:24.295Z,1699891344.295 [NAL9602](INFO): GPS fix at 20231113T160223: (36.802330, -121.787174)
2023-11-13T16:02:24.357Z,1699891344.357 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:02:24.357Z,1699891344.357 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:02:32.213Z,1699891352.213 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231113T151353/Courier0025.lzma
2023-11-13T16:02:33.216Z,1699891353.216 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0025.lzma.bak
2023-11-13T16:02:33.216Z,1699891353.216 [DataOverHttps](INFO): SBD MOMSN=19061548
2023-11-13T16:02:38.836Z,1699891358.836 [NAL9602](INFO): SBD MO Status=0, MOMSN=45463, MT Status=0, MTMSN=0
2023-11-13T16:02:38.836Z,1699891358.836 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:02:50.169Z,1699891370.169 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231113T151353/Express0026.lzma
2023-11-13T16:02:51.172Z,1699891371.172 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0026.lzma.bak
2023-11-13T16:02:51.172Z,1699891371.172 [DataOverHttps](INFO): SBD MOMSN=19061551
2023-11-13T16:02:52.195Z,1699891372.195 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:02:52.195Z,1699891372.195 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T16:02:52.195Z,1699891372.195 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:03:09.553Z,1699891389.553 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:05:01.667Z,1699891501.667 [DataOverHttps](IMPORTANT): SBD MTMSN=20231113T160500
2023-11-13T16:05:09.033Z,1699891509.033 [DataOverHttps](INFO): Received command: ibit
2023-11-13T16:05:09.099Z,1699891509.099 [CommandExec](IMPORTANT): got command ibit
2023-11-13T16:05:09.180Z,1699891509.180 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-11-13T16:05:09.180Z,1699891509.180 [IBIT](IMPORTANT): Beginning control surface checks.
2023-11-13T16:05:09.183Z,1699891509.183 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T16:05:10.773Z,1699891510.773 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160510.00,A,3648.13919,N,12147.22961,W,0.000,80.37,131123,,,D*40
2023-11-13T16:05:10.776Z,1699891510.776 [NAL9602](INFO): GPS fix at 20231113T160510: (36.802320, -121.787160)
2023-11-13T16:05:12.062Z,1699891512.062 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T16:05:20.111Z,1699891520.111 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T16:05:20.479Z,1699891520.479 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T16:05:23.354Z,1699891523.354 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T16:05:31.435Z,1699891531.435 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T16:05:31.907Z,1699891531.907 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T16:05:34.679Z,1699891534.679 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T16:05:39.928Z,1699891539.928 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.799875
2023-11-13T16:05:39.928Z,1699891539.928 [IBIT](FAULT): Control surface position failure.
2023-11-13T16:05:42.747Z,1699891542.747 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T16:05:42.747Z,1699891542.747 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-11-13T16:05:44.297Z,1699891544.297 [NAL9602](INFO): SBD MO Status=0, MOMSN=45464, MT Status=0, MTMSN=0
2023-11-13T16:05:44.297Z,1699891544.297 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:05:45.513Z,1699891545.513 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160544.00,A,3648.13851,N,12147.22974,W,0.039,80.37,131123,,,D*42
2023-11-13T16:05:45.524Z,1699891545.524 [NAL9602](INFO): GPS fix at 20231113T160544: (36.802309, -121.787162)
2023-11-13T16:05:47.937Z,1699891547.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160547.00,A,3648.13839,N,12147.22977,W,0.058,80.37,131123,,,D*4B
2023-11-13T16:05:47.939Z,1699891547.939 [NAL9602](INFO): GPS fix at 20231113T160547: (36.802307, -121.787163)
2023-11-13T16:05:51.169Z,1699891551.169 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160550.00,A,3648.13831,N,12147.22975,W,0.039,80.37,131123,,,D*40
2023-11-13T16:05:51.171Z,1699891551.171 [NAL9602](INFO): GPS fix at 20231113T160550: (36.802305, -121.787162)
2023-11-13T16:05:53.997Z,1699891553.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160553.00,A,3648.13840,N,12147.22972,W,0.019,80.37,131123,,,D*40
2023-11-13T16:05:53.999Z,1699891553.999 [NAL9602](INFO): GPS fix at 20231113T160553: (36.802307, -121.787162)
2023-11-13T16:05:55.655Z,1699891555.655 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802307 Longitude: -121.787163
2023-11-13T16:05:56.030Z,1699891556.030 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-11-13T16:05:56.031Z,1699891556.031 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-11-13T16:05:56.031Z,1699891556.031 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-11-13T16:05:56.439Z,1699891556.439 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-11-13T16:05:56.439Z,1699891556.439 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-11-13T16:05:56.440Z,1699891556.440 [IBIT](IMPORTANT): Pressure:9.659140 PSI
2023-11-13T16:05:56.440Z,1699891556.440 [IBIT](IMPORTANT): Humidity:12.489318 %
2023-11-13T16:05:56.850Z,1699891556.850 [IBIT](IMPORTANT): Vehicle Pitch:-0.584311 degrees
2023-11-13T16:05:56.851Z,1699891556.851 [IBIT](IMPORTANT): Vehicle  Roll:2.504585 degrees
2023-11-13T16:05:56.851Z,1699891556.851 [IBIT](IMPORTANT): Vehicle  Heading:27.894745 degrees
2023-11-13T16:05:57.266Z,1699891557.266 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-11-13T16:05:57.267Z,1699891557.267 [IBIT](IMPORTANT): buoyancyNeutral: 211.377075 cc
2023-11-13T16:05:57.267Z,1699891557.267 [IBIT](IMPORTANT): massDefault: -0.847723 cm
2023-11-13T16:05:57.267Z,1699891557.267 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-11-13T16:05:57.268Z,1699891557.267 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-11-13T16:05:57.268Z,1699891557.268 [IBIT](IMPORTANT): IBIT FAILED
2023-11-13T16:06:26.315Z,1699891586.315 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:07:52.781Z,1699891672.781 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:07:52.781Z,1699891672.781 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:07:52.781Z,1699891672.781 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:07:52.781Z,1699891672.781 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:07:53.187Z,1699891673.187 [Default:CheckIn:D] Stopped
2023-11-13T16:07:53.188Z,1699891673.188 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:07:53.611Z,1699891673.611 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.566585 min 
2023-11-13T16:07:53.612Z,1699891673.612 [Default:CheckIn:E] Stopped
2023-11-13T16:07:53.612Z,1699891673.612 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:07:53.612Z,1699891673.612 [Default:CheckIn] Stopped
2023-11-13T16:07:53.612Z,1699891673.612 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:07:53.612Z,1699891673.612 [Default:CheckIn](INFO): Running loop #7
2023-11-13T16:07:53.612Z,1699891673.612 [Default:CheckIn] Running Loop=7
2023-11-13T16:07:53.612Z,1699891673.612 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:07:53.612Z,1699891673.612 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:07:55.601Z,1699891675.601 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160754.00,A,3648.13989,N,12147.22993,W,0.078,80.37,131123,,,D*49
2023-11-13T16:07:55.603Z,1699891675.603 [NAL9602](INFO): GPS fix at 20231113T160754: (36.802332, -121.787166)
2023-11-13T16:07:55.643Z,1699891675.643 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:07:55.643Z,1699891675.643 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:08:03.929Z,1699891683.929 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231113T151353/Courier0028.lzma
2023-11-13T16:08:04.932Z,1699891684.932 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0028.lzma.bak
2023-11-13T16:08:04.932Z,1699891684.932 [DataOverHttps](INFO): SBD MOMSN=19061564
2023-11-13T16:08:14.987Z,1699891694.987 [NAL9602](INFO): SBD MO Status=0, MOMSN=45465, MT Status=0, MTMSN=0
2023-11-13T16:08:14.987Z,1699891694.987 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:08:23.044Z,1699891703.044 [DataOverHttps](INFO): Sending 779 bytes from file Logs/20231113T151353/Express0029.lzma
2023-11-13T16:08:24.044Z,1699891704.044 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0029.lzma.bak
2023-11-13T16:08:24.044Z,1699891704.044 [DataOverHttps](INFO): SBD MOMSN=19061568
2023-11-13T16:08:25.149Z,1699891705.149 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:08:25.149Z,1699891705.149 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T16:08:25.149Z,1699891705.149 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:08:45.739Z,1699891725.739 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:13:25.727Z,1699892005.727 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:13:25.727Z,1699892005.727 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:13:25.727Z,1699892005.727 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:13:25.727Z,1699892005.727 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:13:26.142Z,1699892006.142 [Default:CheckIn:D] Stopped
2023-11-13T16:13:26.142Z,1699892006.142 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:13:26.534Z,1699892006.534 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.115828 min 
2023-11-13T16:13:26.534Z,1699892006.534 [Default:CheckIn:E] Stopped
2023-11-13T16:13:26.534Z,1699892006.534 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:13:26.534Z,1699892006.534 [Default:CheckIn] Stopped
2023-11-13T16:13:26.534Z,1699892006.534 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:13:26.535Z,1699892006.535 [Default:CheckIn](INFO): Running loop #8
2023-11-13T16:13:26.535Z,1699892006.535 [Default:CheckIn] Running Loop=8
2023-11-13T16:13:26.535Z,1699892006.535 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:13:26.535Z,1699892006.535 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:13:28.545Z,1699892008.545 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161327.00,A,3648.13975,N,12147.22754,W,0.039,80.37,131123,,,D*4B
2023-11-13T16:13:28.547Z,1699892008.547 [NAL9602](INFO): GPS fix at 20231113T161327: (36.802329, -121.787126)
2023-11-13T16:13:28.558Z,1699892008.558 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:13:28.558Z,1699892008.558 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:13:36.221Z,1699892016.221 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231113T151353/Courier0034.lzma
2023-11-13T16:13:37.224Z,1699892017.224 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0034.lzma.bak
2023-11-13T16:13:37.224Z,1699892017.224 [DataOverHttps](INFO): SBD MOMSN=19061599
2023-11-13T16:13:47.534Z,1699892027.534 [NAL9602](INFO): SBD MO Status=0, MOMSN=45466, MT Status=0, MTMSN=0
2023-11-13T16:13:47.534Z,1699892027.534 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:13:53.065Z,1699892033.065 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231113T151353/Express0035.lzma
2023-11-13T16:13:54.068Z,1699892034.068 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0035.lzma.bak
2023-11-13T16:13:54.068Z,1699892034.068 [DataOverHttps](INFO): SBD MOMSN=19061602
2023-11-13T16:13:55.213Z,1699892035.213 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:13:55.213Z,1699892035.213 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T16:13:55.213Z,1699892035.213 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:14:18.247Z,1699892058.247 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:14:32.875Z,1699892072.875 [DataOverHttps](IMPORTANT): SBD MTMSN=20231113T161431
2023-11-13T16:14:40.321Z,1699892080.321 [DataOverHttps](INFO): Received command: ibit
2023-11-13T16:14:40.358Z,1699892080.358 [CommandExec](IMPORTANT): got command ibit
2023-11-13T16:14:40.469Z,1699892080.469 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-11-13T16:14:40.469Z,1699892080.469 [IBIT](IMPORTANT): Beginning control surface checks.
2023-11-13T16:14:40.473Z,1699892080.473 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T16:14:42.073Z,1699892082.073 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161441.00,A,3648.14011,N,12147.22773,W,0.000,80.37,131123,,,D*4F
2023-11-13T16:14:42.075Z,1699892082.075 [NAL9602](INFO): GPS fix at 20231113T161441: (36.802335, -121.787129)
2023-11-13T16:14:43.355Z,1699892083.355 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T16:14:51.421Z,1699892091.421 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002517
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002497
CHAN A3 (5V): -0.001600
CHAN B0 (3.3V): -0.000277
CHAN B1 (3.15aV): -0.000110
CHAN B2 (3.15bV): -0.000208
CHAN B3 (GND): 0.000321
OPEN: -0.000182
Full Scale: +/- 1 mA
2023-11-13T16:15:11.244Z,1699892111.244 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.795477
2023-11-13T16:15:11.244Z,1699892111.244 [IBIT](FAULT): Control surface position failure.
2023-11-13T16:15:26.931Z,1699892126.931 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802334 Longitude: -121.787132
2023-11-13T16:15:27.345Z,1699892127.345 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2023-11-13T16:15:27.345Z,1699892127.345 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-11-13T16:15:27.345Z,1699892127.345 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-11-13T16:15:27.756Z,1699892127.756 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-11-13T16:15:27.756Z,1699892127.756 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-11-13T16:15:27.756Z,1699892127.756 [IBIT](IMPORTANT): Pressure:9.713581 PSI
2023-11-13T16:15:27.757Z,1699892127.757 [IBIT](IMPORTANT): Humidity:12.538152 %
2023-11-13T16:15:28.142Z,1699892128.142 [IBIT](IMPORTANT): Vehicle Pitch:-0.639893 degrees
2023-11-13T16:15:28.143Z,1699892128.143 [IBIT](IMPORTANT): Vehicle  Roll:2.540043 degrees
2023-11-13T16:15:28.143Z,1699892128.143 [IBIT](IMPORTANT): Vehicle  Heading:26.850702 degrees
2023-11-13T16:15:28.551Z,1699892128.551 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-11-13T16:15:28.551Z,1699892128.551 [IBIT](IMPORTANT): buoyancyNeutral: 211.377075 cc
2023-11-13T16:15:28.552Z,1699892128.552 [IBIT](IMPORTANT): massDefault: -0.847723 cm
2023-11-13T16:15:28.552Z,1699892128.552 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-11-13T16:15:28.552Z,1699892128.552 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-11-13T16:15:28.552Z,1699892128.552 [IBIT](IMPORTANT): IBIT FAILED
2023-11-13T16:15:34.189Z,1699892134.189 [NAL9602](INFO): SBD MO Status=2, MOMSN=45467, MT Status=2, MTMSN=0
2023-11-13T16:15:34.189Z,1699892134.189 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-13T16:16:31.959Z,1699892191.959 [NAL9602](INFO): SBD MO Status=2, MOMSN=45467, MT Status=2, MTMSN=0
2023-11-13T16:16:31.959Z,1699892191.959 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-13T16:17:17.625Z,1699892237.625 [NAL9602](INFO): SBD MO Status=2, MOMSN=45467, MT Status=2, MTMSN=0
2023-11-13T16:17:17.625Z,1699892237.625 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-13T16:17:43.475Z,1699892263.475 [NAL9602](INFO): SBD MO Status=0, MOMSN=45467, MT Status=0, MTMSN=0
2023-11-13T16:17:43.475Z,1699892263.475 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:18:14.192Z,1699892294.192 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:18:55.819Z,1699892335.819 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:18:55.819Z,1699892335.819 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:18:55.819Z,1699892335.819 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:18:55.819Z,1699892335.819 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:18:56.209Z,1699892336.209 [Default:CheckIn:D] Stopped
2023-11-13T16:18:56.209Z,1699892336.209 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:18:56.615Z,1699892336.615 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.616943 min 
2023-11-13T16:18:56.615Z,1699892336.615 [Default:CheckIn:E] Stopped
2023-11-13T16:18:56.615Z,1699892336.615 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:18:56.615Z,1699892336.615 [Default:CheckIn] Stopped
2023-11-13T16:18:56.615Z,1699892336.615 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:18:56.616Z,1699892336.616 [Default:CheckIn](INFO): Running loop #9
2023-11-13T16:18:56.616Z,1699892336.616 [Default:CheckIn] Running Loop=9
2023-11-13T16:18:56.616Z,1699892336.616 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:18:56.616Z,1699892336.616 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:18:58.621Z,1699892338.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161857.00,A,3648.14105,N,12147.22882,W,0.214,80.37,131123,,,D*46
2023-11-13T16:18:58.623Z,1699892338.623 [NAL9602](INFO): GPS fix at 20231113T161857: (36.802351, -121.787147)
2023-11-13T16:18:58.634Z,1699892338.634 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:18:58.634Z,1699892338.634 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:19:06.249Z,1699892346.249 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231113T151353/Courier0037.lzma
2023-11-13T16:19:07.252Z,1699892347.252 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0037.lzma.bak
2023-11-13T16:19:07.252Z,1699892347.252 [DataOverHttps](INFO): SBD MOMSN=19061615
2023-11-13T16:19:19.223Z,1699892359.223 [NAL9602](INFO): SBD MO Status=0, MOMSN=45468, MT Status=0, MTMSN=0
2023-11-13T16:19:19.223Z,1699892359.223 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:19:24.116Z,1699892364.116 [DataOverHttps](INFO): Sending 836 bytes from file Logs/20231113T151353/Express0038.lzma
2023-11-13T16:19:25.116Z,1699892365.116 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0038.lzma.bak
2023-11-13T16:19:25.116Z,1699892365.116 [DataOverHttps](INFO): SBD MOMSN=19061618
2023-11-13T16:19:40.809Z,1699892380.809 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20231113T151353/Express0041.lzma
2023-11-13T16:19:41.812Z,1699892381.812 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0041.lzma.bak
2023-11-13T16:19:41.812Z,1699892381.812 [DataOverHttps](INFO): SBD MOMSN=19061646
2023-11-13T16:19:43.088Z,1699892383.088 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:19:43.088Z,1699892383.088 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T16:19:43.088Z,1699892383.088 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:19:49.931Z,1699892389.931 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:24:03.009Z,1699892643.009 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:24:16.740Z,1699892656.740 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:24:30.130Z,1699892670.130 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:24:43.460Z,1699892683.460 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:24:43.496Z,1699892683.496 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:24:43.496Z,1699892683.496 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:24:43.496Z,1699892683.496 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:24:43.496Z,1699892683.496 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:24:43.913Z,1699892683.913 [Default:CheckIn:D] Stopped
2023-11-13T16:24:43.913Z,1699892683.913 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:24:44.301Z,1699892684.301 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.411987 min 
2023-11-13T16:24:44.301Z,1699892684.301 [Default:CheckIn:E] Stopped
2023-11-13T16:24:44.301Z,1699892684.301 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:24:44.302Z,1699892684.302 [Default:CheckIn] Stopped
2023-11-13T16:24:44.302Z,1699892684.302 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:24:44.302Z,1699892684.302 [Default:CheckIn](INFO): Running loop #10
2023-11-13T16:24:44.302Z,1699892684.302 [Default:CheckIn] Running Loop=10
2023-11-13T16:24:44.302Z,1699892684.302 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:24:44.302Z,1699892684.302 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:24:46.362Z,1699892686.362 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162445.00,A,3648.14015,N,12147.22895,W,0.019,0.00,131123,,,D*7F
2023-11-13T16:24:46.365Z,1699892686.365 [NAL9602](INFO): GPS fix at 20231113T162445: (36.802336, -121.787149)
2023-11-13T16:24:46.376Z,1699892686.376 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:24:46.376Z,1699892686.376 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:24:56.889Z,1699892696.889 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231113T151353/Courier0043.lzma
2023-11-13T16:24:57.269Z,1699892697.269 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:24:57.892Z,1699892697.892 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0043.lzma.bak
2023-11-13T16:24:57.892Z,1699892697.892 [DataOverHttps](INFO): SBD MOMSN=19061650
2023-11-13T16:25:02.533Z,1699892702.533 [NAL9602](INFO): SBD MO Status=0, MOMSN=45469, MT Status=0, MTMSN=0
2023-11-13T16:25:02.533Z,1699892702.533 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:25:11.416Z,1699892711.416 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:25:13.757Z,1699892713.757 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231113T151353/Express0044.lzma
2023-11-13T16:25:14.760Z,1699892714.760 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0044.lzma.bak
2023-11-13T16:25:14.760Z,1699892714.760 [DataOverHttps](INFO): SBD MOMSN=19061653
2023-11-13T16:25:15.875Z,1699892715.875 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:25:15.875Z,1699892715.875 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T16:25:15.875Z,1699892715.875 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:25:25.153Z,1699892725.153 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:25:33.235Z,1699892733.235 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:25:39.292Z,1699892739.292 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:25:53.028Z,1699892753.028 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:26:06.765Z,1699892766.765 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-13T16:26:30.679Z,1699892790.679 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-11-13T16:26:30.679Z,1699892790.679 [DropWeight] Hardware Fault, FailCount= 1
2023-11-13T16:26:30.679Z,1699892790.679 [DropWeight](ERROR): Hardware Fault
2023-11-13T16:26:30.697Z,1699892790.697 [CommandExec](FAULT): Scheduling is paused
2023-11-13T16:26:30.697Z,1699892790.697 [CBIT](INFO): Critical error at 20231113T162630
2023-11-13T16:26:30.697Z,1699892790.697 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-11-13T16:26:30.700Z,1699892790.700 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-11-13T16:26:30.700Z,1699892790.700 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-11-13T16:26:31.104Z,1699892791.104 [CBIT](INFO): Critical error at 20231113T162630
2023-11-13T16:28:42.928Z,1699892922.928 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-11-13T16:28:42.931Z,1699892922.931 [BPC1](INFO): Received data from all battery sticks.
2023-11-13T16:30:16.303Z,1699893016.303 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:30:16.303Z,1699893016.303 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:30:16.303Z,1699893016.303 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:30:16.303Z,1699893016.303 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:30:16.713Z,1699893016.713 [Default:CheckIn:D] Stopped
2023-11-13T16:30:16.713Z,1699893016.713 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:30:17.183Z,1699893017.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.958675 min 
2023-11-13T16:30:17.183Z,1699893017.183 [Default:CheckIn:E] Stopped
2023-11-13T16:30:17.183Z,1699893017.183 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:30:17.184Z,1699893017.184 [Default:CheckIn] Stopped
2023-11-13T16:30:17.184Z,1699893017.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:30:17.184Z,1699893017.184 [Default:CheckIn](INFO): Running loop #11
2023-11-13T16:30:17.184Z,1699893017.184 [Default:CheckIn] Running Loop=11
2023-11-13T16:30:17.184Z,1699893017.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:30:17.184Z,1699893017.184 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:30:19.119Z,1699893019.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163018.00,A,3648.13930,N,12147.23029,W,0.233,0.00,131123,,,D*7F
2023-11-13T16:30:19.121Z,1699893019.121 [NAL9602](INFO): GPS fix at 20231113T163018: (36.802322, -121.787171)
2023-11-13T16:30:19.141Z,1699893019.141 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:30:19.141Z,1699893019.141 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:30:29.314Z,1699893029.314 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20231113T151353/Courier0046.lzma
2023-11-13T16:30:30.316Z,1699893030.316 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0046.lzma.bak
2023-11-13T16:30:30.316Z,1699893030.316 [DataOverHttps](INFO): SBD MOMSN=19061664
2023-11-13T16:30:31.330Z,1699893031.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=45470, MT Status=0, MTMSN=0
2023-11-13T16:30:31.330Z,1699893031.330 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:30:45.990Z,1699893045.990 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20231113T151353/Express0047.lzma
2023-11-13T16:30:46.992Z,1699893046.992 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0047.lzma.bak
2023-11-13T16:30:46.992Z,1699893046.992 [DataOverHttps](INFO): SBD MOMSN=19061669
2023-11-13T16:30:48.304Z,1699893048.304 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:30:48.304Z,1699893048.304 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T16:30:48.305Z,1699893048.305 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:31:02.076Z,1699893062.076 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:35:49.049Z,1699893349.049 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:35:49.049Z,1699893349.049 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:35:49.049Z,1699893349.049 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:35:49.050Z,1699893349.050 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:35:49.444Z,1699893349.444 [Default:CheckIn:D] Stopped
2023-11-13T16:35:49.445Z,1699893349.445 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:35:49.850Z,1699893349.850 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.504207 min 
2023-11-13T16:35:49.850Z,1699893349.850 [Default:CheckIn:E] Stopped
2023-11-13T16:35:49.850Z,1699893349.850 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:35:49.850Z,1699893349.850 [Default:CheckIn] Stopped
2023-11-13T16:35:49.850Z,1699893349.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:35:49.851Z,1699893349.851 [Default:CheckIn](INFO): Running loop #12
2023-11-13T16:35:49.851Z,1699893349.851 [Default:CheckIn] Running Loop=12
2023-11-13T16:35:49.851Z,1699893349.851 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:35:49.851Z,1699893349.851 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:35:51.857Z,1699893351.857 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163551.00,A,3648.14080,N,12147.22931,W,0.117,159.80,131123,,,D*73
2023-11-13T16:35:51.859Z,1699893351.859 [NAL9602](INFO): GPS fix at 20231113T163551: (36.802347, -121.787155)
2023-11-13T16:35:51.870Z,1699893351.870 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:35:51.870Z,1699893351.870 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:35:58.417Z,1699893358.417 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231113T151353/Courier0049.lzma
2023-11-13T16:35:59.420Z,1699893359.420 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0049.lzma.bak
2023-11-13T16:35:59.420Z,1699893359.420 [DataOverHttps](INFO): SBD MOMSN=19061681
2023-11-13T16:36:10.040Z,1699893370.040 [NAL9602](INFO): SBD MO Status=2, MOMSN=45471, MT Status=2, MTMSN=0
2023-11-13T16:36:10.040Z,1699893370.040 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-13T16:36:15.229Z,1699893375.229 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231113T151353/Express0050.lzma
2023-11-13T16:36:16.232Z,1699893376.232 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0050.lzma.bak
2023-11-13T16:36:16.232Z,1699893376.232 [DataOverHttps](INFO): SBD MOMSN=19061684
2023-11-13T16:36:17.335Z,1699893377.335 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:36:17.335Z,1699893377.335 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T16:36:17.335Z,1699893377.335 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:36:29.077Z,1699893389.077 [NAL9602](INFO): SBD MO Status=0, MOMSN=45471, MT Status=0, MTMSN=0
2023-11-13T16:36:29.077Z,1699893389.077 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:36:59.830Z,1699893419.830 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:41:17.777Z,1699893677.777 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:41:17.777Z,1699893677.777 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:41:17.778Z,1699893677.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:41:17.778Z,1699893677.778 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:41:18.194Z,1699893678.194 [Default:CheckIn:D] Stopped
2023-11-13T16:41:18.194Z,1699893678.194 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:41:18.618Z,1699893678.618 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.983358 min 
2023-11-13T16:41:18.618Z,1699893678.618 [Default:CheckIn:E] Stopped
2023-11-13T16:41:18.618Z,1699893678.618 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:41:18.618Z,1699893678.618 [Default:CheckIn] Stopped
2023-11-13T16:41:18.618Z,1699893678.618 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:41:18.618Z,1699893678.618 [Default:CheckIn](INFO): Running loop #13
2023-11-13T16:41:18.618Z,1699893678.618 [Default:CheckIn] Running Loop=13
2023-11-13T16:41:18.618Z,1699893678.618 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:41:18.619Z,1699893678.619 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:41:20.586Z,1699893680.586 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164119.00,A,3648.14009,N,12147.22863,W,0.078,135.41,131123,,,D*74
2023-11-13T16:41:20.589Z,1699893680.589 [NAL9602](INFO): GPS fix at 20231113T164119: (36.802335, -121.787144)
2023-11-13T16:41:20.600Z,1699893680.600 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:41:20.600Z,1699893680.600 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:41:27.577Z,1699893687.577 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231113T151353/Courier0052.lzma
2023-11-13T16:41:28.580Z,1699893688.580 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0052.lzma.bak
2023-11-13T16:41:28.580Z,1699893688.580 [DataOverHttps](INFO): SBD MOMSN=19061696
2023-11-13T16:41:30.700Z,1699893690.700 [CBIT](INFO): Clearing failed state for component DropWeight
2023-11-13T16:41:30.700Z,1699893690.700 [DropWeight] No Fault, FailCount= 1
2023-11-13T16:41:44.373Z,1699893704.373 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231113T151353/Express0053.lzma
2023-11-13T16:41:45.376Z,1699893705.376 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0053.lzma.bak
2023-11-13T16:41:45.376Z,1699893705.376 [DataOverHttps](INFO): SBD MOMSN=19061699
2023-11-13T16:41:46.447Z,1699893706.447 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:41:46.447Z,1699893706.447 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T16:41:46.448Z,1699893706.448 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:41:49.265Z,1699893709.265 [NAL9602](INFO): SBD MO Status=2, MOMSN=45472, MT Status=2, MTMSN=0
2023-11-13T16:41:49.265Z,1699893709.265 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-13T16:42:05.425Z,1699893725.425 [NAL9602](INFO): SBD MO Status=0, MOMSN=45472, MT Status=0, MTMSN=0
2023-11-13T16:42:05.425Z,1699893725.425 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:42:36.190Z,1699893756.190 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:46:47.268Z,1699894007.268 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:46:47.268Z,1699894007.268 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:46:47.268Z,1699894007.268 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:46:47.268Z,1699894007.268 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:46:47.690Z,1699894007.690 [Default:CheckIn:D] Stopped
2023-11-13T16:46:47.690Z,1699894007.690 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:46:48.070Z,1699894008.070 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.474886 min 
2023-11-13T16:46:48.070Z,1699894008.070 [Default:CheckIn:E] Stopped
2023-11-13T16:46:48.070Z,1699894008.070 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:46:48.070Z,1699894008.070 [Default:CheckIn] Stopped
2023-11-13T16:46:48.070Z,1699894008.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:46:48.070Z,1699894008.070 [Default:CheckIn](INFO): Running loop #14
2023-11-13T16:46:48.070Z,1699894008.070 [Default:CheckIn] Running Loop=14
2023-11-13T16:46:48.070Z,1699894008.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:46:48.071Z,1699894008.071 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:46:50.068Z,1699894010.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164649.00,A,3648.13951,N,12147.22939,W,0.136,135.41,131123,,,D*70
2023-11-13T16:46:50.070Z,1699894010.070 [NAL9602](INFO): GPS fix at 20231113T164649: (36.802325, -121.787156)
2023-11-13T16:46:50.089Z,1699894010.089 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:46:50.089Z,1699894010.089 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:47:00.309Z,1699894020.309 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231113T151353/Courier0055.lzma
2023-11-13T16:47:01.312Z,1699894021.312 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0055.lzma.bak
2023-11-13T16:47:01.312Z,1699894021.312 [DataOverHttps](IMPORTANT): SBD MOMSN=19061704, MTMSN=20231113T164700
2023-11-13T16:47:07.092Z,1699894027.092 [NAL9602](INFO): SBD MO Status=0, MOMSN=45473, MT Status=0, MTMSN=0
2023-11-13T16:47:07.092Z,1699894027.092 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:47:08.666Z,1699894028.666 [DataOverHttps](INFO): Received command: ibit
2023-11-13T16:47:08.667Z,1699894028.667 [CommandExec](IMPORTANT): got command ibit
2023-11-13T16:47:08.740Z,1699894028.740 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-11-13T16:47:08.741Z,1699894028.741 [IBIT](IMPORTANT): Beginning control surface checks.
2023-11-13T16:47:08.744Z,1699894028.744 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T16:47:10.323Z,1699894030.323 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164709.00,A,3648.14023,N,12147.22953,W,0.156,135.41,131123,,,D*74
2023-11-13T16:47:10.325Z,1699894030.325 [NAL9602](INFO): GPS fix at 20231113T164709: (36.802337, -121.787159)
2023-11-13T16:47:11.619Z,1699894031.619 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T16:47:13.149Z,1699894033.149 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164712.00,A,3648.14037,N,12147.22956,W,0.019,135.41,131123,,,D*74
2023-11-13T16:47:13.152Z,1699894033.152 [NAL9602](INFO): GPS fix at 20231113T164712: (36.802340, -121.787159)
2023-11-13T16:47:15.977Z,1699894035.977 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164715.00,A,3648.14051,N,12147.22961,W,0.136,135.41,131123,,,D*7B
2023-11-13T16:47:15.979Z,1699894035.979 [NAL9602](INFO): GPS fix at 20231113T164715: (36.802342, -121.787160)
2023-11-13T16:47:17.741Z,1699894037.741 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231113T151353/Express0056.lzma
2023-11-13T16:47:18.744Z,1699894038.744 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0056.lzma.bak
2023-11-13T16:47:18.744Z,1699894038.744 [DataOverHttps](INFO): SBD MOMSN=19061708
2023-11-13T16:47:18.809Z,1699894038.809 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164718.00,A,3648.14071,N,12147.22974,W,0.253,135.41,131123,,,D*70
2023-11-13T16:47:18.821Z,1699894038.821 [NAL9602](INFO): GPS fix at 20231113T164718: (36.802345, -121.787162)
2023-11-13T16:47:19.804Z,1699894039.804 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T16:47:20.163Z,1699894040.163 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T16:47:22.037Z,1699894042.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164721.00,A,3648.14082,N,12147.22989,W,0.019,135.41,131123,,,D*78
2023-11-13T16:47:22.048Z,1699894042.048 [NAL9602](INFO): GPS fix at 20231113T164721: (36.802347, -121.787165)
2023-11-13T16:47:22.963Z,1699894042.963 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T16:47:24.869Z,1699894044.869 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164724.00,A,3648.14097,N,12147.22987,W,0.058,135.41,131123,,,D*72
2023-11-13T16:47:24.871Z,1699894044.871 [NAL9602](INFO): GPS fix at 20231113T164724: (36.802349, -121.787165)
2023-11-13T16:47:28.106Z,1699894048.106 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164727.00,A,3648.14105,N,12147.22974,W,0.058,135.41,131123,,,D*77
2023-11-13T16:47:28.108Z,1699894048.108 [NAL9602](INFO): GPS fix at 20231113T164727: (36.802351, -121.787162)
2023-11-13T16:47:30.929Z,1699894050.929 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164730.00,A,3648.14105,N,12147.22982,W,0.078,135.41,131123,,,D*7A
2023-11-13T16:47:30.935Z,1699894050.935 [NAL9602](INFO): GPS fix at 20231113T164730: (36.802351, -121.787164)
2023-11-13T16:47:31.019Z,1699894051.019 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-13T16:47:31.503Z,1699894051.503 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T16:47:34.157Z,1699894054.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164733.00,A,3648.14088,N,12147.22963,W,0.039,135.41,131123,,,D*77
2023-11-13T16:47:34.159Z,1699894054.159 [NAL9602](INFO): GPS fix at 20231113T164733: (36.802348, -121.787160)
2023-11-13T16:47:34.235Z,1699894054.235 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T16:47:35.281Z,1699894055.281 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231113T151353/Courier0058.lzma
2023-11-13T16:47:36.284Z,1699894056.284 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0058.lzma.bak
2023-11-13T16:47:36.284Z,1699894056.284 [DataOverHttps](INFO): SBD MOMSN=19061711
2023-11-13T16:47:36.985Z,1699894056.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164736.00,A,3648.14069,N,12147.22944,W,0.039,135.41,131123,,,D*78
2023-11-13T16:47:36.987Z,1699894056.987 [NAL9602](INFO): GPS fix at 20231113T164736: (36.802345, -121.787157)
2023-11-13T16:47:39.813Z,1699894059.813 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164739.00,A,3648.14040,N,12147.22922,W,0.019,135.41,131123,,,D*7E
2023-11-13T16:47:39.819Z,1699894059.819 [NAL9602](INFO): GPS fix at 20231113T164739: (36.802340, -121.787154)
2023-11-13T16:47:42.375Z,1699894062.375 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001970
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002609
CHAN A3 (5V): -0.001425
CHAN B0 (3.3V): 0.000069
CHAN B1 (3.15aV): 0.000096
CHAN B2 (3.15bV): -0.000287
CHAN B3 (GND): 0.000047
OPEN: -0.000406
Full Scale: +/- 1 mA
2023-11-13T16:47:43.045Z,1699894063.045 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164742.00,A,3648.14019,N,12147.22910,W,0.039,135.41,131123,,,D*7D
2023-11-13T16:47:43.047Z,1699894063.047 [NAL9602](INFO): GPS fix at 20231113T164742: (36.802337, -121.787152)
2023-11-13T16:47:45.883Z,1699894065.883 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164745.00,A,3648.14018,N,12147.22918,W,0.039,135.41,131123,,,D*73
2023-11-13T16:47:45.885Z,1699894065.885 [NAL9602](INFO): GPS fix at 20231113T164745: (36.802336, -121.787153)
2023-11-13T16:47:49.105Z,1699894069.105 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164748.00,A,3648.14023,N,12147.22927,W,0.019,135.41,131123,,,D*78
2023-11-13T16:47:49.107Z,1699894069.107 [NAL9602](INFO): GPS fix at 20231113T164748: (36.802337, -121.787154)
2023-11-13T16:47:51.938Z,1699894071.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164751.00,A,3648.14030,N,12147.22923,W,0.058,135.41,131123,,,D*73
2023-11-13T16:47:51.940Z,1699894071.940 [NAL9602](INFO): GPS fix at 20231113T164751: (36.802338, -121.787154)
2023-11-13T16:47:52.865Z,1699894072.865 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20231113T151353/Express0059.lzma
2023-11-13T16:47:53.868Z,1699894073.868 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Express0059.lzma.bak
2023-11-13T16:47:53.868Z,1699894073.868 [DataOverHttps](INFO): SBD MOMSN=19061713
2023-11-13T16:47:54.839Z,1699894074.839 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802338 Longitude: -121.787155
2023-11-13T16:47:55.166Z,1699894075.166 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164754.00,A,3648.14047,N,12147.22922,W,0.078,135.41,131123,,,D*75
2023-11-13T16:47:55.168Z,1699894075.168 [NAL9602](INFO): GPS fix at 20231113T164754: (36.802341, -121.787154)
2023-11-13T16:47:55.185Z,1699894075.185 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2023-11-13T16:47:55.185Z,1699894075.185 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-11-13T16:47:55.185Z,1699894075.185 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-11-13T16:47:55.607Z,1699894075.607 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-11-13T16:47:55.608Z,1699894075.608 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-11-13T16:47:55.608Z,1699894075.608 [IBIT](IMPORTANT): Pressure:9.699836 PSI
2023-11-13T16:47:55.608Z,1699894075.608 [IBIT](IMPORTANT): Humidity:12.538152 %
2023-11-13T16:47:55.992Z,1699894075.992 [IBIT](IMPORTANT): Vehicle Pitch:-0.612741 degrees
2023-11-13T16:47:55.992Z,1699894075.992 [IBIT](IMPORTANT): Vehicle  Roll:1.757456 degrees
2023-11-13T16:47:55.992Z,1699894075.992 [IBIT](IMPORTANT): Vehicle  Heading:28.201496 degrees
2023-11-13T16:47:56.400Z,1699894076.400 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-11-13T16:47:56.400Z,1699894076.400 [IBIT](IMPORTANT): buoyancyNeutral: 211.377075 cc
2023-11-13T16:47:56.400Z,1699894076.400 [IBIT](IMPORTANT): massDefault: -0.847723 cm
2023-11-13T16:47:56.401Z,1699894076.401 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-11-13T16:47:56.401Z,1699894076.401 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-11-13T16:47:56.401Z,1699894076.401 [IBIT](IMPORTANT): IBIT PASSED
2023-11-13T16:47:56.812Z,1699894076.812 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:47:56.812Z,1699894076.812 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-13T16:47:56.812Z,1699894076.812 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-13T16:48:27.488Z,1699894107.488 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:51:04.475Z,1699894264.475 [CommandExec](IMPORTANT): got command ibit
2023-11-13T16:51:04.746Z,1699894264.746 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-11-13T16:51:04.746Z,1699894264.746 [IBIT](IMPORTANT): Beginning control surface checks.
2023-11-13T16:51:04.749Z,1699894264.749 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-13T16:51:06.329Z,1699894266.329 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165105.00,A,3648.14019,N,12147.22857,W,0.019,135.41,131123,,,D*79
2023-11-13T16:51:06.331Z,1699894266.331 [NAL9602](INFO): GPS fix at 20231113T165105: (36.802337, -121.787143)
2023-11-13T16:51:07.651Z,1699894267.651 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-13T16:51:15.645Z,1699894275.645 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002928
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002123
CHAN A3 (5V): -0.001557
CHAN B0 (3.3V): 0.000066
CHAN B1 (3.15aV): -0.000585
CHAN B2 (3.15bV): -0.000282
CHAN B3 (GND): -0.000327
OPEN: -0.000515
Full Scale: +/- 1 mA
2023-11-13T16:51:19.656Z,1699894279.656 [NAL9602](INFO): SBD MO Status=0, MOMSN=45474, MT Status=0, MTMSN=0
2023-11-13T16:51:19.656Z,1699894279.656 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:51:20.873Z,1699894280.873 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165120.00,A,3648.13987,N,12147.22865,W,0.078,135.41,131123,,,D*71
2023-11-13T16:51:20.884Z,1699894280.884 [NAL9602](INFO): GPS fix at 20231113T165120: (36.802331, -121.787144)
2023-11-13T16:51:24.100Z,1699894284.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165123.00,A,3648.13973,N,12147.22860,W,0.039,135.41,131123,,,D*79
2023-11-13T16:51:24.113Z,1699894284.113 [NAL9602](INFO): GPS fix at 20231113T165123: (36.802329, -121.787143)
2023-11-13T16:51:26.928Z,1699894286.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165126.00,A,3648.13960,N,12147.22851,W,0.039,135.41,131123,,,D*7C
2023-11-13T16:51:26.931Z,1699894286.931 [NAL9602](INFO): GPS fix at 20231113T165126: (36.802327, -121.787142)
2023-11-13T16:51:30.160Z,1699894290.160 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165129.00,A,3648.13945,N,12147.22842,W,0.039,135.41,131123,,,D*76
2023-11-13T16:51:30.162Z,1699894290.162 [NAL9602](INFO): GPS fix at 20231113T165129: (36.802324, -121.787140)
2023-11-13T16:51:32.993Z,1699894292.993 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165132.00,A,3648.13931,N,12147.22827,W,0.097,135.41,131123,,,D*78
2023-11-13T16:51:32.000Z,1699894293.000 [NAL9602](INFO): GPS fix at 20231113T165132: (36.802322, -121.787138)
2023-11-13T16:51:35.821Z,1699894295.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165135.00,A,3648.13932,N,12147.22840,W,0.136,135.41,131123,,,D*77
2023-11-13T16:51:35.823Z,1699894295.823 [NAL9602](INFO): GPS fix at 20231113T165135: (36.802322, -121.787140)
2023-11-13T16:51:39.067Z,1699894299.067 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165138.00,A,3648.13934,N,12147.22852,W,0.175,135.41,131123,,,D*78
2023-11-13T16:51:39.069Z,1699894299.069 [NAL9602](INFO): GPS fix at 20231113T165138: (36.802322, -121.787142)
2023-11-13T16:51:41.877Z,1699894301.877 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165141.00,A,3648.13928,N,12147.22863,W,0.019,135.41,131123,,,D*72
2023-11-13T16:51:41.879Z,1699894301.879 [NAL9602](INFO): GPS fix at 20231113T165141: (36.802321, -121.787144)
2023-11-13T16:51:45.109Z,1699894305.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165144.00,A,3648.13920,N,12147.22862,W,0.019,135.41,131123,,,D*7E
2023-11-13T16:51:45.111Z,1699894305.111 [NAL9602](INFO): GPS fix at 20231113T165144: (36.802320, -121.787144)
2023-11-13T16:51:47.945Z,1699894307.945 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165147.00,A,3648.13911,N,12147.22858,W,0.097,135.41,131123,,,D*70
2023-11-13T16:51:47.947Z,1699894307.947 [NAL9602](INFO): GPS fix at 20231113T165147: (36.802318, -121.787143)
2023-11-13T16:51:51.178Z,1699894311.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165150.00,A,3648.13904,N,12147.22851,W,0.117,135.41,131123,,,D*72
2023-11-13T16:51:51.180Z,1699894311.180 [NAL9602](INFO): GPS fix at 20231113T165150: (36.802317, -121.787142)
2023-11-13T16:51:51.197Z,1699894311.197 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802319 Longitude: -121.787140
2023-11-13T16:51:51.628Z,1699894311.628 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2023-11-13T16:51:51.628Z,1699894311.628 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-11-13T16:51:51.628Z,1699894311.628 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-11-13T16:51:51.992Z,1699894311.992 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-11-13T16:51:51.992Z,1699894311.992 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-11-13T16:51:51.992Z,1699894311.992 [IBIT](IMPORTANT): Pressure:9.699836 PSI
2023-11-13T16:51:51.993Z,1699894311.993 [IBIT](IMPORTANT): Humidity:12.538152 %
2023-11-13T16:51:52.423Z,1699894312.423 [IBIT](IMPORTANT): Vehicle Pitch:-0.615782 degrees
2023-11-13T16:51:52.424Z,1699894312.424 [IBIT](IMPORTANT): Vehicle  Roll:1.755434 degrees
2023-11-13T16:51:52.424Z,1699894312.424 [IBIT](IMPORTANT): Vehicle  Heading:28.150515 degrees
2023-11-13T16:51:52.812Z,1699894312.812 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-11-13T16:51:52.812Z,1699894312.812 [IBIT](IMPORTANT): buoyancyNeutral: 211.377075 cc
2023-11-13T16:51:52.812Z,1699894312.812 [IBIT](IMPORTANT): massDefault: -0.847723 cm
2023-11-13T16:51:52.813Z,1699894312.813 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-11-13T16:51:52.813Z,1699894312.813 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-11-13T16:51:52.813Z,1699894312.813 [IBIT](IMPORTANT): IBIT PASSED
2023-11-13T16:52:23.492Z,1699894343.492 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-13T16:52:57.445Z,1699894377.445 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-13T16:52:57.450Z,1699894377.450 [Default:CheckIn:C.Wait] Stopped
2023-11-13T16:52:57.450Z,1699894377.450 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-13T16:52:57.450Z,1699894377.450 [Default:CheckIn:D] Running Loop=1
2023-11-13T16:52:57.846Z,1699894377.846 [Default:CheckIn:D] Stopped
2023-11-13T16:52:57.846Z,1699894377.846 [Default:CheckIn:E] Running Loop=1
2023-11-13T16:52:58.240Z,1699894378.240 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.644238 min 
2023-11-13T16:52:58.240Z,1699894378.240 [Default:CheckIn:E] Stopped
2023-11-13T16:52:58.240Z,1699894378.240 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-13T16:52:58.240Z,1699894378.240 [Default:CheckIn] Stopped
2023-11-13T16:52:58.240Z,1699894378.240 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:52:58.240Z,1699894378.240 [Default:CheckIn](INFO): Running loop #15
2023-11-13T16:52:58.240Z,1699894378.240 [Default:CheckIn] Running Loop=15
2023-11-13T16:52:58.240Z,1699894378.240 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-13T16:52:58.241Z,1699894378.241 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-13T16:53:00.257Z,1699894380.257 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165259.00,A,3648.14007,N,12147.22881,W,0.019,135.41,131123,,,D*77
2023-11-13T16:53:00.259Z,1699894380.259 [NAL9602](INFO): GPS fix at 20231113T165259: (36.802335, -121.787147)
2023-11-13T16:53:00.270Z,1699894380.270 [Default:CheckIn:Read_GPS] Stopped
2023-11-13T16:53:00.270Z,1699894380.270 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-13T16:53:07.457Z,1699894387.457 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231113T151353/Courier0061.lzma
2023-11-13T16:53:08.460Z,1699894388.460 [DataOverHttps](INFO): Moved sent file to Logs/20231113T151353/Courier0061.lzma.bak
2023-11-13T16:53:08.460Z,1699894388.460 [DataOverHttps](IMPORTANT): SBD MOMSN=19061728, MTMSN=20231113T165307
2023-11-13T16:53:15.893Z,1699894395.893 [DataOverHttps](INFO): Received command: restart app
2023-11-13T16:53:15.979Z,1699894395.979 [CommandExec](IMPORTANT): got command restart application
2023-11-13T16:53:16.817Z,1699894396.817 [NAL9602](INFO): SBD MO Status=0, MOMSN=45475, MT Status=0, MTMSN=0
2023-11-13T16:53:16.817Z,1699894396.817 [NAL9602](INFO): No messages in MT queue
2023-11-13T16:53:16.986Z,1699894396.986 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:16.986Z,1699894396.986 [CommandExec](INFO): Uninitializing the command executive.
2023-11-13T16:53:16.986Z,1699894396.986 [CommandExec](INFO): Uninitializing the command scheduler.
2023-11-13T16:53:16.987Z,1699894396.987 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:17.014Z,1699894397.014 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-11-13T16:53:17.014Z,1699894397.014 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-11-13T16:53:17.014Z,1699894397.014 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:17.015Z,1699894397.015 [NavChartDb](INFO): Join timeout helper Thread ID is 2457
2023-11-13T16:53:17.082Z,1699894397.082 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:17.082Z,1699894397.082 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:17.107Z,1699894397.107 [DataOverHttps](IMPORTANT): SBD MTMSN=20231113T165316
2023-11-13T16:53:17.114Z,1699894397.114 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-11-13T16:53:17.114Z,1699894397.114 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:17.115Z,1699894397.115 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2458
2023-11-13T16:53:17.462Z,1699894397.462 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:17.462Z,1699894397.462 [WetLabsBB2FL](INFO): Powering down
2023-11-13T16:53:17.463Z,1699894397.463 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:17.474Z,1699894397.474 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-11-13T16:53:17.474Z,1699894397.474 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:17.474Z,1699894397.474 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2459
2023-11-13T16:53:18.110Z,1699894398.110 [CTD_Seabird](INFO): Powering down
2023-11-13T16:53:18.122Z,1699894398.122 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:18.122Z,1699894398.122 [CTD_Seabird](INFO): Powering down
2023-11-13T16:53:18.134Z,1699894398.134 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:18.146Z,1699894398.146 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-11-13T16:53:18.146Z,1699894398.146 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:18.146Z,1699894398.146 [Radio_Surface](INFO): Join timeout helper Thread ID is 2460
2023-11-13T16:53:18.246Z,1699894398.246 [Radio_Surface](INFO): Powering down
2023-11-13T16:53:18.247Z,1699894398.247 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:18.247Z,1699894398.247 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:18.254Z,1699894398.254 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-11-13T16:53:18.254Z,1699894398.254 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:18.254Z,1699894398.254 [Onboard](INFO): Join timeout helper Thread ID is 2461
2023-11-13T16:53:19.642Z,1699894399.642 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:19.642Z,1699894399.642 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:19.659Z,1699894399.659 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-11-13T16:53:19.659Z,1699894399.659 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:19.659Z,1699894399.659 [DataOverHttps](INFO): Join timeout helper Thread ID is 2462
2023-11-13T16:53:19.906Z,1699894399.906 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:19.907Z,1699894399.907 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:19.922Z,1699894399.922 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-11-13T16:53:19.923Z,1699894399.923 [logger ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:19.923Z,1699894399.923 [logger](INFO): Join timeout helper Thread ID is 2463
2023-11-13T16:53:19.938Z,1699894399.938 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:19.938Z,1699894399.938 [logger ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:19.943Z,1699894399.943 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-11-13T16:53:19.943Z,1699894399.943 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:19.943Z,1699894399.943 [CommandLine](INFO): Join timeout helper Thread ID is 2464
2023-11-13T16:53:20.042Z,1699894400.042 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:20.042Z,1699894400.042 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.051Z,1699894400.051 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-11-13T16:53:20.051Z,1699894400.051 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.051Z,1699894400.051 [CommandExec](INFO): Join timeout helper Thread ID is 2465
2023-11-13T16:53:20.063Z,1699894400.063 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-11-13T16:53:20.063Z,1699894400.063 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.063Z,1699894400.063 [controlThread](INFO): Join timeout helper Thread ID is 2466
2023-11-13T16:53:20.070Z,1699894400.070 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-13T16:53:20.071Z,1699894400.071 [controlThread](DEBUG): Uninitializing ControlThread
2023-11-13T16:53:20.071Z,1699894400.071 [AHRS_M2](INFO): Powering down
2023-11-13T16:53:20.143Z,1699894400.143 [NAL9602](INFO): Powering down
2023-11-13T16:53:20.144Z,1699894400.144 [Sonardyne_Nano](INFO): Powering down
2023-11-13T16:53:20.263Z,1699894400.263 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-11-13T16:53:20.264Z,1699894400.264 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-11-13T16:53:20.265Z,1699894400.265 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-11-13T16:53:20.265Z,1699894400.265 [MissionManager](INFO): Uninitializing Mission Default
2023-11-13T16:53:20.265Z,1699894400.265 [Default] Stopped
2023-11-13T16:53:20.265Z,1699894400.265 [Default](DEBUG): Aggregate::uninitialize Default
2023-11-13T16:53:20.265Z,1699894400.265 [Default:B.GoToSurface] Stopped
2023-11-13T16:53:20.265Z,1699894400.265 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-13T16:53:20.266Z,1699894400.266 [Default:CheckIn] Stopped
2023-11-13T16:53:20.266Z,1699894400.266 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-13T16:53:20.266Z,1699894400.266 [Default:CheckIn:Read_Iridium] Stopped
2023-11-13T16:53:20.268Z,1699894400.268 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-11-13T16:53:20.269Z,1699894400.269 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-11-13T16:53:20.269Z,1699894400.269 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-11-13T16:53:20.269Z,1699894400.269 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-11-13T16:53:20.270Z,1699894400.270 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-11-13T16:53:20.270Z,1699894400.270 [BuoyancyServo](INFO): Powering down
2023-11-13T16:53:20.282Z,1699894400.282 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-11-13T16:53:20.282Z,1699894400.282 [ElevatorServo](INFO): Powering down
2023-11-13T16:53:20.283Z,1699894400.283 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-11-13T16:53:20.283Z,1699894400.283 [MassServo](INFO): Powering down
2023-11-13T16:53:20.284Z,1699894400.284 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-11-13T16:53:20.284Z,1699894400.284 [RudderServo](INFO): Powering down
2023-11-13T16:53:20.285Z,1699894400.285 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-11-13T16:53:20.285Z,1699894400.285 [ThrusterHE](INFO): Powering down
2023-11-13T16:53:20.286Z,1699894400.286 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-11-13T16:53:20.286Z,1699894400.286 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-11-13T16:53:20.286Z,1699894400.286 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-11-13T16:53:20.287Z,1699894400.287 [CBIT](DEBUG): Powering off loads.
2023-11-13T16:53:20.298Z,1699894400.298 [CBIT](DEBUG): Disabling WDT.
2023-11-13T16:53:20.310Z,1699894400.310 [CBIT](DEBUG): Opening all GF detection circuits.
2023-11-13T16:53:20.311Z,1699894400.311 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.368Z,1699894400.368 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.371Z,1699894400.371 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.377Z,1699894400.377 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.448Z,1699894400.448 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.452Z,1699894400.452 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.493Z,1699894400.493 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-13T16:53:20.565Z,1699894400.565 [logger ThreadHandler](INFO): Thread cancelled.