2025-07-17T03:20:46.650Z,1752722446.650 [Supervisor](DEBUG): Initializing supervisor.
2025-07-17T03:20:46.654Z,1752722446.654 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-07-17T03:20:46.655Z,1752722446.655 [SyncHandler](INFO): Protected caller Thread ID is 836
2025-07-17T03:20:46.655Z,1752722446.655 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-07-17T03:20:46.656Z,1752722446.656 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-07-17T03:20:46.657Z,1752722446.657 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2025-07-17T03:20:46.661Z,1752722446.661 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-07-17T03:20:46.681Z,1752722446.681 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-07-17T03:20:46.682Z,1752722446.682 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-07-17T03:20:46.682Z,1752722446.682 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2025-07-17T03:20:46.687Z,1752722446.687 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-07-17T03:20:46.688Z,1752722446.688 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-07-17T03:20:46.688Z,1752722446.688 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2025-07-17T03:20:46.691Z,1752722446.691 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-07-17T03:20:46.691Z,1752722446.691 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-07-17T03:20:46.692Z,1752722446.692 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2025-07-17T03:20:46.696Z,1752722446.696 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-07-17T03:20:46.696Z,1752722446.696 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-07-17T03:20:46.700Z,1752722446.700 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-07-17T03:20:47.202Z,1752722447.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-07-17T03:20:47.203Z,1752722447.203 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-07-17T03:20:47.428Z,1752722447.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-07-17T03:20:47.430Z,1752722447.430 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-07-17T03:20:47.530Z,1752722447.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-07-17T03:20:47.532Z,1752722447.532 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-07-17T03:20:47.779Z,1752722447.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-07-17T03:20:47.780Z,1752722447.780 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-07-17T03:20:47.871Z,1752722447.871 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-07-17T03:20:47.977Z,1752722447.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-07-17T03:20:47.978Z,1752722447.978 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-07-17T03:20:48.351Z,1752722448.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-07-17T03:20:48.351Z,1752722448.351 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-07-17T03:20:48.510Z,1752722448.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-07-17T03:20:48.511Z,1752722448.511 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-07-17T03:20:49.065Z,1752722449.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-07-17T03:20:49.067Z,1752722449.067 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-07-17T03:20:49.260Z,1752722449.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-07-17T03:20:49.261Z,1752722449.261 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-07-17T03:20:49.696Z,1752722449.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-07-17T03:20:49.697Z,1752722449.697 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-07-17T03:20:50.040Z,1752722450.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-07-17T03:20:50.041Z,1752722450.041 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-07-17T03:20:50.851Z,1752722450.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-07-17T03:20:50.852Z,1752722450.852 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-07-17T03:20:51.819Z,1752722451.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-07-17T03:20:51.821Z,1752722451.821 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-07-17T03:20:52.193Z,1752722452.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-07-17T03:20:52.194Z,1752722452.194 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-07-17T03:20:52.451Z,1752722452.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-07-17T03:20:52.453Z,1752722452.453 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2025-07-17T03:20:52.456Z,1752722452.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2025-07-17T03:20:52.586Z,1752722452.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2025-07-17T03:20:52.681Z,1752722452.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2025-07-17T03:20:52.828Z,1752722452.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2025-07-17T03:20:52.923Z,1752722452.923 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2025-07-17T03:20:53.206Z,1752722453.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-07-17T03:20:53.208Z,1752722453.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2025-07-17T03:20:53.323Z,1752722453.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2025-07-17T03:20:53.440Z,1752722453.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2025-07-17T03:20:53.550Z,1752722453.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2025-07-17T03:20:53.713Z,1752722453.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2025-07-17T03:20:53.821Z,1752722453.821 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2025-07-17T03:20:53.917Z,1752722453.917 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-07-17T03:20:53.920Z,1752722453.920 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-07-17T03:20:53.937Z,1752722453.937 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-07-17T03:20:53.939Z,1752722453.939 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-07-17T03:20:54.145Z,1752722454.145 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-07-17T03:20:54.147Z,1752722454.147 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-07-17T03:20:54.224Z,1752722454.224 [VerticalControl](DEBUG): Construct VerticalControl.
2025-07-17T03:20:54.288Z,1752722454.288 [VerticalControl] Loaded
2025-07-17T03:20:54.289Z,1752722454.289 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-07-17T03:20:54.292Z,1752722454.292 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-07-17T03:20:54.341Z,1752722454.341 [HorizontalControl] Loaded
2025-07-17T03:20:54.342Z,1752722454.342 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-07-17T03:20:54.344Z,1752722454.344 [SpeedControl](DEBUG): Construct SpeedControl.
2025-07-17T03:20:54.347Z,1752722454.347 [SpeedControl] Loaded
2025-07-17T03:20:54.348Z,1752722454.348 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-07-17T03:20:54.350Z,1752722454.350 [LoopControl](DEBUG): Construct LoopControl.
2025-07-17T03:20:54.351Z,1752722454.351 [LoopControl] Loaded
2025-07-17T03:20:54.351Z,1752722454.351 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-07-17T03:20:54.352Z,1752722454.352 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-07-17T03:20:54.352Z,1752722454.352 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-07-17T03:20:54.564Z,1752722454.564 [BuoyancyServo] Loaded
2025-07-17T03:20:54.564Z,1752722454.564 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-07-17T03:20:54.588Z,1752722454.588 [ElevatorServo] Loaded
2025-07-17T03:20:54.588Z,1752722454.588 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-07-17T03:20:54.610Z,1752722454.610 [MassServo] Loaded
2025-07-17T03:20:54.610Z,1752722454.610 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-07-17T03:20:54.632Z,1752722454.632 [RudderServo] Loaded
2025-07-17T03:20:54.632Z,1752722454.632 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-07-17T03:20:54.650Z,1752722454.650 [ThrusterHE] Loaded
2025-07-17T03:20:54.650Z,1752722454.650 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-07-17T03:20:54.650Z,1752722454.650 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-07-17T03:20:54.651Z,1752722454.651 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-07-17T03:20:54.727Z,1752722454.727 [DepthRateCalculator] Loaded
2025-07-17T03:20:54.728Z,1752722454.728 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-07-17T03:20:54.733Z,1752722454.733 [PitchRateCalculator] Loaded
2025-07-17T03:20:54.733Z,1752722454.733 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-07-17T03:20:54.743Z,1752722454.743 [SpeedCalculator] Loaded
2025-07-17T03:20:54.744Z,1752722454.744 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-07-17T03:20:54.748Z,1752722454.748 [YawRateCalculator] Loaded
2025-07-17T03:20:54.748Z,1752722454.748 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-07-17T03:20:54.768Z,1752722454.768 [ElevatorOffsetCalculator] Loaded
2025-07-17T03:20:54.768Z,1752722454.768 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-07-17T03:20:54.769Z,1752722454.769 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-07-17T03:20:54.770Z,1752722454.770 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-07-17T03:20:54.916Z,1752722454.916 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-07-17T03:20:54.917Z,1752722454.917 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-07-17T03:20:54.931Z,1752722454.931 [NavChart] Loaded
2025-07-17T03:20:54.932Z,1752722454.932 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-07-17T03:20:54.938Z,1752722454.938 [UniversalFixResidualReporter] Loaded
2025-07-17T03:20:54.938Z,1752722454.938 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-07-17T03:20:54.938Z,1752722454.938 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-07-17T03:20:54.939Z,1752722454.939 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-07-17T03:20:56.377Z,1752722456.377 [AHRS_M2] Loaded
2025-07-17T03:20:56.377Z,1752722456.377 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-07-17T03:20:56.632Z,1752722456.632 [BackseatComponent] Loaded
2025-07-17T03:20:56.632Z,1752722456.632 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-07-17T03:20:56.633Z,1752722456.633 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409294E0
2025-07-17T03:20:56.634Z,1752722456.634 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922
2025-07-17T03:20:56.637Z,1752722456.637 [LcmUniversalReporter] Loaded
2025-07-17T03:20:56.637Z,1752722456.637 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-07-17T03:20:56.643Z,1752722456.643 [BioacousticsDataBridge] Loaded
2025-07-17T03:20:56.644Z,1752722456.644 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-07-17T03:20:57.523Z,1752722457.523 [BPC1] Loaded
2025-07-17T03:20:57.523Z,1752722457.523 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-07-17T03:20:57.626Z,1752722457.626 [DAT] Loaded
2025-07-17T03:20:57.626Z,1752722457.626 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-07-17T03:20:57.627Z,1752722457.627 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409594E0
2025-07-17T03:20:57.628Z,1752722457.628 [DAT ThreadHandler](INFO): Protected caller Thread ID is 923
2025-07-17T03:20:57.773Z,1752722457.773 [DataOverHttps] Loaded
2025-07-17T03:20:57.773Z,1752722457.773 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-07-17T03:20:57.775Z,1752722457.775 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2025-07-17T03:20:57.775Z,1752722457.775 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2025-07-17T03:20:57.800Z,1752722457.800 [Depth_Keller] Loaded
2025-07-17T03:20:57.800Z,1752722457.800 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-07-17T03:20:57.806Z,1752722457.806 [DropWeight] Loaded
2025-07-17T03:20:57.806Z,1752722457.806 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-07-17T03:20:57.873Z,1752722457.873 [NAL9602] Loaded
2025-07-17T03:20:57.873Z,1752722457.873 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-07-17T03:20:57.905Z,1752722457.905 [Onboard] Loaded
2025-07-17T03:20:57.905Z,1752722457.905 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-07-17T03:20:57.906Z,1752722457.906 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2025-07-17T03:20:57.906Z,1752722457.906 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2025-07-17T03:20:57.921Z,1752722457.921 [Power24vConverter] Loaded
2025-07-17T03:20:57.922Z,1752722457.922 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-07-17T03:20:57.940Z,1752722457.940 [Radio_Surface] Loaded
2025-07-17T03:20:57.940Z,1752722457.940 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-07-17T03:20:57.941Z,1752722457.941 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0
2025-07-17T03:20:57.942Z,1752722457.942 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2025-07-17T03:20:57.957Z,1752722457.957 [Sonardyne_Nano] Loaded
2025-07-17T03:20:57.957Z,1752722457.957 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-07-17T03:20:57.982Z,1752722457.982 [Waterlinked] Loaded
2025-07-17T03:20:57.982Z,1752722457.982 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-07-17T03:20:57.982Z,1752722457.982 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-07-17T03:20:57.983Z,1752722457.983 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-07-17T03:20:58.074Z,1752722458.074 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-07-17T03:20:58.075Z,1752722458.075 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-07-17T03:20:58.309Z,1752722458.309 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-07-17T03:20:58.309Z,1752722458.309 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-07-17T03:20:58.359Z,1752722458.359 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-07-17T03:20:58.359Z,1752722458.359 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-07-17T03:20:58.594Z,1752722458.594 [CTD_Seabird] Loaded
2025-07-17T03:20:58.594Z,1752722458.594 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-07-17T03:20:58.595Z,1752722458.595 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B314E0
2025-07-17T03:20:58.595Z,1752722458.595 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2025-07-17T03:20:58.619Z,1752722458.619 [PAR_Licor] Loaded
2025-07-17T03:20:58.619Z,1752722458.619 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-07-17T03:20:58.666Z,1752722458.666 [WetLabsBB2FL] Loaded
2025-07-17T03:20:58.667Z,1752722458.667 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-07-17T03:20:58.668Z,1752722458.668 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B614E0
2025-07-17T03:20:58.668Z,1752722458.668 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2025-07-17T03:20:58.669Z,1752722458.669 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-07-17T03:20:58.670Z,1752722458.670 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-07-17T03:20:59.099Z,1752722459.099 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-07-17T03:20:59.100Z,1752722459.100 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-07-17T03:20:59.310Z,1752722459.310 [SBIT](DEBUG): Construct Startup Built In Test.
2025-07-17T03:20:59.319Z,1752722459.319 [SBIT] Loaded
2025-07-17T03:20:59.320Z,1752722459.320 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-07-17T03:20:59.323Z,1752722459.323 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-07-17T03:20:59.336Z,1752722459.336 [IBIT] Loaded
2025-07-17T03:20:59.337Z,1752722459.337 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-07-17T03:20:59.342Z,1752722459.342 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-07-17T03:20:59.427Z,1752722459.427 [CBIT] Loaded
2025-07-17T03:20:59.427Z,1752722459.427 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-07-17T03:20:59.462Z,1752722459.462 [GFScanner] Loaded
2025-07-17T03:20:59.462Z,1752722459.462 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-07-17T03:20:59.463Z,1752722459.463 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-07-17T03:20:59.469Z,1752722459.469 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-07-17T03:20:59.472Z,1752722459.472 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-07-17T03:20:59.484Z,1752722459.484 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-07-17T03:20:59.485Z,1752722459.485 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2025-07-17T03:20:59.485Z,1752722459.485 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2025-07-17T03:20:59.490Z,1752722459.490 [Supervisor](INFO): Main Thread ID is 829
2025-07-17T03:20:59.490Z,1752722459.490 [Supervisor](DEBUG): Running supervisor.
2025-07-17T03:20:59.491Z,1752722459.491 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2025-07-17T03:20:59.491Z,1752722459.491 [CommandExec](INFO): Initializing the command executive.
2025-07-17T03:20:59.493Z,1752722459.493 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2025-07-17T03:20:59.495Z,1752722459.495 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2025-07-17T03:20:59.495Z,1752722459.495 [controlThread](DEBUG): Initializing ControlThread
2025-07-17T03:20:59.496Z,1752722459.496 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-07-17T03:20:59.498Z,1752722459.498 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-07-17T03:20:59.498Z,1752722459.498 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-07-17T03:20:59.499Z,1752722459.499 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-07-17T03:20:59.500Z,1752722459.500 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-07-17T03:20:59.501Z,1752722459.501 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-07-17T03:20:59.501Z,1752722459.501 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-07-17T03:20:59.501Z,1752722459.501 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-07-17T03:20:59.502Z,1752722459.502 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-07-17T03:20:59.503Z,1752722459.503 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-07-17T03:20:59.503Z,1752722459.503 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-07-17T03:20:59.508Z,1752722459.508 [SBIT](INFO): Initialize SBIT Component.
2025-07-17T03:20:59.509Z,1752722459.509 [SBIT](IMPORTANT): git: 2025-07-16
2025-07-17T03:20:59.509Z,1752722459.509 [SBIT](INFO): git hash: d69d2715ddd00fa751675e8858faf22d094d5d81
2025-07-17T03:20:59.509Z,1752722459.509 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-07-17T03:20:59.510Z,1752722459.510 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-07-17T03:20:59.511Z,1752722459.511 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2025-07-17T03:20:59.512Z,1752722459.512 [IBIT](INFO): Initialize IBIT Component.
2025-07-17T03:20:59.513Z,1752722459.513 [CBIT](DEBUG): Initialize CBIT Component.
2025-07-17T03:20:59.514Z,1752722459.514 [logger ThreadHandler](INFO): Handler Thread ID is 933
2025-07-17T03:20:59.524Z,1752722459.524 [CBIT](DEBUG): Initialized mux pins.
2025-07-17T03:20:59.524Z,1752722459.524 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-07-17T03:20:59.524Z,1752722459.524 [CBIT](DEBUG): Initializing the watchdog timer.
2025-07-17T03:20:59.532Z,1752722459.532 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2025-07-17T03:20:59.544Z,1752722459.544 [DAT ThreadHandler](INFO): Handler Thread ID is 935
2025-07-17T03:20:59.545Z,1752722459.545 [DAT](INFO): Powering up
2025-07-17T03:20:59.545Z,1752722459.545 [DAT](DEBUG): Initializing DAT.
2025-07-17T03:20:59.548Z,1752722459.548 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-07-17T03:20:59.548Z,1752722459.548 [CBIT](DEBUG): Initializing heartbeat.
2025-07-17T03:20:59.550Z,1752722459.550 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937
2025-07-17T03:20:59.552Z,1752722459.552 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-07-17T03:20:59.561Z,1752722459.561 [Onboard ThreadHandler](INFO): Handler Thread ID is 938
2025-07-17T03:20:59.590Z,1752722459.590 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939
2025-07-17T03:20:59.604Z,1752722459.604 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940
2025-07-17T03:20:59.605Z,1752722459.605 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-07-17T03:20:59.609Z,1752722459.609 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 942
2025-07-17T03:20:59.612Z,1752722459.612 [WetLabsBB2FL](INFO): Powering up
2025-07-17T03:20:59.614Z,1752722459.614 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2025-07-17T03:20:59.620Z,1752722459.620 [CBIT](DEBUG): Deactivating emergency mode.
2025-07-17T03:20:59.622Z,1752722459.622 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2025-07-17T03:20:59.622Z,1752722459.622 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2025-07-17T03:20:59.622Z,1752722459.622 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2025-07-17T03:20:59.623Z,1752722459.623 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2025-07-17T03:20:59.623Z,1752722459.623 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2025-07-17T03:20:59.623Z,1752722459.623 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2025-07-17T03:20:59.623Z,1752722459.623 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2025-07-17T03:20:59.623Z,1752722459.623 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2025-07-17T03:20:59.623Z,1752722459.623 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2025-07-17T03:20:59.624Z,1752722459.624 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2025-07-17T03:20:59.624Z,1752722459.624 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2025-07-17T03:20:59.624Z,1752722459.624 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2025-07-17T03:20:59.624Z,1752722459.624 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2025-07-17T03:20:59.624Z,1752722459.624 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2025-07-17T03:20:59.625Z,1752722459.625 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2025-07-17T03:20:59.625Z,1752722459.625 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2025-07-17T03:20:59.625Z,1752722459.625 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2025-07-17T03:20:59.625Z,1752722459.625 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2025-07-17T03:20:59.625Z,1752722459.625 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2025-07-17T03:20:59.625Z,1752722459.625 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2025-07-17T03:20:59.660Z,1752722459.660 [CBIT](DEBUG): Backplane powered.
2025-07-17T03:20:59.660Z,1752722459.660 [GFScanner](DEBUG): Initializing GFScanner
2025-07-17T03:20:59.660Z,1752722459.660 [GFScanner](DEBUG): Deactivating GF circuits.
2025-07-17T03:20:59.665Z,1752722459.665 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-07-17T03:20:59.666Z,1752722459.666 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-07-17T03:20:59.666Z,1752722459.666 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-07-17T03:20:59.676Z,1752722459.676 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-07-17T03:20:59.727Z,1752722459.727 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission 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>

2025-07-17T03:20:59.756Z,1752722459.756 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-17T03:20:59.768Z,1752722459.768 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Startup {
  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate StartupSatComms {
    run in sequence

    readDatum {
      timeout duration=P1M

      Universal:latitude_fix
    }

    readDatum {
      timeout duration=P1M

      Universal:platform_communications
    }
  }
}


2025-07-17T03:20:59.769Z,1752722459.769 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-07-17T03:20:59.769Z,1752722459.769 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-07-17T03:20:59.772Z,1752722459.772 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-07-17T03:20:59.992Z,1752722459.992 [Radio_Surface](INFO): Powering up
2025-07-17T03:21:00.085Z,1752722460.085 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <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>

2025-07-17T03:21:00.111Z,1752722460.111 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-07-17T03:21:00.124Z,1752722460.124 [Default:A.Wait](DEBUG): Construct Wait.
2025-07-17T03:21:00.126Z,1752722460.126 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-17T03:21:00.155Z,1752722460.155 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-07-17T03:21:00.174Z,1752722460.174 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-07-17T03:21:00.200Z,1752722460.200 [Default:E.Execute](DEBUG): Construct Execute.
2025-07-17T03:21:00.203Z,1752722460.203 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Default {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

  behavior Guidance:Wait {
    """
    Wait a moment to see if the scheduler starts a new mission before
    starting to actually run Default.
    """

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P4H {
        behavior Guidance:Execute {
          run in sequence

          set command = "Burn on"
        }

        syslog critical "Dropped weight due to communications timeout."
      }

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

    syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
  }

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2025-07-17T03:21:00.208Z,1752722460.208 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,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,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-07-17T03:21:00.216Z,1752722460.216 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-07-17T03:21:00.364Z,1752722460.364 [Depth_Keller](INFO): Initializing.
2025-07-17T03:21:00.366Z,1752722460.366 [Power24vConverter](INFO): Powering up.
2025-07-17T03:21:00.366Z,1752722460.366 [Sonardyne_Nano](INFO): Initializing.
2025-07-17T03:21:00.409Z,1752722460.409 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-07-17T03:21:00.435Z,1752722460.435 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-07-17T03:21:00.444Z,1752722460.444 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-07-17T03:21:00.445Z,1752722460.445 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-17T03:21:00.452Z,1752722460.452 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-17T03:21:00.453Z,1752722460.453 [MassServo](DEBUG): Initializing EZServoServo.
2025-07-17T03:21:00.460Z,1752722460.460 [MassServo](DEBUG): Initializing MassServo.
2025-07-17T03:21:00.461Z,1752722460.461 [RudderServo](DEBUG): Initializing EZServoServo.
2025-07-17T03:21:00.468Z,1752722460.468 [RudderServo](DEBUG): Initializing RudderServo.
2025-07-17T03:21:00.469Z,1752722460.469 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-07-17T03:21:00.476Z,1752722460.476 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-07-17T03:21:00.673Z,1752722460.673 [Depth_Keller](ERROR): Pressure or depth reading out of range: 963.011658 decibar, 0.000000 m
2025-07-17T03:21:00.728Z,1752722460.728 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-07-17T03:21:02.224Z,1752722462.224 [WetLabsBB2FL](INFO): Powering down
2025-07-17T03:21:02.642Z,1752722462.642 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-07-17T03:21:03.885Z,1752722463.885 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2025-07-17T03:21:06.052Z,1752722466.052 [Radio_Surface](INFO): Powering down
2025-07-17T03:21:06.141Z,1752722466.141 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-07-17T03:21:06.640Z,1752722466.640 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-07-17T03:21:11.892Z,1752722471.892 [DAT](INFO): DAT read: 

2025-07-17T03:21:11.894Z,1752722471.894 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-07-17T03:21:13.657Z,1752722473.657 [DAT](INFO): DAT read: MF Frequency Band

2025-07-17T03:21:13.658Z,1752722473.658 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-07-17T03:21:13.659Z,1752722473.659 [DAT](INFO): DAT read: Jul 17 2025 03:21:07 

2025-07-17T03:21:14.917Z,1752722474.917 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-07-17T03:21:14.918Z,1752722474.918 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-07-17T03:21:14.919Z,1752722474.919 [DAT](INFO): commRate: 800
2025-07-17T03:21:16.984Z,1752722476.984 [DAT](INFO): entering command mode
2025-07-17T03:21:17.184Z,1752722477.184 [DAT](INFO): DAT read: 

2025-07-17T03:21:17.185Z,1752722477.185 [DAT](INFO): DAT read: user:1>

2025-07-17T03:21:17.185Z,1752722477.185 [DAT](INFO): setting verbose to 3
2025-07-17T03:21:17.437Z,1752722477.437 [DAT](INFO): DAT read: user:1>

2025-07-17T03:21:17.438Z,1752722477.438 [DAT](INFO): DAT read: Verbose         | 3           

2025-07-17T03:21:17.438Z,1752722477.438 [DAT](INFO): set verbose to 3
2025-07-17T03:21:17.438Z,1752722477.438 [DAT](INFO): setting DatVerbose to 27440
2025-07-17T03:21:17.689Z,1752722477.689 [DAT](INFO): DAT read: user:2>

2025-07-17T03:21:17.690Z,1752722477.690 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-07-17T03:21:17.690Z,1752722477.690 [DAT](INFO): set DatVerbose to 27440
2025-07-17T03:21:17.690Z,1752722477.690 [DAT](INFO): setting transmit power to 8
2025-07-17T03:21:17.941Z,1752722477.941 [DAT](INFO): DAT read: user:3>

2025-07-17T03:21:17.942Z,1752722477.942 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-07-17T03:21:17.942Z,1752722477.942 [DAT](INFO): set transmit power to 8
2025-07-17T03:21:17.942Z,1752722477.942 [DAT](INFO): setting local address to 12
2025-07-17T03:21:18.193Z,1752722478.193 [DAT](INFO): DAT read: user:4>

2025-07-17T03:21:18.194Z,1752722478.194 [DAT](INFO): DAT read: LocalAddr       | 12          

2025-07-17T03:21:18.194Z,1752722478.194 [DAT](INFO): set local address to 12
2025-07-17T03:21:18.195Z,1752722478.195 [DAT](INFO): Setting time to: 3:21:18 And date to:7/17/2025
2025-07-17T03:21:18.445Z,1752722478.445 [DAT](INFO): DAT read: user:5>

2025-07-17T03:21:18.446Z,1752722478.446 [DAT](INFO): DAT read: Thu Jul 17, 2025  03:21:18  

2025-07-17T03:21:18.446Z,1752722478.446 [DAT](INFO): Local DAT time set to Thu Jul 17, 2025  03:21:18  

2025-07-17T03:21:20.090Z,1752722480.090 [SBIT](IMPORTANT): Beginning Startup BIT
2025-07-17T03:21:20.095Z,1752722480.095 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-17T03:21:24.638Z,1752722484.638 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.408848
CHAN A1 (24V): 0.459478
CHAN A2 (12V): 0.211468
CHAN A3 (5V): 0.055716
CHAN B0 (3.3V): 0.035685
CHAN B1 (3.15aV): 0.061166
CHAN B2 (3.15bV): 0.060223
CHAN B3 (GND): -0.005477
OPEN: 0.012690
Full Scale: +/- 1 mA
2025-07-17T03:21:28.198Z,1752722488.198 [NAL9602](INFO): Powering up NAL9602
2025-07-17T03:21:39.182Z,1752722499.182 [NAL9602](INFO): NAL9602 initialized
2025-07-17T03:22:13.514Z,1752722533.514 [SBIT](IMPORTANT): SBIT PASSED
2025-07-17T03:22:13.514Z,1752722533.514 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-07-17T03:22:13.877Z,1752722533.877 [MissionManager](IMPORTANT): Started mission Startup
2025-07-17T03:22:13.877Z,1752722533.877 [Startup] Running Loop=1
2025-07-17T03:22:13.878Z,1752722533.878 [Startup](DEBUG): Aggregate::initialize Startup
2025-07-17T03:22:13.878Z,1752722533.878 [Startup:A.GoToSurface] Running Loop=1
2025-07-17T03:22:13.878Z,1752722533.878 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-17T03:22:13.878Z,1752722533.878 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-17T03:22:13.879Z,1752722533.879 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-17T03:22:13.879Z,1752722533.879 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-17T03:22:13.879Z,1752722533.879 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-17T03:22:13.904Z,1752722533.904 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-17T03:22:13.905Z,1752722533.905 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-17T03:22:14.290Z,1752722534.290 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -30.00 mm (1 active estimators).
2025-07-17T03:22:14.356Z,1752722534.356 [Radio_Surface](INFO): Powering up
2025-07-17T03:23:39.028Z,1752722619.028 [Startup:StartupSatComms] Running Loop=1
2025-07-17T03:23:39.028Z,1752722619.028 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-07-17T03:23:39.028Z,1752722619.028 [Startup:StartupSatComms:A] Running Loop=1
2025-07-17T03:23:39.476Z,1752722619.476 [Waterlinked](INFO): Powering down
2025-07-17T03:23:39.491Z,1752722619.491 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-07-17T03:23:59.627Z,1752722639.627 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-07-17T03:23:59.627Z,1752722639.627 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T03:23:59.692Z,1752722639.692 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T03:24:00.104Z,1752722640.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T03:24:00.104Z,1752722640.104 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-07-17T03:24:16.175Z,1752722656.175 [NAL9602](INFO): SBD MO Status=0, MOMSN=9880, MT Status=0, MTMSN=0
2025-07-17T03:24:16.175Z,1752722656.175 [NAL9602](INFO): No messages in MT queue
2025-07-17T03:24:32.739Z,1752722672.739 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,032430.00,A,3648.19798,N,12150.94920,W,0.194,0.00,170725,,,A*76
2025-07-17T03:24:32.742Z,1752722672.742 [NAL9602](INFO): GPS fix at 20250717T032430: (36.803300, -121.849153)
2025-07-17T03:24:32.754Z,1752722672.754 [Startup:StartupSatComms:A] Stopped
2025-07-17T03:24:32.754Z,1752722672.754 [Startup:StartupSatComms:B] Running Loop=1
2025-07-17T03:24:33.180Z,1752722673.180 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-17T03:24:45.738Z,1752722685.738 [NAL9602](INFO): SBD MO Status=1, MOMSN=9881, MT Status=0, MTMSN=0
2025-07-17T03:24:45.788Z,1752722685.788 [NAL9602](INFO): Sent 18 bytes from file Logs/20250717T024800/Courier0019.lzma
2025-07-17T03:24:45.788Z,1752722685.788 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:24:49.461Z,1752722689.461 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:24:49.467Z,1752722689.467 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:25:00.371Z,1752722700.371 [NAL9602](INFO): SBD MO Status=2, MOMSN=9882, MT Status=2, MTMSN=0
2025-07-17T03:25:00.371Z,1752722700.371 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:25:04.004Z,1752722704.004 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:25:04.844Z,1752722704.844 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:25:19.136Z,1752722719.136 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:25:19.956Z,1752722719.956 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:25:22.782Z,1752722722.782 [NAL9602](INFO): SBD MO Status=1, MOMSN=9882, MT Status=0, MTMSN=0
2025-07-17T03:25:22.836Z,1752722722.836 [NAL9602](INFO): Sent 268 bytes from file Logs/20250717T032046/Courier0000.lzma
2025-07-17T03:25:22.836Z,1752722722.836 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:25:32.939Z,1752722732.939 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-17T03:24:32.8Z
2025-07-17T03:25:32.939Z,1752722732.939 [Startup:StartupSatComms:B] Stopped
2025-07-17T03:25:32.939Z,1752722732.939 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-07-17T03:25:32.968Z,1752722732.968 [Startup:StartupSatComms] Stopped
2025-07-17T03:25:32.968Z,1752722732.968 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-07-17T03:25:32.969Z,1752722732.969 [Startup](INFO): Completed Startup
2025-07-17T03:25:32.969Z,1752722732.969 [MissionManager](INFO): Startup is completed.
2025-07-17T03:25:32.970Z,1752722732.970 [MissionManager](INFO): Uninitializing Mission Startup
2025-07-17T03:25:32.970Z,1752722732.970 [Startup] Stopped
2025-07-17T03:25:32.970Z,1752722732.970 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-07-17T03:25:32.970Z,1752722732.970 [Startup:A.GoToSurface] Stopped
2025-07-17T03:25:32.970Z,1752722732.970 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-17T03:25:33.342Z,1752722733.342 [MissionManager](IMPORTANT): Started mission Default
2025-07-17T03:25:33.343Z,1752722733.343 [Default] Running Loop=1
2025-07-17T03:25:33.343Z,1752722733.343 [Default](DEBUG): Aggregate::initialize Default
2025-07-17T03:25:33.343Z,1752722733.343 [Default:B.GoToSurface] Running Loop=1
2025-07-17T03:25:33.343Z,1752722733.343 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-17T03:25:33.343Z,1752722733.343 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-17T03:25:33.343Z,1752722733.343 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-17T03:25:33.344Z,1752722733.344 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-17T03:25:33.344Z,1752722733.344 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-17T03:25:33.345Z,1752722733.345 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-17T03:25:33.345Z,1752722733.345 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-17T03:25:33.345Z,1752722733.345 [Default:A.Wait] Running Loop=1
2025-07-17T03:25:33.345Z,1752722733.345 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-07-17T03:25:34.536Z,1752722734.536 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:25:35.356Z,1752722735.356 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:25:43.839Z,1752722743.839 [NAL9602](INFO): SBD MO Status=1, MOMSN=9883, MT Status=0, MTMSN=0
2025-07-17T03:25:43.890Z,1752722743.890 [NAL9602](INFO): Sent 18 bytes from file Logs/20250717T024800/Express0020.lzma
2025-07-17T03:25:43.890Z,1752722743.890 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:25:46.670Z,1752722746.670 [Default:A.Wait](INFO): Done Waiting.
2025-07-17T03:25:46.670Z,1752722746.670 [Default:A.Wait] Stopped
2025-07-17T03:25:46.671Z,1752722746.671 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-17T03:25:47.080Z,1752722747.080 [Default:CheckIn] Running Loop=1
2025-07-17T03:25:47.080Z,1752722747.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-17T03:25:47.080Z,1752722747.080 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-17T03:25:47.496Z,1752722747.496 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-17T03:25:49.133Z,1752722749.133 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,032546.00,A,3648.20519,N,12150.94274,W,0.175,11.75,170725,,,A*40
2025-07-17T03:25:49.136Z,1752722749.136 [NAL9602](INFO): GPS fix at 20250717T032546: (36.803420, -121.849046)
2025-07-17T03:25:49.167Z,1752722749.167 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T03:25:49.174Z,1752722749.174 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T03:25:49.484Z,1752722749.484 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:25:49.560Z,1752722749.560 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-17T03:25:50.304Z,1752722750.304 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:25:51.174Z,1752722751.174 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004427
2025-07-17T03:25:58.479Z,1752722758.479 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250717T032046/Courier0004.lzma
2025-07-17T03:26:04.908Z,1752722764.908 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:26:05.367Z,1752722765.367 [NAL9602](INFO): SBD MO Status=1, MOMSN=9884, MT Status=0, MTMSN=0
2025-07-17T03:26:05.421Z,1752722765.421 [NAL9602](INFO): Sent 73 bytes from file Logs/20250717T032046/Courier0004.lzma
2025-07-17T03:26:05.421Z,1752722765.421 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:26:05.452Z,1752722765.452 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T03:26:05.452Z,1752722765.452 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-17T03:26:05.452Z,1752722765.452 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-17T03:26:05.724Z,1752722765.724 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:26:09.521Z,1752722769.521 [DataOverHttps](INFO): Moved sent file to Logs/20250717T032046/Courier0004.lzma.bak
2025-07-17T03:26:09.522Z,1752722769.522 [DataOverHttps](INFO): SBD MOMSN=25537595
2025-07-17T03:26:20.260Z,1752722780.260 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:26:20.676Z,1752722780.676 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:26:35.611Z,1752722795.611 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:26:35.618Z,1752722795.618 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:26:36.049Z,1752722796.049 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-17T03:26:51.455Z,1752722811.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:26:51.771Z,1752722811.771 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:27:06.721Z,1752722826.721 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-17T03:27:07.136Z,1752722827.136 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-17T03:27:13.618Z,1752722833.618 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2025-07-17T03:27:13.621Z,1752722833.621 [BPC1](INFO): Received data from all battery sticks.
2025-07-17T03:31:05.925Z,1752723065.925 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-17T03:31:05.925Z,1752723065.925 [Default:CheckIn:C.Wait] Stopped
2025-07-17T03:31:05.925Z,1752723065.925 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-17T03:31:05.925Z,1752723065.925 [Default:CheckIn:D] Running Loop=1
2025-07-17T03:31:06.335Z,1752723066.335 [Default:CheckIn:D] Stopped
2025-07-17T03:31:06.335Z,1752723066.335 [Default:CheckIn:E] Running Loop=1
2025-07-17T03:31:06.741Z,1752723066.741 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  5.549876 min 
2025-07-17T03:31:06.741Z,1752723066.741 [Default:CheckIn:E] Stopped
2025-07-17T03:31:06.742Z,1752723066.742 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-17T03:31:06.742Z,1752723066.742 [Default:CheckIn] Stopped
2025-07-17T03:31:06.742Z,1752723066.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-17T03:31:06.742Z,1752723066.742 [Default:CheckIn](INFO): Running loop #2
2025-07-17T03:31:06.742Z,1752723066.742 [Default:CheckIn] Running Loop=2
2025-07-17T03:31:06.742Z,1752723066.742 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-17T03:31:06.742Z,1752723066.742 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-17T03:31:08.743Z,1752723068.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033107.00,A,3648.23360,N,12150.90445,W,0.680,13.60,170725,,,A*40
2025-07-17T03:31:08.746Z,1752723068.746 [NAL9602](INFO): GPS fix at 20250717T033107: (36.803893, -121.848407)
2025-07-17T03:31:08.776Z,1752723068.776 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T03:31:08.776Z,1752723068.776 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T03:31:20.212Z,1752723080.212 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20250717T032046/Courier0007.lzma
2025-07-17T03:31:31.255Z,1752723091.255 [DataOverHttps](INFO): Moved sent file to Logs/20250717T032046/Courier0007.lzma.bak
2025-07-17T03:31:31.255Z,1752723091.255 [DataOverHttps](INFO): SBD MOMSN=25537610
2025-07-17T03:31:55.229Z,1752723115.229 [NAL9602](INFO): SBD MO Status=2, MOMSN=9885, MT Status=2, MTMSN=0
2025-07-17T03:31:55.229Z,1752723115.229 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:31:57.222Z,1752723117.222 [DataOverHttps](INFO): Sending 912 bytes from file Logs/20250717T032046/Express0001.lzma
2025-07-17T03:32:08.262Z,1752723128.262 [DataOverHttps](INFO): Moved sent file to Logs/20250717T032046/Express0001.lzma.bak
2025-07-17T03:32:08.262Z,1752723128.262 [DataOverHttps](INFO): SBD MOMSN=25537613
2025-07-17T03:32:35.376Z,1752723155.376 [DataOverHttps](INFO): Sending 125 bytes from file Logs/20250717T032046/Express0005.lzma
2025-07-17T03:32:46.418Z,1752723166.418 [DataOverHttps](INFO): Moved sent file to Logs/20250717T032046/Express0005.lzma.bak
2025-07-17T03:32:46.418Z,1752723166.418 [DataOverHttps](INFO): SBD MOMSN=25537627
2025-07-17T03:32:50.167Z,1752723170.167 [NAL9602](INFO): SBD MO Status=0, MOMSN=9885, MT Status=0, MTMSN=0
2025-07-17T03:32:50.167Z,1752723170.167 [NAL9602](INFO): No messages in MT queue
2025-07-17T03:33:10.917Z,1752723190.917 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20250717T032046/Express0008.lzma
2025-07-17T03:33:20.865Z,1752723200.865 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-17T03:33:21.958Z,1752723201.958 [DataOverHttps](INFO): Moved sent file to Logs/20250717T032046/Express0008.lzma.bak
2025-07-17T03:33:21.958Z,1752723201.958 [DataOverHttps](INFO): SBD MOMSN=25537631
2025-07-17T03:33:23.369Z,1752723203.369 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T03:33:23.369Z,1752723203.369 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-17T03:33:23.369Z,1752723203.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-17T03:38:17.346Z,1752723497.346 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=63.231567
2025-07-17T03:38:24.017Z,1752723504.017 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-17T03:38:24.018Z,1752723504.018 [Default:CheckIn:C.Wait] Stopped
2025-07-17T03:38:24.018Z,1752723504.018 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-17T03:38:24.018Z,1752723504.018 [Default:CheckIn:D] Running Loop=1
2025-07-17T03:38:24.703Z,1752723504.703 [Default:CheckIn:D] Stopped
2025-07-17T03:38:24.714Z,1752723504.714 [Default:CheckIn:E] Running Loop=1
2025-07-17T03:38:25.438Z,1752723505.438 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  12.855986 min 
2025-07-17T03:38:25.438Z,1752723505.438 [Default:CheckIn:E] Stopped
2025-07-17T03:38:25.439Z,1752723505.439 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-17T03:38:25.439Z,1752723505.439 [Default:CheckIn] Stopped
2025-07-17T03:38:25.457Z,1752723505.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-17T03:38:25.457Z,1752723505.457 [Default:CheckIn](INFO): Running loop #3
2025-07-17T03:38:25.458Z,1752723505.458 [Default:CheckIn] Running Loop=3
2025-07-17T03:38:25.458Z,1752723505.458 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-17T03:38:25.458Z,1752723505.458 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-17T03:38:31.382Z,1752723511.382 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033828.00,A,3648.27457,N,12150.85176,W,0.680,13.46,170725,,,A*46
2025-07-17T03:38:31.455Z,1752723511.455 [NAL9602](INFO): GPS fix at 20250717T033828: (36.804576, -121.847529)
2025-07-17T03:38:31.997Z,1752723511.997 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T03:38:31.997Z,1752723511.997 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T03:39:13.363Z,1752723553.363 [NAL9602](INFO): SBD MO Status=2, MOMSN=9886, MT Status=2, MTMSN=0
2025-07-17T03:39:13.367Z,1752723553.367 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:39:40.181Z,1752723580.181 [NAL9602](INFO): SBD MO Status=2, MOMSN=9886, MT Status=2, MTMSN=0
2025-07-17T03:39:40.182Z,1752723580.182 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:41:21.551Z,1752723681.551 [NAL9602](INFO): SBD MO Status=2, MOMSN=9886, MT Status=2, MTMSN=0
2025-07-17T03:41:21.579Z,1752723681.579 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:41:55.533Z,1752723715.533 [NAL9602](INFO): SBD MO Status=1, MOMSN=9886, MT Status=0, MTMSN=0
2025-07-17T03:41:55.591Z,1752723715.591 [NAL9602](INFO): Sent 72 bytes from file Logs/20250717T032046/Courier0010.lzma
2025-07-17T03:41:55.591Z,1752723715.591 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:42:12.003Z,1752723732.003 [NAL9602](INFO): SBD MO Status=1, MOMSN=9887, MT Status=0, MTMSN=0
2025-07-17T03:42:12.059Z,1752723732.059 [NAL9602](INFO): Sent 188 bytes from file Logs/20250717T032046/Express0011.lzma
2025-07-17T03:42:12.059Z,1752723732.059 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:42:34.394Z,1752723754.394 [NAL9602](INFO): SBD MO Status=2, MOMSN=9888, MT Status=2, MTMSN=0
2025-07-17T03:42:34.395Z,1752723754.395 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:42:53.326Z,1752723773.326 [NAL9602](INFO): SBD MO Status=0, MOMSN=9888, MT Status=0, MTMSN=0
2025-07-17T03:42:53.773Z,1752723773.773 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T03:42:53.773Z,1752723773.773 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-17T03:42:53.773Z,1752723773.773 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-17T03:43:25.685Z,1752723805.685 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-17T03:47:55.977Z,1752724075.977 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-17T03:47:55.977Z,1752724075.977 [Default:CheckIn:C.Wait] Stopped
2025-07-17T03:47:55.977Z,1752724075.977 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-17T03:47:55.978Z,1752724075.978 [Default:CheckIn:D] Running Loop=1
2025-07-17T03:47:56.770Z,1752724076.770 [Default:CheckIn:D] Stopped
2025-07-17T03:47:56.771Z,1752724076.771 [Default:CheckIn:E] Running Loop=1
2025-07-17T03:47:57.379Z,1752724077.379 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  22.390440 min 
2025-07-17T03:47:57.379Z,1752724077.379 [Default:CheckIn:E] Stopped
2025-07-17T03:47:57.379Z,1752724077.379 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-17T03:47:57.390Z,1752724077.390 [Default:CheckIn] Stopped
2025-07-17T03:47:57.391Z,1752724077.391 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-17T03:47:57.400Z,1752724077.400 [Default:CheckIn](INFO): Running loop #4
2025-07-17T03:47:57.401Z,1752724077.401 [Default:CheckIn] Running Loop=4
2025-07-17T03:47:57.401Z,1752724077.401 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-17T03:47:57.402Z,1752724077.402 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-17T03:48:00.634Z,1752724080.634 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034759.00,A,3648.32785,N,12150.77718,W,1.108,11.05,170725,,,A*40
2025-07-17T03:48:00.677Z,1752724080.677 [NAL9602](INFO): GPS fix at 20250717T034759: (36.805464, -121.846286)
2025-07-17T03:48:00.858Z,1752724080.858 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T03:48:00.858Z,1752724080.858 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T03:48:31.875Z,1752724111.875 [NAL9602](INFO): SBD MO Status=2, MOMSN=9889, MT Status=2, MTMSN=0
2025-07-17T03:48:31.893Z,1752724111.893 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:49:01.777Z,1752724141.777 [NAL9602](INFO): SBD MO Status=2, MOMSN=9889, MT Status=2, MTMSN=0
2025-07-17T03:49:01.779Z,1752724141.779 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:49:32.094Z,1752724172.094 [NAL9602](INFO): SBD MO Status=2, MOMSN=9889, MT Status=2, MTMSN=0
2025-07-17T03:49:32.094Z,1752724172.094 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:50:44.155Z,1752724244.155 [NAL9602](INFO): SBD MO Status=2, MOMSN=9889, MT Status=2, MTMSN=0
2025-07-17T03:50:44.178Z,1752724244.178 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:51:15.473Z,1752724275.473 [NAL9602](INFO): SBD MO Status=1, MOMSN=9889, MT Status=0, MTMSN=0
2025-07-17T03:51:15.530Z,1752724275.530 [NAL9602](INFO): Sent 73 bytes from file Logs/20250717T032046/Courier0013.lzma
2025-07-17T03:51:15.531Z,1752724275.531 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:51:37.609Z,1752724297.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=9890, MT Status=2, MTMSN=0
2025-07-17T03:51:37.610Z,1752724297.610 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:52:13.118Z,1752724333.118 [NAL9602](INFO): SBD MO Status=2, MOMSN=9890, MT Status=2, MTMSN=0
2025-07-17T03:52:13.119Z,1752724333.119 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:52:27.657Z,1752724347.657 [NAL9602](INFO): SBD MO Status=1, MOMSN=9890, MT Status=0, MTMSN=0
2025-07-17T03:52:27.721Z,1752724347.721 [NAL9602](INFO): Sent 177 bytes from file Logs/20250717T032046/Express0014.lzma
2025-07-17T03:52:27.722Z,1752724347.722 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:52:41.797Z,1752724361.797 [NAL9602](INFO): SBD MO Status=0, MOMSN=9891, MT Status=0, MTMSN=0
2025-07-17T03:52:42.439Z,1752724362.439 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T03:52:42.462Z,1752724362.462 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-17T03:52:42.462Z,1752724362.462 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-17T03:53:15.087Z,1752724395.087 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-17T03:57:45.285Z,1752724665.285 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-17T03:57:45.286Z,1752724665.286 [Default:CheckIn:C.Wait] Stopped
2025-07-17T03:57:45.286Z,1752724665.286 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-17T03:57:45.286Z,1752724665.286 [Default:CheckIn:D] Running Loop=1
2025-07-17T03:57:45.969Z,1752724665.969 [Default:CheckIn:D] Stopped
2025-07-17T03:57:45.970Z,1752724665.970 [Default:CheckIn:E] Running Loop=1
2025-07-17T03:57:46.670Z,1752724666.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  32.210276 min 
2025-07-17T03:57:46.670Z,1752724666.670 [Default:CheckIn:E] Stopped
2025-07-17T03:57:46.671Z,1752724666.671 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-17T03:57:46.671Z,1752724666.671 [Default:CheckIn] Stopped
2025-07-17T03:57:46.672Z,1752724666.672 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-17T03:57:46.673Z,1752724666.673 [Default:CheckIn](INFO): Running loop #5
2025-07-17T03:57:46.673Z,1752724666.673 [Default:CheckIn] Running Loop=5
2025-07-17T03:57:46.673Z,1752724666.673 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-17T03:57:46.673Z,1752724666.673 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-17T03:57:49.962Z,1752724669.962 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,035748.00,A,3648.38070,N,12150.70392,W,0.816,68.08,170725,,,A*43
2025-07-17T03:57:50.007Z,1752724670.007 [NAL9602](INFO): GPS fix at 20250717T035748: (36.806345, -121.845065)
2025-07-17T03:57:50.319Z,1752724670.319 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T03:57:50.325Z,1752724670.325 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T03:58:15.062Z,1752724695.062 [NAL9602](INFO): SBD MO Status=2, MOMSN=9892, MT Status=2, MTMSN=0
2025-07-17T03:58:15.063Z,1752724695.063 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:58:32.726Z,1752724712.726 [NAL9602](INFO): SBD MO Status=1, MOMSN=9892, MT Status=0, MTMSN=0
2025-07-17T03:58:32.789Z,1752724712.789 [NAL9602](INFO): Sent 39 bytes from file Logs/20250717T032046/Courier0016.lzma
2025-07-17T03:58:32.790Z,1752724712.790 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:58:51.086Z,1752724731.086 [NAL9602](INFO): SBD MO Status=1, MOMSN=9893, MT Status=0, MTMSN=0
2025-07-17T03:58:51.142Z,1752724731.142 [NAL9602](INFO): Sent 130 bytes from file Logs/20250717T032046/Express0017.lzma
2025-07-17T03:58:51.143Z,1752724731.143 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:59:12.102Z,1752724752.102 [NAL9602](INFO): SBD MO Status=1, MOMSN=9894, MT Status=0, MTMSN=0
2025-07-17T03:59:12.158Z,1752724752.158 [NAL9602](INFO): Sent 80 bytes from file Logs/20250717T032046/Express0018.lzma
2025-07-17T03:59:12.159Z,1752724752.159 [NAL9602](INFO): Packets left to send: 0
2025-07-17T03:59:27.922Z,1752724767.922 [NAL9602](INFO): SBD MO Status=2, MOMSN=9895, MT Status=2, MTMSN=0
2025-07-17T03:59:27.922Z,1752724767.922 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-17T03:59:39.720Z,1752724779.720 [NAL9602](INFO): SBD MO Status=0, MOMSN=9895, MT Status=0, MTMSN=0
2025-07-17T03:59:39.998Z,1752724779.998 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T03:59:39.998Z,1752724779.998 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-17T03:59:39.998Z,1752724779.998 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-17T04:00:11.051Z,1752724811.051 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-17T04:00:18.478Z,1752724818.478 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-17T04:04:41.005Z,1752725081.005 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-17T04:04:41.006Z,1752725081.006 [Default:CheckIn:C.Wait] Stopped
2025-07-17T04:04:41.007Z,1752725081.007 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-17T04:04:41.007Z,1752725081.007 [Default:CheckIn:D] Running Loop=1
2025-07-17T04:04:43.071Z,1752725083.071 [Default:CheckIn:D] Stopped
2025-07-17T04:04:43.092Z,1752725083.092 [Default:CheckIn:E] Running Loop=1
2025-07-17T04:04:44.750Z,1752725084.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  39.162134 min 
2025-07-17T04:04:44.750Z,1752725084.750 [Default:CheckIn:E] Stopped
2025-07-17T04:04:44.751Z,1752725084.751 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-17T04:04:44.751Z,1752725084.751 [Default:CheckIn] Stopped
2025-07-17T04:04:44.769Z,1752725084.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-17T04:04:44.770Z,1752725084.770 [Default:CheckIn](INFO): Running loop #6
2025-07-17T04:04:44.770Z,1752725084.770 [Default:CheckIn] Running Loop=6
2025-07-17T04:04:44.770Z,1752725084.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-17T04:04:44.771Z,1752725084.771 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-17T04:04:53.241Z,1752725093.241 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040450.00,A,3648.41905,N,12150.65295,W,1.011,19.04,170725,,,A*48
2025-07-17T04:04:53.437Z,1752725093.437 [NAL9602](INFO): GPS fix at 20250717T040450: (36.806984, -121.844216)
2025-07-17T04:04:54.101Z,1752725094.101 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T04:04:54.102Z,1752725094.102 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T04:05:32.266Z,1752725132.266 [NAL9602](INFO): SBD MO Status=1, MOMSN=9896, MT Status=0, MTMSN=0
2025-07-17T04:05:32.330Z,1752725132.330 [NAL9602](INFO): Sent 73 bytes from file Logs/20250717T032046/Courier0019.lzma
2025-07-17T04:05:32.338Z,1752725132.338 [NAL9602](INFO): Packets left to send: 0
2025-07-17T04:05:49.695Z,1752725149.695 [NAL9602](INFO): SBD MO Status=1, MOMSN=9897, MT Status=0, MTMSN=0
2025-07-17T04:05:49.771Z,1752725149.771 [NAL9602](INFO): Sent 171 bytes from file Logs/20250717T032046/Express0020.lzma
2025-07-17T04:05:49.771Z,1752725149.771 [NAL9602](INFO): Packets left to send: 0
2025-07-17T04:06:07.676Z,1752725167.676 [NAL9602](INFO): SBD MO Status=1, MOMSN=9898, MT Status=0, MTMSN=0
2025-07-17T04:06:07.727Z,1752725167.727 [NAL9602](INFO): Sent 52 bytes from file Logs/20250717T032046/Express0021.lzma
2025-07-17T04:06:07.727Z,1752725167.727 [NAL9602](INFO): Packets left to send: 0
2025-07-17T04:06:24.420Z,1752725184.420 [NAL9602](INFO): SBD MO Status=0, MOMSN=9899, MT Status=0, MTMSN=0
2025-07-17T04:06:24.675Z,1752725184.675 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T04:06:24.675Z,1752725184.675 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-17T04:06:24.685Z,1752725184.685 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-17T04:06:56.505Z,1752725216.505 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-17T04:11:25.636Z,1752725485.636 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-17T04:11:25.637Z,1752725485.637 [Default:CheckIn:C.Wait] Stopped
2025-07-17T04:11:25.637Z,1752725485.637 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-17T04:11:25.637Z,1752725485.637 [Default:CheckIn:D] Running Loop=1
2025-07-17T04:11:26.403Z,1752725486.403 [Default:CheckIn:D] Stopped
2025-07-17T04:11:26.403Z,1752725486.403 [Default:CheckIn:E] Running Loop=1
2025-07-17T04:11:27.041Z,1752725487.041 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  45.884318 min 
2025-07-17T04:11:27.042Z,1752725487.042 [Default:CheckIn:E] Stopped
2025-07-17T04:11:27.043Z,1752725487.043 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-17T04:11:27.043Z,1752725487.043 [Default:CheckIn] Stopped
2025-07-17T04:11:27.043Z,1752725487.043 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-17T04:11:27.052Z,1752725487.052 [Default:CheckIn](INFO): Running loop #7
2025-07-17T04:11:27.053Z,1752725487.053 [Default:CheckIn] Running Loop=7
2025-07-17T04:11:27.053Z,1752725487.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-17T04:11:27.054Z,1752725487.054 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-17T04:11:30.627Z,1752725490.627 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,041128.00,A,3648.45463,N,12150.60699,W,0.369,44.26,170725,,,A*42
2025-07-17T04:11:30.685Z,1752725490.685 [NAL9602](INFO): GPS fix at 20250717T041128: (36.807577, -121.843450)
2025-07-17T04:11:30.877Z,1752725490.877 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T04:11:30.885Z,1752725490.885 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T04:12:01.386Z,1752725521.386 [NAL9602](INFO): SBD MO Status=1, MOMSN=9900, MT Status=0, MTMSN=0
2025-07-17T04:12:01.463Z,1752725521.463 [NAL9602](INFO): Sent 72 bytes from file Logs/20250717T032046/Courier0022.lzma
2025-07-17T04:12:01.463Z,1752725521.463 [NAL9602](INFO): Packets left to send: 0
2025-07-17T04:12:17.143Z,1752725537.143 [CommandExec](IMPORTANT): got command restart system
2025-07-17T04:12:20.301Z,1752725540.301 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-07-17T04:12:20.303Z,1752725540.303 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:20.306Z,1752725540.306 [CommandExec](INFO): Uninitializing the command executive.
2025-07-17T04:12:20.306Z,1752725540.306 [CommandExec](INFO): Uninitializing the command scheduler.
2025-07-17T04:12:20.310Z,1752725540.310 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:20.401Z,1752725540.401 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-07-17T04:12:20.402Z,1752725540.402 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-07-17T04:12:20.402Z,1752725540.402 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:20.407Z,1752725540.407 [NavChartDb](INFO): Join timeout helper Thread ID is 1192
2025-07-17T04:12:20.766Z,1752725540.766 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:20.769Z,1752725540.769 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:20.773Z,1752725540.773 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-07-17T04:12:20.773Z,1752725540.773 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:20.777Z,1752725540.777 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1193
2025-07-17T04:12:21.095Z,1752725541.095 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:21.096Z,1752725541.096 [WetLabsBB2FL](INFO): Powering down
2025-07-17T04:12:21.106Z,1752725541.106 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:21.124Z,1752725541.124 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-07-17T04:12:21.125Z,1752725541.125 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:21.126Z,1752725541.126 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1194
2025-07-17T04:12:21.154Z,1752725541.154 [CTD_Seabird](INFO): Powering down
2025-07-17T04:12:21.173Z,1752725541.173 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:21.175Z,1752725541.175 [CTD_Seabird](INFO): Powering down
2025-07-17T04:12:21.194Z,1752725541.194 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:21.209Z,1752725541.209 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-07-17T04:12:21.209Z,1752725541.209 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:21.210Z,1752725541.210 [Radio_Surface](INFO): Join timeout helper Thread ID is 1195
2025-07-17T04:12:21.438Z,1752725541.438 [Radio_Surface](INFO): Powering down
2025-07-17T04:12:21.443Z,1752725541.443 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:21.444Z,1752725541.444 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:21.466Z,1752725541.466 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-07-17T04:12:21.467Z,1752725541.467 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:21.469Z,1752725541.469 [Onboard](INFO): Join timeout helper Thread ID is 1196
2025-07-17T04:12:22.343Z,1752725542.343 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:22.347Z,1752725542.347 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:22.370Z,1752725542.370 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-07-17T04:12:22.370Z,1752725542.370 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:22.373Z,1752725542.373 [DataOverHttps](INFO): Join timeout helper Thread ID is 1197
2025-07-17T04:12:30.735Z,1752725550.735 [NAL9602](INFO): SBD MO Status=1, MOMSN=9901, MT Status=0, MTMSN=0
2025-07-17T04:12:30.798Z,1752725550.798 [NAL9602](INFO): Sent 179 bytes from file Logs/20250717T032046/Express0023.lzma
2025-07-17T04:12:30.799Z,1752725550.799 [NAL9602](INFO): Packets left to send: 0
2025-07-17T04:12:36.238Z,1752725556.238 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:36.241Z,1752725556.241 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.265Z,1752725556.265 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-07-17T04:12:36.265Z,1752725556.265 [DAT ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.267Z,1752725556.267 [DAT](INFO): Join timeout helper Thread ID is 1198
2025-07-17T04:12:36.471Z,1752725556.471 [DAT](INFO): Powering down
2025-07-17T04:12:36.553Z,1752725556.553 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:36.553Z,1752725556.553 [DAT](INFO): Powering down
2025-07-17T04:12:36.561Z,1752725556.561 [DAT ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.580Z,1752725556.580 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-07-17T04:12:36.580Z,1752725556.580 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.582Z,1752725556.582 [BackseatComponent](INFO): Join timeout helper Thread ID is 1199
2025-07-17T04:12:36.618Z,1752725556.618 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:36.620Z,1752725556.620 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.631Z,1752725556.631 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-07-17T04:12:36.631Z,1752725556.631 [logger ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.634Z,1752725556.634 [logger](INFO): Join timeout helper Thread ID is 1200
2025-07-17T04:12:36.687Z,1752725556.687 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:36.690Z,1752725556.690 [logger ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.712Z,1752725556.712 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-07-17T04:12:36.713Z,1752725556.713 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.714Z,1752725556.714 [CommandLine](INFO): Join timeout helper Thread ID is 1201
2025-07-17T04:12:36.787Z,1752725556.787 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:36.789Z,1752725556.789 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.797Z,1752725556.797 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-07-17T04:12:36.797Z,1752725556.797 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.799Z,1752725556.799 [CommandExec](INFO): Join timeout helper Thread ID is 1202
2025-07-17T04:12:36.809Z,1752725556.809 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-07-17T04:12:36.810Z,1752725556.810 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:36.811Z,1752725556.811 [controlThread](INFO): Join timeout helper Thread ID is 1203
2025-07-17T04:12:36.846Z,1752725556.846 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T04:12:36.846Z,1752725556.846 [controlThread](DEBUG): Uninitializing ControlThread
2025-07-17T04:12:36.849Z,1752725556.849 [AHRS_M2](INFO): Powering down
2025-07-17T04:12:36.935Z,1752725556.935 [NAL9602](INFO): Powering down
2025-07-17T04:12:36.945Z,1752725556.945 [Sonardyne_Nano](INFO): Powering down
2025-07-17T04:12:37.067Z,1752725557.067 [Waterlinked](INFO): Powering down
2025-07-17T04:12:37.163Z,1752725557.163 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-07-17T04:12:37.169Z,1752725557.169 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-07-17T04:12:37.172Z,1752725557.172 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-07-17T04:12:37.175Z,1752725557.175 [MissionManager](INFO): Uninitializing Mission Default
2025-07-17T04:12:37.176Z,1752725557.176 [Default] Stopped
2025-07-17T04:12:37.176Z,1752725557.176 [Default](DEBUG): Aggregate::uninitialize Default
2025-07-17T04:12:37.177Z,1752725557.177 [Default:B.GoToSurface] Stopped
2025-07-17T04:12:37.177Z,1752725557.177 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-17T04:12:37.177Z,1752725557.177 [Default:CheckIn] Stopped
2025-07-17T04:12:37.178Z,1752725557.178 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-17T04:12:37.178Z,1752725557.178 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T04:12:37.197Z,1752725557.197 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-07-17T04:12:37.199Z,1752725557.199 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-07-17T04:12:37.201Z,1752725557.201 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-07-17T04:12:37.203Z,1752725557.203 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-07-17T04:12:37.204Z,1752725557.204 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-07-17T04:12:37.205Z,1752725557.205 [BuoyancyServo](INFO): Powering down
2025-07-17T04:12:37.225Z,1752725557.225 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-17T04:12:37.225Z,1752725557.225 [ElevatorServo](INFO): Powering down
2025-07-17T04:12:37.230Z,1752725557.230 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-07-17T04:12:37.231Z,1752725557.231 [MassServo](INFO): Powering down
2025-07-17T04:12:37.237Z,1752725557.237 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-07-17T04:12:37.237Z,1752725557.237 [RudderServo](INFO): Powering down
2025-07-17T04:12:37.243Z,1752725557.243 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-07-17T04:12:37.245Z,1752725557.245 [ThrusterHE](INFO): Powering down
2025-07-17T04:12:37.249Z,1752725557.249 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-07-17T04:12:37.251Z,1752725557.251 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-07-17T04:12:37.254Z,1752725557.254 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-07-17T04:12:37.254Z,1752725557.254 [CBIT](DEBUG): Powering off loads.
2025-07-17T04:12:37.268Z,1752725557.268 [CBIT](DEBUG): Disabling WDT.
2025-07-17T04:12:37.282Z,1752725557.282 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-07-17T04:12:37.282Z,1752725557.283 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-07-17T04:12:37.289Z,1752725557.289 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:37.356Z,1752725557.356 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:37.359Z,1752725557.359 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:37.365Z,1752725557.365 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:37.371Z,1752725557.371 [DAT ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:37.416Z,1752725557.416 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:37.467Z,1752725557.467 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:37.472Z,1752725557.472 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:37.497Z,1752725557.497 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-07-17T04:12:37.497Z,1752725557.497 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-07-17T04:12:37.514Z,1752725557.514 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-17T04:12:37.579Z,1752725557.579 [logger ThreadHandler](INFO): Thread cancelled.