2024-09-05T19:11:15.278Z,1725563475.278 [Supervisor](DEBUG): Initializing supervisor. 2024-09-05T19:11:15.282Z,1725563475.282 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-09-05T19:11:15.283Z,1725563475.283 [SyncHandler](INFO): Protected caller Thread ID is 3368 2024-09-05T19:11:15.283Z,1725563475.283 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-09-05T19:11:15.284Z,1725563475.284 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-09-05T19:11:15.285Z,1725563475.285 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3369 2024-09-05T19:11:15.289Z,1725563475.289 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-09-05T19:11:15.306Z,1725563475.306 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-09-05T19:11:15.307Z,1725563475.307 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-09-05T19:11:15.307Z,1725563475.307 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3370 2024-09-05T19:11:15.312Z,1725563475.312 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-09-05T19:11:15.313Z,1725563475.313 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-09-05T19:11:15.313Z,1725563475.313 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3371 2024-09-05T19:11:15.315Z,1725563475.315 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-09-05T19:11:15.316Z,1725563475.316 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-09-05T19:11:15.316Z,1725563475.316 [logger ThreadHandler](INFO): Protected caller Thread ID is 3372 2024-09-05T19:11:15.320Z,1725563475.320 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-09-05T19:11:15.320Z,1725563475.320 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-09-05T19:11:15.322Z,1725563475.322 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-09-05T19:11:15.662Z,1725563475.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-09-05T19:11:15.664Z,1725563475.664 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-09-05T19:11:15.759Z,1725563475.759 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-09-05T19:11:16.313Z,1725563476.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-09-05T19:11:16.315Z,1725563476.315 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-09-05T19:11:16.675Z,1725563476.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-09-05T19:11:16.678Z,1725563476.678 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-09-05T19:11:16.786Z,1725563476.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-09-05T19:11:16.786Z,1725563476.786 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-09-05T19:11:17.210Z,1725563477.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-09-05T19:11:17.212Z,1725563477.212 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-09-05T19:11:17.440Z,1725563477.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-09-05T19:11:17.442Z,1725563477.442 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-09-05T19:11:18.004Z,1725563478.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-09-05T19:11:18.007Z,1725563478.007 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-09-05T19:11:18.365Z,1725563478.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-09-05T19:11:18.435Z,1725563478.435 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-09-05T19:11:18.556Z,1725563478.556 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-09-05T19:11:19.566Z,1725563479.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-09-05T19:11:19.567Z,1725563479.567 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-09-05T19:11:19.966Z,1725563479.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-09-05T19:11:19.968Z,1725563479.968 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-09-05T19:11:20.176Z,1725563480.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-09-05T19:11:20.177Z,1725563480.177 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-09-05T19:11:20.301Z,1725563480.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-09-05T19:11:20.301Z,1725563480.301 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-09-05T19:11:20.542Z,1725563480.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-09-05T19:11:20.542Z,1725563480.542 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-09-05T19:11:20.807Z,1725563480.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-09-05T19:11:20.809Z,1725563480.809 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-09-05T19:11:20.811Z,1725563480.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-09-05T19:11:20.916Z,1725563480.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-09-05T19:11:21.014Z,1725563481.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-09-05T19:11:21.128Z,1725563481.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-09-05T19:11:21.224Z,1725563481.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-09-05T19:11:21.413Z,1725563481.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-09-05T19:11:21.518Z,1725563481.518 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-09-05T19:11:21.648Z,1725563481.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-09-05T19:11:21.814Z,1725563481.814 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-09-05T19:11:21.975Z,1725563481.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-09-05T19:11:22.099Z,1725563482.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-09-05T19:11:22.377Z,1725563482.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-09-05T19:11:22.378Z,1725563482.378 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2024-09-05T19:11:22.378Z,1725563482.378 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-09-05T19:11:22.392Z,1725563482.392 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-09-05T19:11:22.446Z,1725563482.446 [VerticalControl](DEBUG): Construct VerticalControl. 2024-09-05T19:11:22.507Z,1725563482.507 [VerticalControl] Loaded 2024-09-05T19:11:22.508Z,1725563482.508 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-09-05T19:11:22.510Z,1725563482.510 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-09-05T19:11:22.557Z,1725563482.557 [HorizontalControl] Loaded 2024-09-05T19:11:22.558Z,1725563482.558 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-09-05T19:11:22.560Z,1725563482.560 [SpeedControl](DEBUG): Construct SpeedControl. 2024-09-05T19:11:22.563Z,1725563482.563 [SpeedControl] Loaded 2024-09-05T19:11:22.563Z,1725563482.563 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-09-05T19:11:22.566Z,1725563482.566 [LoopControl](DEBUG): Construct LoopControl. 2024-09-05T19:11:22.567Z,1725563482.567 [LoopControl] Loaded 2024-09-05T19:11:22.567Z,1725563482.567 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-09-05T19:11:22.567Z,1725563482.567 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-09-05T19:11:22.568Z,1725563482.568 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-09-05T19:11:22.579Z,1725563482.579 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-09-05T19:11:22.579Z,1725563482.579 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-09-05T19:11:22.752Z,1725563482.752 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-09-05T19:11:22.753Z,1725563482.753 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-09-05T19:11:22.912Z,1725563482.912 [BuoyancyServo] Loaded 2024-09-05T19:11:22.912Z,1725563482.912 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-09-05T19:11:22.934Z,1725563482.934 [ElevatorServo] Loaded 2024-09-05T19:11:22.934Z,1725563482.934 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-09-05T19:11:22.955Z,1725563482.955 [MassServo] Loaded 2024-09-05T19:11:22.956Z,1725563482.956 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-09-05T19:11:22.976Z,1725563482.976 [RudderServo] Loaded 2024-09-05T19:11:22.976Z,1725563482.976 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-09-05T19:11:22.992Z,1725563482.992 [ThrusterHE] Loaded 2024-09-05T19:11:22.993Z,1725563482.993 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-09-05T19:11:22.993Z,1725563482.993 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-09-05T19:11:22.994Z,1725563482.994 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-09-05T19:11:23.352Z,1725563483.352 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-09-05T19:11:23.352Z,1725563483.352 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-09-05T19:11:23.365Z,1725563483.365 [NavChart] Loaded 2024-09-05T19:11:23.366Z,1725563483.366 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-09-05T19:11:23.371Z,1725563483.371 [UniversalFixResidualReporter] Loaded 2024-09-05T19:11:23.371Z,1725563483.371 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-09-05T19:11:23.372Z,1725563483.372 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-09-05T19:11:23.373Z,1725563483.373 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-09-05T19:11:23.689Z,1725563483.689 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-09-05T19:11:23.690Z,1725563483.690 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-09-05T19:11:24.190Z,1725563484.190 [AHRS_M2] Loaded 2024-09-05T19:11:24.191Z,1725563484.191 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-09-05T19:11:25.017Z,1725563485.017 [BPC1] Loaded 2024-09-05T19:11:25.018Z,1725563485.018 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-09-05T19:11:25.106Z,1725563485.106 [DATMMP] Loaded 2024-09-05T19:11:25.106Z,1725563485.106 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2024-09-05T19:11:25.107Z,1725563485.107 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 4094C4E0 2024-09-05T19:11:25.107Z,1725563485.107 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 3454 2024-09-05T19:11:25.186Z,1725563485.186 [DataOverHttps] Loaded 2024-09-05T19:11:25.186Z,1725563485.186 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-09-05T19:11:25.187Z,1725563485.187 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097C4E0 2024-09-05T19:11:25.187Z,1725563485.187 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3455 2024-09-05T19:11:25.210Z,1725563485.210 [Depth_Keller] Loaded 2024-09-05T19:11:25.210Z,1725563485.210 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-09-05T19:11:25.215Z,1725563485.215 [DropWeight] Loaded 2024-09-05T19:11:25.215Z,1725563485.215 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-09-05T19:11:25.279Z,1725563485.279 [NAL9602] Loaded 2024-09-05T19:11:25.279Z,1725563485.279 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-09-05T19:11:25.309Z,1725563485.309 [Onboard] Loaded 2024-09-05T19:11:25.309Z,1725563485.309 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-09-05T19:11:25.310Z,1725563485.310 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AC4E0 2024-09-05T19:11:25.311Z,1725563485.311 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3456 2024-09-05T19:11:25.325Z,1725563485.325 [Power24vConverter] Loaded 2024-09-05T19:11:25.325Z,1725563485.325 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-09-05T19:11:25.340Z,1725563485.340 [Radio_Surface] Loaded 2024-09-05T19:11:25.341Z,1725563485.341 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-09-05T19:11:25.342Z,1725563485.342 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DC4E0 2024-09-05T19:11:25.342Z,1725563485.342 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3457 2024-09-05T19:11:25.343Z,1725563485.343 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-09-05T19:11:25.344Z,1725563485.344 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-09-05T19:11:25.399Z,1725563485.399 [DepthRateCalculator] Loaded 2024-09-05T19:11:25.400Z,1725563485.400 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-09-05T19:11:25.405Z,1725563485.405 [PitchRateCalculator] Loaded 2024-09-05T19:11:25.405Z,1725563485.405 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-09-05T19:11:25.414Z,1725563485.414 [SpeedCalculator] Loaded 2024-09-05T19:11:25.414Z,1725563485.414 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-09-05T19:11:25.418Z,1725563485.418 [YawRateCalculator] Loaded 2024-09-05T19:11:25.418Z,1725563485.418 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-09-05T19:11:25.437Z,1725563485.437 [ElevatorOffsetCalculator] Loaded 2024-09-05T19:11:25.437Z,1725563485.437 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-09-05T19:11:25.438Z,1725563485.438 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-09-05T19:11:25.439Z,1725563485.439 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-09-05T19:11:25.616Z,1725563485.616 [CTD_Seabird] Loaded 2024-09-05T19:11:25.616Z,1725563485.616 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-09-05T19:11:25.617Z,1725563485.617 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A984E0 2024-09-05T19:11:25.618Z,1725563485.618 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3458 2024-09-05T19:11:25.655Z,1725563485.655 [ESPComponent] Loaded 2024-09-05T19:11:25.655Z,1725563485.655 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-09-05T19:11:25.675Z,1725563485.675 [PAR_Licor] Loaded 2024-09-05T19:11:25.675Z,1725563485.675 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-09-05T19:11:25.719Z,1725563485.719 [WetLabsBB2FL] Loaded 2024-09-05T19:11:25.720Z,1725563485.720 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-09-05T19:11:25.721Z,1725563485.721 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC84E0 2024-09-05T19:11:25.721Z,1725563485.721 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3459 2024-09-05T19:11:25.722Z,1725563485.722 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-09-05T19:11:25.722Z,1725563485.722 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-09-05T19:11:25.756Z,1725563485.756 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-09-05T19:11:25.756Z,1725563485.756 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-09-05T19:11:26.091Z,1725563486.091 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-09-05T19:11:26.091Z,1725563486.091 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-09-05T19:11:26.234Z,1725563486.234 [SBIT](DEBUG): Construct Startup Built In Test. 2024-09-05T19:11:26.243Z,1725563486.243 [SBIT] Loaded 2024-09-05T19:11:26.243Z,1725563486.243 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-09-05T19:11:26.246Z,1725563486.246 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-09-05T19:11:26.259Z,1725563486.259 [IBIT] Loaded 2024-09-05T19:11:26.259Z,1725563486.259 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-09-05T19:11:26.265Z,1725563486.265 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-09-05T19:11:26.372Z,1725563486.372 [CBIT] Loaded 2024-09-05T19:11:26.372Z,1725563486.372 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-09-05T19:11:26.372Z,1725563486.372 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-09-05T19:11:26.373Z,1725563486.373 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-09-05T19:11:26.483Z,1725563486.483 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-09-05T19:11:26.489Z,1725563486.489 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-09-05T19:11:26.492Z,1725563486.492 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-09-05T19:11:26.503Z,1725563486.503 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-09-05T19:11:26.504Z,1725563486.504 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0 2024-09-05T19:11:26.505Z,1725563486.505 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3460 2024-09-05T19:11:26.509Z,1725563486.509 [Supervisor](INFO): Main Thread ID is 831 2024-09-05T19:11:26.509Z,1725563486.509 [Supervisor](DEBUG): Running supervisor. 2024-09-05T19:11:26.510Z,1725563486.510 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3461 2024-09-05T19:11:26.510Z,1725563486.510 [CommandExec](INFO): Initializing the command executive. 2024-09-05T19:11:26.512Z,1725563486.512 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3462 2024-09-05T19:11:26.515Z,1725563486.515 [controlThread ThreadHandler](INFO): Handler Thread ID is 3463 2024-09-05T19:11:26.515Z,1725563486.515 [controlThread](DEBUG): Initializing ControlThread 2024-09-05T19:11:26.516Z,1725563486.516 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-09-05T19:11:26.518Z,1725563486.518 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-09-05T19:11:26.518Z,1725563486.518 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-09-05T19:11:26.519Z,1725563486.519 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-09-05T19:11:26.521Z,1725563486.521 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-09-05T19:11:26.521Z,1725563486.521 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-09-05T19:11:26.526Z,1725563486.526 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-09-05T19:11:26.526Z,1725563486.526 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-09-05T19:11:26.527Z,1725563486.527 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-09-05T19:11:26.527Z,1725563486.527 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-09-05T19:11:26.527Z,1725563486.527 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-09-05T19:11:26.533Z,1725563486.533 [SBIT](INFO): Initialize SBIT Component. 2024-09-05T19:11:26.533Z,1725563486.533 [SBIT](IMPORTANT): git: 2024-09-04 2024-09-05T19:11:26.533Z,1725563486.533 [SBIT](INFO): git hash: c3a48f2091374af655f27818376c2e16c84a5fcd 2024-09-05T19:11:26.534Z,1725563486.534 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-09-05T19:11:26.535Z,1725563486.535 [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 2024-09-05T19:11:26.536Z,1725563486.536 [SBIT](INFO): Beginning SBIT in 70.000000 seconds. 2024-09-05T19:11:26.536Z,1725563486.536 [IBIT](INFO): Initialize IBIT Component. 2024-09-05T19:11:26.537Z,1725563486.537 [CBIT](DEBUG): Initialize CBIT Component. 2024-09-05T19:11:26.538Z,1725563486.538 [logger ThreadHandler](INFO): Handler Thread ID is 3464 2024-09-05T19:11:26.548Z,1725563486.548 [CBIT](DEBUG): Initialized mux pins. 2024-09-05T19:11:26.549Z,1725563486.549 [CBIT](DEBUG): Initializing the watchdog timer. 2024-09-05T19:11:26.557Z,1725563486.557 [DATMMP ThreadHandler](INFO): Handler Thread ID is 3465 2024-09-05T19:11:26.557Z,1725563486.557 [DATMMP](INFO): Start 2024-09-05T19:11:26.558Z,1725563486.558 [DATMMP](INFO): Powering up 2024-09-05T19:11:26.558Z,1725563486.558 [DATMMP](DEBUG): Initializing DATMMP. 2024-09-05T19:11:26.562Z,1725563486.562 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3467 2024-09-05T19:11:26.564Z,1725563486.564 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-09-05T19:11:26.572Z,1725563486.572 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-09-05T19:11:26.573Z,1725563486.573 [CBIT](DEBUG): Initializing heartbeat. 2024-09-05T19:11:26.574Z,1725563486.574 [Onboard ThreadHandler](INFO): Handler Thread ID is 3468 2024-09-05T19:11:26.595Z,1725563486.595 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3469 2024-09-05T19:11:26.605Z,1725563486.605 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3470 2024-09-05T19:11:26.606Z,1725563486.606 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-09-05T19:11:26.609Z,1725563486.609 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3472 2024-09-05T19:11:26.613Z,1725563486.613 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3474 2024-09-05T19:11:26.615Z,1725563486.615 [WetLabsBB2FL](INFO): Powering up 2024-09-05T19:11:26.618Z,1725563486.618 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-09-05T19:11:26.618Z,1725563486.618 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-09-05T19:11:26.619Z,1725563486.619 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-09-05T19:11:26.619Z,1725563486.619 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-09-05T19:11:26.619Z,1725563486.619 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-09-05T19:11:26.619Z,1725563486.619 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-09-05T19:11:26.619Z,1725563486.619 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-09-05T19:11:26.619Z,1725563486.619 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-09-05T19:11:26.644Z,1725563486.644 [CBIT](DEBUG): Deactivating GF circuits. 2024-09-05T19:11:26.645Z,1725563486.645 [CBIT](DEBUG): Deactivating emergency mode. 2024-09-05T19:11:26.684Z,1725563486.684 [CBIT](DEBUG): Backplane powered. 2024-09-05T19:11:26.685Z,1725563486.685 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-09-05T19:11:26.686Z,1725563486.686 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-09-05T19:11:26.686Z,1725563486.686 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-09-05T19:11:26.690Z,1725563486.690 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-09-05T19:11:26.722Z,1725563486.722 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-09-05T19:11:26.742Z,1725563486.742 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-09-05T19:11:26.774Z,1725563486.774 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-09-05T19:11:26.775Z,1725563486.775 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-09-05T19:11:26.775Z,1725563486.775 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-09-05T19:11:26.785Z,1725563486.785 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-09-05T19:11:26.808Z,1725563486.808 [DATMMP](INFO): Starting 2024-09-05T19:11:27.008Z,1725563487.008 [Radio_Surface](INFO): Powering up 2024-09-05T19:11:27.060Z,1725563487.060 [DATMMP](INFO): Starting 2024-09-05T19:11:27.066Z,1725563487.066 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-09-05T19:11:27.096Z,1725563487.096 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-09-05T19:11:27.097Z,1725563487.097 [Default:A.Wait](DEBUG): Construct Wait. 2024-09-05T19:11:27.099Z,1725563487.099 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-09-05T19:11:27.152Z,1725563487.152 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-09-05T19:11:27.155Z,1725563487.155 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-09-05T19:11:27.204Z,1725563487.204 [Default:E.Execute](DEBUG): Construct Execute. 2024-09-05T19:11:27.208Z,1725563487.208 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-09-05T19:11:27.212Z,1725563487.212 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-09-05T19:11:27.235Z,1725563487.235 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-09-05T19:11:27.312Z,1725563487.312 [Power24vConverter](INFO): Powering up. 2024-09-05T19:11:27.313Z,1725563487.313 [DATMMP](INFO): Starting 2024-09-05T19:11:27.326Z,1725563487.326 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-09-05T19:11:27.333Z,1725563487.333 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-09-05T19:11:27.334Z,1725563487.334 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-09-05T19:11:27.341Z,1725563487.341 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-09-05T19:11:27.342Z,1725563487.342 [MassServo](DEBUG): Initializing EZServoServo. 2024-09-05T19:11:27.349Z,1725563487.349 [MassServo](DEBUG): Initializing MassServo. 2024-09-05T19:11:27.349Z,1725563487.349 [RudderServo](DEBUG): Initializing EZServoServo. 2024-09-05T19:11:27.357Z,1725563487.357 [RudderServo](DEBUG): Initializing RudderServo. 2024-09-05T19:11:27.357Z,1725563487.357 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-09-05T19:11:27.365Z,1725563487.365 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-09-05T19:11:27.564Z,1725563487.564 [DATMMP](INFO): Starting 2024-09-05T19:11:27.817Z,1725563487.817 [DATMMP](INFO): Starting 2024-09-05T19:11:28.068Z,1725563488.068 [DATMMP](INFO): Starting 2024-09-05T19:11:28.321Z,1725563488.321 [DATMMP](INFO): Starting 2024-09-05T19:11:28.579Z,1725563488.579 [DATMMP](INFO): Starting 2024-09-05T19:11:28.829Z,1725563488.829 [DATMMP](INFO): Starting 2024-09-05T19:11:29.081Z,1725563489.081 [DATMMP](INFO): Starting 2024-09-05T19:11:29.229Z,1725563489.229 [WetLabsBB2FL](INFO): Powering down 2024-09-05T19:11:29.333Z,1725563489.333 [DATMMP](INFO): Starting 2024-09-05T19:11:29.585Z,1725563489.585 [DATMMP](INFO): Starting 2024-09-05T19:11:29.836Z,1725563489.836 [DATMMP](INFO): Starting 2024-09-05T19:11:30.089Z,1725563490.089 [DATMMP](INFO): Starting 2024-09-05T19:11:30.341Z,1725563490.341 [DATMMP](INFO): Starting 2024-09-05T19:11:30.593Z,1725563490.593 [DATMMP](INFO): Starting 2024-09-05T19:11:30.844Z,1725563490.844 [DATMMP](INFO): Starting 2024-09-05T19:11:31.097Z,1725563491.097 [DATMMP](INFO): Starting 2024-09-05T19:11:31.349Z,1725563491.349 [DATMMP](INFO): Starting 2024-09-05T19:11:31.601Z,1725563491.601 [DATMMP](INFO): Starting 2024-09-05T19:11:31.853Z,1725563491.853 [DATMMP](INFO): Starting 2024-09-05T19:11:32.104Z,1725563492.104 [DATMMP](INFO): Starting 2024-09-05T19:11:32.357Z,1725563492.357 [DATMMP](INFO): Starting 2024-09-05T19:11:32.609Z,1725563492.609 [DATMMP](INFO): Starting 2024-09-05T19:11:32.861Z,1725563492.861 [DATMMP](INFO): Starting 2024-09-05T19:11:33.113Z,1725563493.113 [DATMMP](INFO): Starting 2024-09-05T19:11:33.364Z,1725563493.364 [DATMMP](INFO): Starting 2024-09-05T19:11:33.617Z,1725563493.617 [DATMMP](INFO): Starting 2024-09-05T19:11:33.869Z,1725563493.869 [DATMMP](INFO): Starting 2024-09-05T19:11:34.120Z,1725563494.120 [DATMMP](INFO): Starting 2024-09-05T19:11:34.373Z,1725563494.373 [DATMMP](INFO): Starting 2024-09-05T19:11:34.624Z,1725563494.624 [DATMMP](INFO): Starting 2024-09-05T19:11:34.877Z,1725563494.877 [DATMMP](INFO): Starting 2024-09-05T19:11:35.129Z,1725563495.129 [DATMMP](INFO): Starting 2024-09-05T19:11:35.380Z,1725563495.380 [DATMMP](INFO): Starting 2024-09-05T19:11:35.633Z,1725563495.633 [DATMMP](INFO): Starting 2024-09-05T19:11:35.884Z,1725563495.884 [DATMMP](INFO): Starting 2024-09-05T19:11:36.137Z,1725563496.137 [DATMMP](INFO): Starting 2024-09-05T19:11:36.389Z,1725563496.389 [DATMMP](INFO): Starting 2024-09-05T19:11:36.641Z,1725563496.641 [DATMMP](INFO): Starting 2024-09-05T19:11:36.893Z,1725563496.893 [DATMMP](INFO): Starting 2024-09-05T19:11:37.145Z,1725563497.145 [DATMMP](INFO): Starting 2024-09-05T19:11:37.397Z,1725563497.397 [DATMMP](INFO): Starting 2024-09-05T19:11:37.649Z,1725563497.649 [DATMMP](INFO): Starting 2024-09-05T19:11:37.901Z,1725563497.901 [DATMMP](INFO): Starting 2024-09-05T19:11:38.153Z,1725563498.153 [DATMMP](INFO): Starting 2024-09-05T19:11:38.153Z,1725563498.153 [DATMMP](INFO): DAT read: 2024-09-05T19:11:38.154Z,1725563498.154 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-09-05T19:11:38.405Z,1725563498.405 [DATMMP](INFO): Starting 2024-09-05T19:11:38.657Z,1725563498.657 [DATMMP](INFO): Starting 2024-09-05T19:11:38.909Z,1725563498.909 [DATMMP](INFO): Starting 2024-09-05T19:11:39.161Z,1725563499.161 [DATMMP](INFO): Starting 2024-09-05T19:11:39.413Z,1725563499.413 [DATMMP](INFO): Starting 2024-09-05T19:11:39.665Z,1725563499.665 [DATMMP](INFO): Starting 2024-09-05T19:11:39.665Z,1725563499.665 [DATMMP](INFO): DAT read: MF Frequency Band 2024-09-05T19:11:39.666Z,1725563499.666 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-09-05T19:11:39.667Z,1725563499.667 [DATMMP](INFO): DAT read: Aug 5 2000 19:11:40 2024-09-05T19:11:39.917Z,1725563499.917 [DATMMP](INFO): Starting 2024-09-05T19:11:40.168Z,1725563500.168 [DATMMP](INFO): Starting 2024-09-05T19:11:40.421Z,1725563500.421 [DATMMP](INFO): Starting 2024-09-05T19:11:40.673Z,1725563500.673 [DATMMP](INFO): Starting 2024-09-05T19:11:40.674Z,1725563500.674 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2024-09-05T19:11:40.674Z,1725563500.674 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-09-05T19:11:40.675Z,1725563500.675 [DATMMP](INFO): commRate: 1200 2024-09-05T19:11:40.925Z,1725563500.925 [DATMMP](INFO): Starting 2024-09-05T19:11:41.177Z,1725563501.177 [DATMMP](INFO): Starting 2024-09-05T19:11:41.429Z,1725563501.429 [DATMMP](INFO): Starting 2024-09-05T19:11:41.680Z,1725563501.680 [DATMMP](INFO): Starting 2024-09-05T19:11:41.933Z,1725563501.933 [DATMMP](INFO): Starting 2024-09-05T19:11:42.185Z,1725563502.185 [DATMMP](INFO): Starting 2024-09-05T19:11:42.437Z,1725563502.437 [DATMMP](INFO): Starting 2024-09-05T19:11:42.689Z,1725563502.689 [DATMMP](INFO): Starting 2024-09-05T19:11:42.740Z,1725563502.740 [DATMMP](INFO): entering command mode 2024-09-05T19:11:42.941Z,1725563502.941 [DATMMP](INFO): Starting 2024-09-05T19:11:42.941Z,1725563502.941 [DATMMP](INFO): DAT read: 2024-09-05T19:11:42.941Z,1725563502.941 [DATMMP](INFO): DAT read: user:1> 2024-09-05T19:11:42.942Z,1725563502.942 [DATMMP](DEBUG): read user prompt 1: user:1> 2024-09-05T19:11:42.942Z,1725563502.942 [DATMMP](INFO): entering MMP mode 2024-09-05T19:11:43.193Z,1725563503.193 [DATMMP](INFO): Starting 2024-09-05T19:11:43.193Z,1725563503.193 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T19:11:43.195Z,1725563503.195 [DATMMP](INFO): status rx: x1 2024-09-05T19:11:43.195Z,1725563503.195 [DATMMP](INFO): MMP status: 1: started 2024-09-05T19:11:43.195Z,1725563503.195 [DATMMP](INFO): Received message type: status 2024-09-05T19:11:43.196Z,1725563503.196 [DATMMP](INFO): Handled 2024-09-05T19:11:43.445Z,1725563503.445 [DATMMP](INFO): Starting 2024-09-05T19:11:43.447Z,1725563503.447 [DATMMP](INFO): Sent Tx Power Config 8 2024-09-05T19:11:43.448Z,1725563503.448 [DATMMP](INFO): Setting local address: 8 2024-09-05T19:11:43.451Z,1725563503.451 [DATMMP](INFO): Setting time to: 19:11:43 And date to:9/5/2024 2024-09-05T19:11:43.697Z,1725563503.697 [DATMMP](INFO): Starting 2024-09-05T19:11:43.697Z,1725563503.697 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T19:11:43.698Z,1725563503.698 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2024-09-05T19:11:43.698Z,1725563503.698 [DATMMP](INFO): Received message type: mdmpower 2024-09-05T19:11:43.698Z,1725563503.698 [DATMMP](INFO): Handled 2024-09-05T19:11:43.949Z,1725563503.949 [DATMMP](INFO): Starting 2024-09-05T19:11:43.949Z,1725563503.949 [DATMMP](INFO): GSXN notify for xid: 10 2024-09-05T19:11:43.950Z,1725563503.950 [DATMMP](INFO): sreg_txpower rx: x8 2024-09-05T19:11:43.950Z,1725563503.950 [DATMMP](INFO): Received message type: sreg_txpower 2024-09-05T19:11:43.950Z,1725563503.950 [DATMMP](INFO): Match for : sreg_txpower 2024-09-05T19:11:43.950Z,1725563503.950 [DATMMP](INFO): ACK 2024-09-05T19:11:44.200Z,1725563504.200 [DATMMP](INFO): Starting 2024-09-05T19:11:44.201Z,1725563504.201 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T19:11:44.202Z,1725563504.202 [DATMMP](INFO): mdmlocal rx: x8 x0 x0 x0 2024-09-05T19:11:44.202Z,1725563504.202 [DATMMP](INFO): Received message type: mdmlocal 2024-09-05T19:11:44.202Z,1725563504.202 [DATMMP](INFO): Handled 2024-09-05T19:11:44.453Z,1725563504.453 [DATMMP](INFO): Starting 2024-09-05T19:11:44.453Z,1725563504.453 [DATMMP](INFO): GSXN notify for xid: 11 2024-09-05T19:11:44.454Z,1725563504.454 [DATMMP](INFO): sreg_locaddr rx: x8 2024-09-05T19:11:44.454Z,1725563504.454 [DATMMP](INFO): Received message type: sreg_locaddr 2024-09-05T19:11:44.454Z,1725563504.454 [DATMMP](INFO): Match for : sreg_locaddr 2024-09-05T19:11:44.454Z,1725563504.454 [DATMMP](INFO): ACK 2024-09-05T19:11:44.705Z,1725563504.705 [DATMMP](INFO): Starting 2024-09-05T19:11:44.705Z,1725563504.705 [DATMMP](INFO): GSXN notify for xid: 12 2024-09-05T19:11:44.706Z,1725563504.706 [DATMMP](INFO): timedate rx: x0 x13 xB x2B x8 x5 x7C x0 2024-09-05T19:11:44.706Z,1725563504.706 [DATMMP](INFO): Received message type: timedate 2024-09-05T19:11:44.707Z,1725563504.707 [DATMMP](INFO): Match for : timedate 2024-09-05T19:11:44.707Z,1725563504.707 [DATMMP](INFO): ACK 2024-09-05T19:11:44.957Z,1725563504.957 [DATMMP](INFO): Starting 2024-09-05T19:11:44.957Z,1725563504.957 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T19:11:44.958Z,1725563504.958 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2024-09-05T19:11:44.958Z,1725563504.958 [DATMMP](INFO): Received message type: sys_verb 2024-09-05T19:11:44.958Z,1725563504.958 [DATMMP](INFO): Handled 2024-09-05T19:11:45.208Z,1725563505.208 [DATMMP](INFO): Starting 2024-09-05T19:11:45.209Z,1725563505.209 [DATMMP](INFO): GSXN notify for xid: 13 2024-09-05T19:11:45.210Z,1725563505.210 [DATMMP](INFO): sreg_verbosity rx: x0 2024-09-05T19:11:45.210Z,1725563505.210 [DATMMP](INFO): Received message type: sreg_verbosity 2024-09-05T19:11:45.210Z,1725563505.210 [DATMMP](INFO): Match for : sreg_verbosity 2024-09-05T19:11:45.210Z,1725563505.210 [DATMMP](INFO): ACK 2024-09-05T19:11:45.461Z,1725563505.461 [DATMMP](INFO): Starting 2024-09-05T19:11:45.461Z,1725563505.461 [DATMMP](INFO): GSXN notify for xid: 14 2024-09-05T19:11:45.462Z,1725563505.462 [DATMMP](INFO): version rx: x2 x5 2024-09-05T19:11:45.462Z,1725563505.462 [DATMMP](INFO): DatMMPVersion: 2.5 2024-09-05T19:11:45.463Z,1725563505.463 [DATMMP](INFO): Received message type: version 2024-09-05T19:11:45.463Z,1725563505.463 [DATMMP](INFO): Match for : version 2024-09-05T19:11:45.463Z,1725563505.463 [DATMMP](INFO): ACK 2024-09-05T19:11:45.463Z,1725563505.463 [DATMMP](INFO): in MMP mode: config complete going to runnable 2024-09-05T19:11:55.502Z,1725563515.502 [NAL9602](INFO): Powering up NAL9602 2024-09-05T19:11:59.841Z,1725563519.841 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T19:11:59.842Z,1725563519.842 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2024-09-05T19:11:59.842Z,1725563519.842 [DATMMP](INFO): Received message type: timesync 2024-09-05T19:11:59.842Z,1725563519.842 [DATMMP](INFO): Handled 2024-09-05T19:12:06.410Z,1725563526.410 [NAL9602](INFO): NAL9602 initialized 2024-09-05T19:12:09.933Z,1725563529.933 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T19:12:09.934Z,1725563529.934 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-09-05T19:12:09.934Z,1725563529.934 [DATMMP](INFO): Received message type: timesync 2024-09-05T19:12:09.934Z,1725563529.934 [DATMMP](INFO): Handled 2024-09-05T19:12:37.159Z,1725563557.159 [SBIT](IMPORTANT): Beginning Startup BIT 2024-09-05T19:12:37.164Z,1725563557.164 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T19:12:40.754Z,1725563560.754 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-05T19:12:48.042Z,1725563568.042 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-09-05T19:12:48.456Z,1725563568.456 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T19:12:54.481Z,1725563574.481 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-05T19:12:59.434Z,1725563579.434 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-09-05T19:12:59.849Z,1725563579.849 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T19:13:10.682Z,1725563590.682 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002387 CHAN A1 (24V): 0.000137 CHAN A2 (12V): -0.002427 CHAN A3 (5V): -0.001621 CHAN B0 (3.3V): -0.000369 CHAN B1 (3.15aV): -0.000163 CHAN B2 (3.15bV): 0.000336 CHAN B3 (GND): 0.000483 OPEN: -0.000421 Full Scale: +/- 1 mA 2024-09-05T19:13:31.266Z,1725563611.266 [SBIT](IMPORTANT): SBIT PASSED 2024-09-05T19:13:31.267Z,1725563611.267 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-09-05T19:13:31.267Z,1725563611.267 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-09-05T19:13:31.268Z,1725563611.268 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=0 bool; 2024-09-05T19:13:31.268Z,1725563611.268 [SBIT](IMPORTANT): CANONSampler.simulateHardware=0 bool; 2024-09-05T19:13:31.268Z,1725563611.268 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-09-05T19:13:31.268Z,1725563611.268 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-09-05T19:13:31.268Z,1725563611.268 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-09-05T19:13:31.268Z,1725563611.268 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter; 2024-09-05T19:13:31.268Z,1725563611.268 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2024-09-05T19:13:31.269Z,1725563611.269 [SBIT](IMPORTANT): Express none _.predict_plankton_data_pct_dinos_sqerr; 2024-09-05T19:13:31.269Z,1725563611.269 [SBIT](IMPORTANT): Normal all WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2024-09-05T19:13:31.269Z,1725563611.269 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=243.573504 cubic_centimeter; 2024-09-05T19:13:31.269Z,1725563611.269 [SBIT](IMPORTANT): VerticalControl.massDefault=1.25 centimeter; 2024-09-05T19:13:31.670Z,1725563611.670 [MissionManager](IMPORTANT): Started mission Startup 2024-09-05T19:13:31.671Z,1725563611.671 [Startup] Running Loop=1 2024-09-05T19:13:31.671Z,1725563611.671 [Startup](DEBUG): Aggregate::initialize Startup 2024-09-05T19:13:31.671Z,1725563611.671 [Startup:A.GoToSurface] Running Loop=1 2024-09-05T19:13:31.671Z,1725563611.671 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-09-05T19:13:31.671Z,1725563611.671 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-09-05T19:13:31.672Z,1725563611.672 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-09-05T19:13:31.673Z,1725563611.673 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-09-05T19:13:31.675Z,1725563611.675 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-09-05T19:13:31.678Z,1725563611.678 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-09-05T19:13:31.681Z,1725563611.681 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-09-05T19:13:31.697Z,1725563611.697 [Startup:StartupSatComms] Running Loop=1 2024-09-05T19:13:31.697Z,1725563611.697 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-09-05T19:13:31.699Z,1725563611.699 [Startup:StartupSatComms:A] Running Loop=1 2024-09-05T19:13:32.072Z,1725563612.072 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-09-05T19:14:26.624Z,1725563666.624 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-09-05T19:14:26.624Z,1725563666.624 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T19:14:26.689Z,1725563666.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T19:14:27.102Z,1725563667.102 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T19:14:27.102Z,1725563667.102 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-09-05T19:14:31.886Z,1725563671.886 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-05T19:13:31.7Z 2024-09-05T19:14:31.886Z,1725563671.886 [Startup:StartupSatComms:A] Stopped 2024-09-05T19:14:31.886Z,1725563671.886 [Startup:StartupSatComms:B] Running Loop=1 2024-09-05T19:14:32.266Z,1725563672.266 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-09-05T19:15:01.776Z,1725563701.776 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004486 2024-09-05T19:15:09.168Z,1725563709.168 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240904T224813/Courier0367.lzma 2024-09-05T19:15:10.171Z,1725563710.171 [DataOverHttps](INFO): Moved sent file to Logs/20240904T224813/Courier0367.lzma.bak 2024-09-05T19:15:10.171Z,1725563710.171 [DataOverHttps](INFO): SBD MOMSN=23131406 2024-09-05T19:15:26.080Z,1725563726.080 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240904T224813/Courier0370.lzma 2024-09-05T19:15:27.083Z,1725563727.083 [DataOverHttps](INFO): Moved sent file to Logs/20240904T224813/Courier0370.lzma.bak 2024-09-05T19:15:27.083Z,1725563727.083 [DataOverHttps](INFO): SBD MOMSN=23131408 2024-09-05T19:15:32.135Z,1725563732.135 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-05T19:14:31.9Z 2024-09-05T19:15:32.135Z,1725563732.135 [Startup:StartupSatComms:B] Stopped 2024-09-05T19:15:32.135Z,1725563732.135 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-09-05T19:15:32.135Z,1725563732.135 [Startup:StartupSatComms] Stopped 2024-09-05T19:15:32.135Z,1725563732.135 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-09-05T19:15:32.140Z,1725563732.140 [Startup](INFO): Completed Startup 2024-09-05T19:15:32.141Z,1725563732.141 [MissionManager](INFO): Startup is completed. 2024-09-05T19:15:32.141Z,1725563732.141 [MissionManager](INFO): Uninitializing Mission Startup 2024-09-05T19:15:32.141Z,1725563732.141 [Startup] Stopped 2024-09-05T19:15:32.141Z,1725563732.141 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-09-05T19:15:32.141Z,1725563732.141 [Startup:A.GoToSurface] Stopped 2024-09-05T19:15:32.141Z,1725563732.141 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-09-05T19:15:32.541Z,1725563732.541 [MissionManager](IMPORTANT): Started mission Default 2024-09-05T19:15:32.541Z,1725563732.541 [Default] Running Loop=1 2024-09-05T19:15:32.541Z,1725563732.541 [Default](DEBUG): Aggregate::initialize Default 2024-09-05T19:15:32.542Z,1725563732.542 [Default:B.GoToSurface] Running Loop=1 2024-09-05T19:15:32.542Z,1725563732.542 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-09-05T19:15:32.542Z,1725563732.542 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-09-05T19:15:32.542Z,1725563732.542 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-09-05T19:15:32.542Z,1725563732.542 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-09-05T19:15:32.543Z,1725563732.543 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-09-05T19:15:32.543Z,1725563732.543 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-09-05T19:15:32.543Z,1725563732.543 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-09-05T19:15:32.544Z,1725563732.544 [Default:A.Wait] Running Loop=1 2024-09-05T19:15:32.544Z,1725563732.544 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-09-05T19:15:42.223Z,1725563742.223 [NAL9602](INFO): SBD MO Status=2, MOMSN=56383, MT Status=2, MTMSN=0 2024-09-05T19:15:42.223Z,1725563742.223 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-05T19:15:43.056Z,1725563743.056 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240905T191115/Courier0000.lzma 2024-09-05T19:15:44.058Z,1725563744.058 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Courier0000.lzma.bak 2024-09-05T19:15:44.059Z,1725563744.059 [DataOverHttps](INFO): SBD MOMSN=23131411 2024-09-05T19:15:45.870Z,1725563745.870 [Default:A.Wait](INFO): Done Waiting. 2024-09-05T19:15:45.870Z,1725563745.870 [Default:A.Wait] Stopped 2024-09-05T19:15:45.870Z,1725563745.870 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T19:15:46.274Z,1725563746.274 [Default:CheckIn] Running Loop=1 2024-09-05T19:15:46.274Z,1725563746.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T19:15:46.274Z,1725563746.274 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T19:15:46.696Z,1725563746.696 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-09-05T19:16:19.777Z,1725563779.777 [CommandExec](IMPORTANT): got command failComponent 2024-09-05T19:16:19.777Z,1725563779.777 [CommandExec](IMPORTANT): Failed components: 2024-09-05T19:16:19.778Z,1725563779.778 [CommandExec](IMPORTANT): No failed Components. 2024-09-05T19:16:28.469Z,1725563788.469 [CommandExec](IMPORTANT): got command get CANONSampler.loadAtStartup 2024-09-05T19:16:28.469Z,1725563788.469 [CommandExec](IMPORTANT): CANONSampler.loadAtStartup 0 bool 2024-09-05T19:16:30.345Z,1725563790.345 [CommandExec](IMPORTANT): got command burn 2024-09-05T19:16:30.345Z,1725563790.345 [CommandExec](FAULT): Incomplete syntax. Try: help burn 2024-09-05T19:16:32.634Z,1725563792.634 [CommandExec](IMPORTANT): got command burn on 2024-09-05T19:16:32.634Z,1725563792.634 [CommandExec](IMPORTANT): Activating dropweight wire 2024-09-05T19:17:10.294Z,1725563830.294 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-09-05T19:17:13.546Z,1725563833.546 [CommandExec](IMPORTANT): got command burn off 2024-09-05T19:17:13.546Z,1725563833.546 [CommandExec](IMPORTANT): Deactivating dropweight wire 2024-09-05T19:17:27.676Z,1725563847.676 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-09-05T19:17:27.676Z,1725563847.676 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T19:17:27.741Z,1725563847.741 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T19:17:28.166Z,1725563848.166 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T19:17:28.166Z,1725563848.166 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-09-05T19:17:43.433Z,1725563863.433 [BPC1](ERROR): Battery stick #16 (s/n: 0240) reported OVER_TEMP_ALARM. Status code: 0x1717. 2024-09-05T19:17:43.434Z,1725563863.434 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 2. 2024-09-05T19:17:43.445Z,1725563863.445 [BPC1](INFO): Received data from all battery sticks. 2024-09-05T19:20:18.486Z,1725564018.486 [DataOverHttps](IMPORTANT): SBD MTMSN=20240905T192017 2024-09-05T19:20:28.597Z,1725564028.597 [DataOverHttps](INFO): Received command: strobe off 2024-09-05T19:20:28.599Z,1725564028.599 [CommandExec](IMPORTANT): got command strobe off 2024-09-05T19:20:28.599Z,1725564028.599 [CommandExec](IMPORTANT): Deactivating strobe 2024-09-05T19:20:28.786Z,1725564028.786 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-09-05T19:20:28.786Z,1725564028.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T19:20:28.856Z,1725564028.856 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T19:20:29.221Z,1725564029.221 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T19:20:29.221Z,1725564029.221 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-09-05T19:20:46.535Z,1725564046.535 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-05T19:15:46.3Z 2024-09-05T19:20:46.535Z,1725564046.535 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T19:20:46.535Z,1725564046.535 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T19:20:46.955Z,1725564046.955 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-09-05T19:20:56.864Z,1725564056.864 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240905T191115/Courier0004.lzma 2024-09-05T19:20:57.866Z,1725564057.866 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Courier0004.lzma.bak 2024-09-05T19:20:57.867Z,1725564057.867 [DataOverHttps](INFO): SBD MOMSN=23131438 2024-09-05T19:21:13.664Z,1725564073.664 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240905T191115/Courier0007.lzma 2024-09-05T19:21:14.666Z,1725564074.666 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Courier0007.lzma.bak 2024-09-05T19:21:14.666Z,1725564074.666 [DataOverHttps](INFO): SBD MOMSN=23131442 2024-09-05T19:21:31.249Z,1725564091.249 [DataOverHttps](INFO): Sending 471 bytes from file Logs/20240904T224813/Express0365.lzma 2024-09-05T19:21:32.251Z,1725564092.251 [DataOverHttps](INFO): Moved sent file to Logs/20240904T224813/Express0365.lzma.bak 2024-09-05T19:21:32.251Z,1725564092.251 [DataOverHttps](INFO): SBD MOMSN=23131448 2024-09-05T19:21:47.972Z,1725564107.972 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240904T224813/Express0368.lzma 2024-09-05T19:21:48.977Z,1725564108.977 [DataOverHttps](INFO): Moved sent file to Logs/20240904T224813/Express0368.lzma.bak 2024-09-05T19:21:48.977Z,1725564108.977 [DataOverHttps](INFO): SBD MOMSN=23131471 2024-09-05T19:22:04.756Z,1725564124.756 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240904T224813/Express0371.lzma 2024-09-05T19:22:05.762Z,1725564125.762 [DataOverHttps](INFO): Moved sent file to Logs/20240904T224813/Express0371.lzma.bak 2024-09-05T19:22:05.763Z,1725564125.763 [DataOverHttps](INFO): SBD MOMSN=23131480 2024-09-05T19:22:07.602Z,1725564127.602 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-05T19:22:07.602Z,1725564127.602 [NAL9602] Data Fault, FailCount= 1 2024-09-05T19:22:07.602Z,1725564127.602 [NAL9602](ERROR): Data Fault 2024-09-05T19:22:07.717Z,1725564127.717 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-05T19:22:08.014Z,1725564128.014 [NAL9602](INFO): Powering down 2024-09-05T19:22:08.892Z,1725564128.892 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-05T19:22:08.892Z,1725564128.892 [NAL9602] No Fault, FailCount= 1 2024-09-05T19:22:25.158Z,1725564145.158 [DataOverHttps](INFO): Sending 915 bytes from file Logs/20240905T191115/Express0001.lzma 2024-09-05T19:22:26.158Z,1725564146.158 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Express0001.lzma.bak 2024-09-05T19:22:26.158Z,1725564146.158 [DataOverHttps](INFO): SBD MOMSN=23131485 2024-09-05T19:22:38.366Z,1725564158.366 [NAL9602](INFO): Powering up NAL9602 2024-09-05T19:22:43.553Z,1725564163.553 [DataOverHttps](INFO): Sending 446 bytes from file Logs/20240905T191115/Express0005.lzma 2024-09-05T19:22:44.554Z,1725564164.554 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Express0005.lzma.bak 2024-09-05T19:22:44.554Z,1725564164.554 [DataOverHttps](INFO): SBD MOMSN=23131511 2024-09-05T19:22:49.294Z,1725564169.294 [NAL9602](INFO): NAL9602 initialized 2024-09-05T19:23:03.184Z,1725564183.184 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240905T191115/Express0008.lzma 2024-09-05T19:23:04.187Z,1725564184.187 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Express0008.lzma.bak 2024-09-05T19:23:04.187Z,1725564184.187 [DataOverHttps](INFO): SBD MOMSN=23131529 2024-09-05T19:23:05.898Z,1725564185.898 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T19:23:05.899Z,1725564185.899 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T19:23:05.899Z,1725564185.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T19:23:29.720Z,1725564209.720 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-09-05T19:23:29.720Z,1725564209.720 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T19:23:29.790Z,1725564209.790 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T19:23:30.220Z,1725564210.220 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T19:23:30.220Z,1725564210.220 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-09-05T19:26:30.718Z,1725564390.718 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-09-05T19:26:30.718Z,1725564390.718 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T19:26:30.781Z,1725564390.781 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T19:26:31.204Z,1725564391.204 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T19:26:31.204Z,1725564391.204 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-09-05T19:28:06.492Z,1725564486.492 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-05T19:28:06.492Z,1725564486.492 [Default:CheckIn:C.Wait] Stopped 2024-09-05T19:28:06.492Z,1725564486.492 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T19:28:06.492Z,1725564486.492 [Default:CheckIn:D] Running Loop=1 2024-09-05T19:28:06.910Z,1725564486.910 [Default:CheckIn:D] Stopped 2024-09-05T19:28:06.910Z,1725564486.910 [Default:CheckIn:E] Running Loop=1 2024-09-05T19:28:07.323Z,1725564487.323 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.572805 min 2024-09-05T19:28:07.323Z,1725564487.323 [Default:CheckIn:E] Stopped 2024-09-05T19:28:07.323Z,1725564487.323 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-05T19:28:07.323Z,1725564487.323 [Default:CheckIn] Stopped 2024-09-05T19:28:07.323Z,1725564487.323 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-05T19:28:07.323Z,1725564487.323 [Default:CheckIn](INFO): Running loop #2 2024-09-05T19:28:07.323Z,1725564487.323 [Default:CheckIn] Running Loop=2 2024-09-05T19:28:07.323Z,1725564487.323 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T19:28:07.323Z,1725564487.323 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T19:29:31.749Z,1725564571.749 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-09-05T19:29:31.750Z,1725564571.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T19:29:31.809Z,1725564571.809 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T19:29:32.208Z,1725564572.208 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T19:29:32.209Z,1725564572.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-09-05T19:32:32.766Z,1725564752.766 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-09-05T19:32:32.766Z,1725564752.766 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T19:32:32.837Z,1725564752.837 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T19:32:33.244Z,1725564753.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T19:32:33.244Z,1725564753.244 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-09-05T19:32:50.166Z,1725564770.166 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-05T19:32:50.166Z,1725564770.166 [NAL9602] Data Fault, FailCount= 2 2024-09-05T19:32:50.166Z,1725564770.166 [NAL9602](ERROR): Data Fault 2024-09-05T19:32:50.302Z,1725564770.302 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-05T19:32:50.587Z,1725564770.587 [NAL9602](INFO): Powering down 2024-09-05T19:32:51.521Z,1725564771.521 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-05T19:32:51.521Z,1725564771.521 [NAL9602] No Fault, FailCount= 2 2024-09-05T19:33:07.540Z,1725564787.540 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-05T19:28:07.3Z 2024-09-05T19:33:07.540Z,1725564787.540 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T19:33:07.540Z,1725564787.540 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T19:33:15.168Z,1725564795.168 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240905T191115/Courier0010.lzma 2024-09-05T19:33:16.170Z,1725564796.170 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Courier0010.lzma.bak 2024-09-05T19:33:16.170Z,1725564796.170 [DataOverHttps](INFO): SBD MOMSN=23131561 2024-09-05T19:33:20.850Z,1725564800.850 [NAL9602](INFO): Powering up NAL9602 2024-09-05T19:33:31.465Z,1725564811.465 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20240905T191115/Express0011.lzma 2024-09-05T19:33:31.762Z,1725564811.762 [NAL9602](INFO): NAL9602 initialized 2024-09-05T19:33:32.466Z,1725564812.466 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Express0011.lzma.bak 2024-09-05T19:33:32.466Z,1725564812.466 [DataOverHttps](INFO): SBD MOMSN=23131563 2024-09-05T19:33:34.205Z,1725564814.205 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T19:33:34.205Z,1725564814.205 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T19:33:34.205Z,1725564814.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T19:34:29.742Z,1725564869.742 [CommandExec](IMPORTANT): got command ibit 2024-09-05T19:34:29.971Z,1725564869.971 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-09-05T19:34:29.971Z,1725564869.971 [IBIT](IMPORTANT): Beginning control surface checks. 2024-09-05T19:34:29.974Z,1725564869.974 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T19:34:40.901Z,1725564880.901 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002444 CHAN A1 (24V): 0.000298 CHAN A2 (12V): -0.002441 CHAN A3 (5V): -0.001504 CHAN B0 (3.3V): 0.000428 CHAN B1 (3.15aV): -0.000146 CHAN B2 (3.15bV): -0.000558 CHAN B3 (GND): -0.000066 OPEN: -0.000654 Full Scale: +/- 1 mA 2024-09-05T19:34:50.837Z,1725564890.837 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality 2024-09-05T19:35:20.044Z,1725564920.044 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193518.00,A,3648.13988,N,12147.22925,W,0.603,67.78,050924,,,A*47 2024-09-05T19:35:20.055Z,1725564920.055 [NAL9602](INFO): GPS fix at 20240905T193518: (36.802331, -121.787154) 2024-09-05T19:35:22.871Z,1725564922.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193521.00,A,3648.13940,N,12147.22934,W,0.117,67.78,050924,,,A*4B 2024-09-05T19:35:22.881Z,1725564922.881 [NAL9602](INFO): GPS fix at 20240905T193521: (36.802323, -121.787156) 2024-09-05T19:35:25.699Z,1725564925.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193524.00,A,3648.13949,N,12147.22930,W,0.039,67.78,050924,,,A*4E 2024-09-05T19:35:25.702Z,1725564925.702 [NAL9602](INFO): GPS fix at 20240905T193524: (36.802325, -121.787155) 2024-09-05T19:35:28.930Z,1725564928.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193527.00,A,3648.13948,N,12147.22935,W,0.058,67.78,050924,,,A*4E 2024-09-05T19:35:28.933Z,1725564928.933 [NAL9602](INFO): GPS fix at 20240905T193527: (36.802325, -121.787156) 2024-09-05T19:35:31.763Z,1725564931.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193530.00,A,3648.13964,N,12147.22936,W,0.097,67.78,050924,,,A*46 2024-09-05T19:35:31.765Z,1725564931.765 [NAL9602](INFO): GPS fix at 20240905T193530: (36.802327, -121.787156) 2024-09-05T19:35:34.991Z,1725564934.991 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193533.00,A,3648.13995,N,12147.22931,W,0.058,67.78,050924,,,A*4F 2024-09-05T19:35:34.994Z,1725564934.994 [NAL9602](INFO): GPS fix at 20240905T193533: (36.802332, -121.787155) 2024-09-05T19:35:37.819Z,1725564937.819 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193536.00,A,3648.14024,N,12147.22918,W,0.058,67.78,050924,,,A*45 2024-09-05T19:35:37.822Z,1725564937.822 [NAL9602](INFO): GPS fix at 20240905T193536: (36.802337, -121.787153) 2024-09-05T19:35:41.051Z,1725564941.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193539.00,A,3648.14045,N,12147.22911,W,0.019,67.78,050924,,,A*41 2024-09-05T19:35:41.054Z,1725564941.054 [NAL9602](INFO): GPS fix at 20240905T193539: (36.802341, -121.787152) 2024-09-05T19:35:43.883Z,1725564943.883 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193542.00,A,3648.14042,N,12147.22923,W,0.039,67.78,050924,,,A*49 2024-09-05T19:35:43.885Z,1725564943.885 [NAL9602](INFO): GPS fix at 20240905T193542: (36.802340, -121.787154) 2024-09-05T19:35:46.711Z,1725564946.711 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193545.00,A,3648.14046,N,12147.22914,W,0.000,67.78,050924,,,A*44 2024-09-05T19:35:46.713Z,1725564946.713 [NAL9602](INFO): GPS fix at 20240905T193545: (36.802341, -121.787152) 2024-09-05T19:35:49.947Z,1725564949.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193548.00,A,3648.14045,N,12147.22912,W,0.058,67.78,050924,,,A*41 2024-09-05T19:35:49.949Z,1725564949.949 [NAL9602](INFO): GPS fix at 20240905T193548: (36.802341, -121.787152) 2024-09-05T19:35:52.771Z,1725564952.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193551.00,A,3648.14038,N,12147.22918,W,0.039,67.78,050924,,,A*4E 2024-09-05T19:35:52.773Z,1725564952.773 [NAL9602](INFO): GPS fix at 20240905T193551: (36.802340, -121.787153) 2024-09-05T19:35:56.003Z,1725564956.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193554.00,A,3648.14035,N,12147.22926,W,0.039,67.78,050924,,,A*4B 2024-09-05T19:35:56.005Z,1725564956.005 [NAL9602](INFO): GPS fix at 20240905T193554: (36.802339, -121.787154) 2024-09-05T19:35:58.831Z,1725564958.831 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193557.00,A,3648.14038,N,12147.22931,W,0.039,67.78,050924,,,A*43 2024-09-05T19:35:58.833Z,1725564958.833 [NAL9602](INFO): GPS fix at 20240905T193557: (36.802340, -121.787155) 2024-09-05T19:36:02.063Z,1725564962.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193600.00,A,3648.14046,N,12147.22934,W,0.039,67.78,050924,,,A*4E 2024-09-05T19:36:02.065Z,1725564962.065 [NAL9602](INFO): GPS fix at 20240905T193600: (36.802341, -121.787156) 2024-09-05T19:36:04.891Z,1725564964.891 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193603.00,A,3648.14054,N,12147.22934,W,0.058,67.78,050924,,,A*49 2024-09-05T19:36:04.893Z,1725564964.893 [NAL9602](INFO): GPS fix at 20240905T193603: (36.802342, -121.787156) 2024-09-05T19:36:07.714Z,1725564967.714 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193606.00,A,3648.14059,N,12147.22933,W,0.058,67.78,050924,,,A*46 2024-09-05T19:36:07.716Z,1725564967.716 [NAL9602](INFO): GPS fix at 20240905T193606: (36.802343, -121.787155) 2024-09-05T19:36:10.947Z,1725564970.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193609.00,A,3648.14059,N,12147.22938,W,0.039,67.78,050924,,,A*45 2024-09-05T19:36:10.949Z,1725564970.949 [NAL9602](INFO): GPS fix at 20240905T193609: (36.802343, -121.787156) 2024-09-05T19:36:13.774Z,1725564973.774 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193612.00,A,3648.14061,N,12147.22946,W,0.019,67.78,050924,,,A*4F 2024-09-05T19:36:13.776Z,1725564973.776 [NAL9602](INFO): GPS fix at 20240905T193612: (36.802343, -121.787158) 2024-09-05T19:36:17.011Z,1725564977.011 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193615.00,A,3648.14065,N,12147.22944,W,0.039,67.78,050924,,,A*4C 2024-09-05T19:36:17.014Z,1725564977.014 [NAL9602](INFO): GPS fix at 20240905T193615: (36.802344, -121.787157) 2024-09-05T19:36:19.835Z,1725564979.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193618.00,A,3648.14069,N,12147.22935,W,0.039,67.78,050924,,,A*4B 2024-09-05T19:36:19.838Z,1725564979.838 [NAL9602](INFO): GPS fix at 20240905T193618: (36.802345, -121.787156) 2024-09-05T19:36:21.133Z,1725564981.133 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired. 2024-09-05T19:36:21.497Z,1725564981.497 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.650999 2024-09-05T19:36:21.498Z,1725564981.498 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-09-05T19:36:21.498Z,1725564981.498 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-09-05T19:36:21.887Z,1725564981.887 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-09-05T19:36:21.887Z,1725564981.887 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-09-05T19:36:21.887Z,1725564981.887 [IBIT](IMPORTANT): Pressure:10.416013 PSI 2024-09-05T19:36:21.888Z,1725564981.888 [IBIT](IMPORTANT): Humidity:15.773410 % 2024-09-05T19:36:22.297Z,1725564982.297 [IBIT](IMPORTANT): Vehicle Pitch:-1.240627 degrees 2024-09-05T19:36:22.297Z,1725564982.297 [IBIT](IMPORTANT): Vehicle Roll:1.757512 degrees 2024-09-05T19:36:22.297Z,1725564982.297 [IBIT](IMPORTANT): Vehicle Heading:14.652754 degrees 2024-09-05T19:36:22.695Z,1725564982.695 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-09-05T19:36:22.696Z,1725564982.696 [IBIT](IMPORTANT): buoyancyNeutral: 243.573502 cc 2024-09-05T19:36:22.696Z,1725564982.696 [IBIT](IMPORTANT): massDefault: 1.250000 cm 2024-09-05T19:36:22.705Z,1725564982.705 [IBIT](IMPORTANT): stopDepth: 250.000000 m 2024-09-05T19:36:22.705Z,1725564982.705 [IBIT](IMPORTANT): abortDepth: 270.000000 m 2024-09-05T19:36:22.705Z,1725564982.705 [IBIT](IMPORTANT): IBIT FAILED 2024-09-05T19:36:23.072Z,1725564983.072 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193621.00,A,3648.14074,N,12147.22916,W,0.039,67.78,050924,,,A*4C 2024-09-05T19:36:23.074Z,1725564983.074 [NAL9602](INFO): GPS fix at 20240905T193621: (36.802346, -121.787153) 2024-09-05T19:36:55.405Z,1725565015.405 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-05T19:36:55.521Z,1725565015.521 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-05T19:38:34.803Z,1725565114.803 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-05T19:38:34.803Z,1725565114.803 [Default:CheckIn:C.Wait] Stopped 2024-09-05T19:38:34.803Z,1725565114.803 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T19:38:34.803Z,1725565114.803 [Default:CheckIn:D] Running Loop=1 2024-09-05T19:38:35.212Z,1725565115.212 [Default:CheckIn:D] Stopped 2024-09-05T19:38:35.212Z,1725565115.212 [Default:CheckIn:E] Running Loop=1 2024-09-05T19:38:35.612Z,1725565115.612 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.044509 min 2024-09-05T19:38:35.612Z,1725565115.612 [Default:CheckIn:E] Stopped 2024-09-05T19:38:35.612Z,1725565115.612 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-05T19:38:35.612Z,1725565115.612 [Default:CheckIn] Stopped 2024-09-05T19:38:35.633Z,1725565115.633 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-05T19:38:35.633Z,1725565115.633 [Default:CheckIn](INFO): Running loop #3 2024-09-05T19:38:35.633Z,1725565115.633 [Default:CheckIn] Running Loop=3 2024-09-05T19:38:35.633Z,1725565115.633 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T19:38:35.633Z,1725565115.633 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T19:38:37.619Z,1725565117.619 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193835.00,A,3648.14155,N,12147.22746,W,0.078,67.78,050924,,,D*4E 2024-09-05T19:38:37.622Z,1725565117.622 [NAL9602](INFO): GPS fix at 20240905T193835: (36.802359, -121.787124) 2024-09-05T19:38:37.632Z,1725565117.632 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T19:38:37.632Z,1725565117.632 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T19:38:44.197Z,1725565124.197 [CommandExec](IMPORTANT): got command ibit 2024-09-05T19:38:44.578Z,1725565124.578 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-09-05T19:38:44.578Z,1725565124.578 [IBIT](IMPORTANT): Beginning control surface checks. 2024-09-05T19:38:44.581Z,1725565124.581 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T19:38:45.597Z,1725565125.597 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240905T191115/Courier0013.lzma 2024-09-05T19:38:46.599Z,1725565126.599 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Courier0013.lzma.bak 2024-09-05T19:38:46.599Z,1725565126.599 [DataOverHttps](INFO): SBD MOMSN=23131580 2024-09-05T19:38:46.678Z,1725565126.678 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-05T19:38:51.133Z,1725565131.133 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-05T19:38:55.657Z,1725565135.657 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-09-05T19:38:56.006Z,1725565136.006 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T19:38:57.649Z,1725565137.649 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-05T19:39:06.790Z,1725565146.790 [DataOverHttps](INFO): Sending 877 bytes from file Logs/20240905T191115/Express0014.lzma 2024-09-05T19:39:06.818Z,1725565146.818 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-09-05T19:39:07.242Z,1725565147.242 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T19:39:07.790Z,1725565147.790 [DataOverHttps](INFO): Moved sent file to Logs/20240905T191115/Express0014.lzma.bak 2024-09-05T19:39:07.790Z,1725565147.790 [DataOverHttps](INFO): SBD MOMSN=23131588 2024-09-05T19:39:13.631Z,1725565153.631 [NAL9602](INFO): SBD MO Status=0, MOMSN=56383, MT Status=0, MTMSN=0 2024-09-05T19:39:13.631Z,1725565153.631 [NAL9602](INFO): No messages in MT queue 2024-09-05T19:39:14.847Z,1725565154.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193913.00,A,3648.14098,N,12147.22871,W,0.019,67.78,050924,,,D*47 2024-09-05T19:39:14.849Z,1725565154.849 [NAL9602](INFO): GPS fix at 20240905T193913: (36.802350, -121.787145) 2024-09-05T19:39:18.095Z,1725565158.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193916.00,A,3648.14091,N,12147.22881,W,0.194,67.78,050924,,,D*40 2024-09-05T19:39:18.097Z,1725565158.097 [NAL9602](INFO): GPS fix at 20240905T193916: (36.802349, -121.787147) 2024-09-05T19:39:18.238Z,1725565158.238 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002435 CHAN A1 (24V): 0.000109 CHAN A2 (12V): -0.002109 CHAN A3 (5V): -0.001410 CHAN B0 (3.3V): 0.000526 CHAN B1 (3.15aV): -0.000086 CHAN B2 (3.15bV): -0.000544 CHAN B3 (GND): 0.000214 OPEN: -0.000214 Full Scale: +/- 1 mA 2024-09-05T19:39:20.915Z,1725565160.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193919.00,A,3648.14135,N,12147.22911,W,0.039,67.78,050924,,,D*4E 2024-09-05T19:39:20.917Z,1725565160.917 [NAL9602](INFO): GPS fix at 20240905T193919: (36.802356, -121.787152) 2024-09-05T19:39:23.743Z,1725565163.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193922.00,A,3648.14196,N,12147.22959,W,0.078,67.78,050924,,,D*46 2024-09-05T19:39:23.745Z,1725565163.745 [NAL9602](INFO): GPS fix at 20240905T193922: (36.802366, -121.787160) 2024-09-05T19:39:26.980Z,1725565166.980 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193925.00,A,3648.14201,N,12147.22974,W,0.136,67.78,050924,,,D*48 2024-09-05T19:39:26.982Z,1725565166.982 [NAL9602](INFO): GPS fix at 20240905T193925: (36.802367, -121.787162) 2024-09-05T19:39:29.803Z,1725565169.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193928.00,A,3648.14172,N,12147.22983,W,0.019,67.78,050924,,,D*46 2024-09-05T19:39:29.806Z,1725565169.806 [NAL9602](INFO): GPS fix at 20240905T193928: (36.802362, -121.787164) 2024-09-05T19:39:31.099Z,1725565171.099 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802361 Longitude: -121.787163 2024-09-05T19:39:31.470Z,1725565171.470 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.650999 2024-09-05T19:39:31.470Z,1725565171.470 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-09-05T19:39:31.470Z,1725565171.470 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-09-05T19:39:31.878Z,1725565171.878 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-09-05T19:39:31.878Z,1725565171.878 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-09-05T19:39:31.878Z,1725565171.878 [IBIT](IMPORTANT): Pressure:10.302545 PSI 2024-09-05T19:39:31.879Z,1725565171.879 [IBIT](IMPORTANT): Humidity:15.694055 % 2024-09-05T19:39:32.254Z,1725565172.254 [IBIT](IMPORTANT): Vehicle Pitch:-1.251573 degrees 2024-09-05T19:39:32.254Z,1725565172.254 [IBIT](IMPORTANT): Vehicle Roll:1.752039 degrees 2024-09-05T19:39:32.254Z,1725565172.254 [IBIT](IMPORTANT): Vehicle Heading:14.571424 degrees 2024-09-05T19:39:32.665Z,1725565172.665 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-09-05T19:39:32.666Z,1725565172.666 [IBIT](IMPORTANT): buoyancyNeutral: 243.573502 cc 2024-09-05T19:39:32.666Z,1725565172.666 [IBIT](IMPORTANT): massDefault: 1.250000 cm 2024-09-05T19:39:32.666Z,1725565172.666 [IBIT](IMPORTANT): stopDepth: 250.000000 m 2024-09-05T19:39:32.666Z,1725565172.666 [IBIT](IMPORTANT): abortDepth: 270.000000 m 2024-09-05T19:39:32.667Z,1725565172.667 [IBIT](IMPORTANT): IBIT PASSED 2024-09-05T19:39:33.083Z,1725565173.083 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T19:39:33.084Z,1725565173.084 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T19:39:33.084Z,1725565173.084 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T19:39:54.885Z,1725565194.885 [CommandExec](IMPORTANT): got command ibit 2024-09-05T19:39:54.966Z,1725565194.966 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-09-05T19:39:54.966Z,1725565194.966 [IBIT](IMPORTANT): Beginning control surface checks. 2024-09-05T19:39:54.969Z,1725565194.969 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T19:39:56.471Z,1725565196.471 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193954.00,A,3648.14114,N,12147.23101,W,0.136,67.78,050924,,,D*42 2024-09-05T19:39:56.473Z,1725565196.473 [NAL9602](INFO): GPS fix at 20240905T193954: (36.802352, -121.787183) 2024-09-05T19:39:58.890Z,1725565198.890 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193957.00,A,3648.14136,N,12147.23115,W,0.097,67.78,050924,,,D*4E 2024-09-05T19:39:58.903Z,1725565198.903 [NAL9602](INFO): GPS fix at 20240905T193957: (36.802356, -121.787186) 2024-09-05T19:40:02.122Z,1725565202.122 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194000.00,A,3648.14154,N,12147.23132,W,0.078,67.78,050924,,,D*42 2024-09-05T19:40:02.124Z,1725565202.124 [NAL9602](INFO): GPS fix at 20240905T194000: (36.802359, -121.787189) 2024-09-05T19:40:04.959Z,1725565204.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194003.00,A,3648.14171,N,12147.23139,W,0.117,67.78,050924,,,D*45 2024-09-05T19:40:04.961Z,1725565204.961 [NAL9602](INFO): GPS fix at 20240905T194003: (36.802362, -121.787190) 2024-09-05T19:40:05.834Z,1725565205.834 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-09-05T19:40:06.313Z,1725565206.313 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T19:40:07.779Z,1725565207.779 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194006.00,A,3648.14172,N,12147.23137,W,0.058,67.78,050924,,,D*47 2024-09-05T19:40:07.786Z,1725565207.786 [NAL9602](INFO): GPS fix at 20240905T194006: (36.802362, -121.787189) 2024-09-05T19:40:11.019Z,1725565211.019 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194009.00,A,3648.14133,N,12147.23108,W,0.117,67.78,050924,,,D*4B 2024-09-05T19:40:11.022Z,1725565211.022 [NAL9602](INFO): GPS fix at 20240905T194009: (36.802355, -121.787185) 2024-09-05T19:40:13.843Z,1725565213.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194012.00,A,3648.14097,N,12147.23089,W,0.097,67.78,050924,,,D*4F 2024-09-05T19:40:13.855Z,1725565213.855 [NAL9602](INFO): GPS fix at 20240905T194012: (36.802349, -121.787182) 2024-09-05T19:40:17.086Z,1725565217.086 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194015.00,A,3648.14065,N,12147.23075,W,0.097,67.78,050924,,,D*46 2024-09-05T19:40:17.088Z,1725565217.088 [NAL9602](INFO): GPS fix at 20240905T194015: (36.802344, -121.787179) 2024-09-05T19:40:17.167Z,1725565217.167 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002609 CHAN A1 (24V): 0.000736 CHAN A2 (12V): -0.002603 CHAN A3 (5V): -0.001159 CHAN B0 (3.3V): -0.000030 CHAN B1 (3.15aV): 0.000454 CHAN B2 (3.15bV): 0.000125 CHAN B3 (GND): 0.000318 OPEN: -0.000239 Full Scale: +/- 1 mA 2024-09-05T19:40:19.899Z,1725565219.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194018.00,A,3648.14043,N,12147.23058,W,0.039,67.78,050924,,,D*44 2024-09-05T19:40:19.902Z,1725565219.902 [NAL9602](INFO): GPS fix at 20240905T194018: (36.802340, -121.787176) 2024-09-05T19:40:22.741Z,1725565222.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194021.00,A,3648.14029,N,12147.23045,W,0.097,67.78,050924,,,D*4A 2024-09-05T19:40:22.743Z,1725565222.743 [NAL9602](INFO): GPS fix at 20240905T194021: (36.802338, -121.787174) 2024-09-05T19:40:25.968Z,1725565225.968 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194024.00,A,3648.14029,N,12147.23047,W,0.058,67.78,050924,,,D*4E 2024-09-05T19:40:25.970Z,1725565225.970 [NAL9602](INFO): GPS fix at 20240905T194024: (36.802338, -121.787175) 2024-09-05T19:40:28.799Z,1725565228.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194027.00,A,3648.14049,N,12147.23063,W,0.136,67.78,050924,,,D*44 2024-09-05T19:40:28.802Z,1725565228.802 [NAL9602](INFO): GPS fix at 20240905T194027: (36.802341, -121.787177) 2024-09-05T19:40:32.026Z,1725565232.026 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194030.00,A,3648.14066,N,12147.23075,W,0.058,67.78,050924,,,D*41 2024-09-05T19:40:32.029Z,1725565232.029 [NAL9602](INFO): GPS fix at 20240905T194030: (36.802344, -121.787179) 2024-09-05T19:40:34.859Z,1725565234.859 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194033.00,A,3648.14054,N,12147.23073,W,0.117,67.78,050924,,,D*4F 2024-09-05T19:40:34.861Z,1725565234.861 [NAL9602](INFO): GPS fix at 20240905T194033: (36.802342, -121.787179) 2024-09-05T19:40:38.087Z,1725565238.087 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194036.00,A,3648.14036,N,12147.23067,W,0.058,67.78,050924,,,D*41 2024-09-05T19:40:38.090Z,1725565238.090 [NAL9602](INFO): GPS fix at 20240905T194036: (36.802339, -121.787178) 2024-09-05T19:40:40.915Z,1725565240.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194039.00,A,3648.14035,N,12147.23065,W,0.058,67.78,050924,,,D*4F 2024-09-05T19:40:40.918Z,1725565240.918 [NAL9602](INFO): GPS fix at 20240905T194039: (36.802339, -121.787177) 2024-09-05T19:40:41.366Z,1725565241.366 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802338 Longitude: -121.787178 2024-09-05T19:40:41.736Z,1725565241.736 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.650999 2024-09-05T19:40:41.736Z,1725565241.736 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-09-05T19:40:41.736Z,1725565241.736 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-09-05T19:40:42.205Z,1725565242.205 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-09-05T19:40:42.205Z,1725565242.205 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-09-05T19:40:42.205Z,1725565242.205 [IBIT](IMPORTANT): Pressure:10.282007 PSI 2024-09-05T19:40:42.205Z,1725565242.205 [IBIT](IMPORTANT): Humidity:15.645221 % 2024-09-05T19:40:42.566Z,1725565242.566 [IBIT](IMPORTANT): Vehicle Pitch:-1.253608 degrees 2024-09-05T19:40:42.567Z,1725565242.567 [IBIT](IMPORTANT): Vehicle Roll:1.746279 degrees 2024-09-05T19:40:42.567Z,1725565242.567 [IBIT](IMPORTANT): Vehicle Heading:14.597425 degrees 2024-09-05T19:40:42.953Z,1725565242.953 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-09-05T19:40:42.953Z,1725565242.953 [IBIT](IMPORTANT): buoyancyNeutral: 243.573502 cc 2024-09-05T19:40:42.953Z,1725565242.953 [IBIT](IMPORTANT): massDefault: 1.250000 cm 2024-09-05T19:40:42.953Z,1725565242.953 [IBIT](IMPORTANT): stopDepth: 250.000000 m 2024-09-05T19:40:42.954Z,1725565242.954 [IBIT](IMPORTANT): abortDepth: 270.000000 m 2024-09-05T19:40:42.954Z,1725565242.954 [IBIT](IMPORTANT): IBIT PASSED 2024-09-05T19:41:03.457Z,1725565263.457 [CommandExec](IMPORTANT): got command failComponent 2024-09-05T19:41:03.457Z,1725565263.457 [CommandExec](IMPORTANT): Failed components: 2024-09-05T19:41:03.457Z,1725565263.457 [CommandExec](IMPORTANT): No failed Components. 2024-09-05T19:41:13.242Z,1725565273.242 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-05T19:41:13.313Z,1725565273.313 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-05T19:41:43.311Z,1725565303.311 [DataOverHttps](IMPORTANT): SBD MTMSN=20240905T194142 2024-09-05T19:41:51.052Z,1725565311.052 [DataOverHttps](INFO): Received command: restart app 2024-09-05T19:41:51.090Z,1725565311.090 [CommandExec](IMPORTANT): got command restart application 2024-09-05T19:41:52.093Z,1725565312.093 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-09-05T19:41:52.095Z,1725565312.095 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:52.097Z,1725565312.098 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-09-05T19:41:52.098Z,1725565312.098 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-09-05T19:41:52.099Z,1725565312.099 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:52.101Z,1725565312.101 [NavChartDb](INFO): Join timeout helper Thread ID is 3650 2024-09-05T19:41:52.101Z,1725565312.101 [CommandExec](INFO): Uninitializing the command executive. 2024-09-05T19:41:52.102Z,1725565312.102 [CommandExec](INFO): Uninitializing the command scheduler. 2024-09-05T19:41:52.102Z,1725565312.102 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:52.369Z,1725565312.369 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:52.369Z,1725565312.369 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:52.380Z,1725565312.380 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-09-05T19:41:52.380Z,1725565312.380 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:52.381Z,1725565312.381 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3651 2024-09-05T19:41:52.677Z,1725565312.677 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:52.677Z,1725565312.677 [WetLabsBB2FL](INFO): Powering down 2024-09-05T19:41:52.677Z,1725565312.677 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:52.680Z,1725565312.680 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-09-05T19:41:52.680Z,1725565312.680 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:52.681Z,1725565312.681 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3652 2024-09-05T19:41:53.501Z,1725565313.501 [CTD_Seabird](INFO): Powering down 2024-09-05T19:41:53.513Z,1725565313.513 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:53.513Z,1725565313.513 [CTD_Seabird](INFO): Powering down 2024-09-05T19:41:53.525Z,1725565313.525 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:53.536Z,1725565313.536 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-09-05T19:41:53.536Z,1725565313.536 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:53.537Z,1725565313.537 [Radio_Surface](INFO): Join timeout helper Thread ID is 3653 2024-09-05T19:41:53.776Z,1725565313.776 [Radio_Surface](INFO): Powering down 2024-09-05T19:41:53.777Z,1725565313.777 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:53.778Z,1725565313.778 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:53.780Z,1725565313.780 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-09-05T19:41:53.781Z,1725565313.781 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:53.781Z,1725565313.781 [Onboard](INFO): Join timeout helper Thread ID is 3654 2024-09-05T19:41:54.061Z,1725565314.061 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-09-05T19:41:58.156Z,1725565318.156 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:58.157Z,1725565318.157 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:58.177Z,1725565318.177 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-09-05T19:41:58.177Z,1725565318.177 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:58.177Z,1725565318.177 [DataOverHttps](INFO): Join timeout helper Thread ID is 3655 2024-09-05T19:41:59.081Z,1725565319.081 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:59.081Z,1725565319.081 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.093Z,1725565319.093 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2024-09-05T19:41:59.093Z,1725565319.093 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.094Z,1725565319.094 [DATMMP](INFO): Join timeout helper Thread ID is 3656 2024-09-05T19:41:59.317Z,1725565319.317 [DATMMP](INFO): Stop 2024-09-05T19:41:59.317Z,1725565319.317 [DATMMP](INFO): uninitialize 2024-09-05T19:41:59.317Z,1725565319.317 [DATMMP](INFO): Powering down 2024-09-05T19:41:59.385Z,1725565319.385 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:59.385Z,1725565319.385 [DATMMP](INFO): uninitialize 2024-09-05T19:41:59.385Z,1725565319.385 [DATMMP](INFO): Powering down 2024-09-05T19:41:59.386Z,1725565319.386 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.401Z,1725565319.401 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-09-05T19:41:59.401Z,1725565319.401 [logger ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.402Z,1725565319.402 [logger](INFO): Join timeout helper Thread ID is 3657 2024-09-05T19:41:59.433Z,1725565319.433 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:59.433Z,1725565319.433 [logger ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.445Z,1725565319.445 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-09-05T19:41:59.445Z,1725565319.445 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.446Z,1725565319.446 [CommandLine](INFO): Join timeout helper Thread ID is 3658 2024-09-05T19:41:59.501Z,1725565319.501 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:59.501Z,1725565319.501 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.505Z,1725565319.505 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-09-05T19:41:59.505Z,1725565319.505 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.506Z,1725565319.506 [CommandExec](INFO): Join timeout helper Thread ID is 3659 2024-09-05T19:41:59.507Z,1725565319.507 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-09-05T19:41:59.507Z,1725565319.507 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.507Z,1725565319.507 [controlThread](INFO): Join timeout helper Thread ID is 3660 2024-09-05T19:41:59.685Z,1725565319.685 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T19:41:59.685Z,1725565319.685 [controlThread](DEBUG): Uninitializing ControlThread 2024-09-05T19:41:59.685Z,1725565319.685 [AHRS_M2](INFO): Powering down 2024-09-05T19:41:59.757Z,1725565319.757 [NAL9602](INFO): Powering down 2024-09-05T19:41:59.759Z,1725565319.759 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-09-05T19:41:59.760Z,1725565319.760 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-09-05T19:41:59.761Z,1725565319.761 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-09-05T19:41:59.761Z,1725565319.761 [MissionManager](INFO): Uninitializing Mission Default 2024-09-05T19:41:59.761Z,1725565319.761 [Default] Stopped 2024-09-05T19:41:59.761Z,1725565319.761 [Default](DEBUG): Aggregate::uninitialize Default 2024-09-05T19:41:59.761Z,1725565319.761 [Default:B.GoToSurface] Stopped 2024-09-05T19:41:59.761Z,1725565319.761 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-09-05T19:41:59.761Z,1725565319.761 [Default:CheckIn] Stopped 2024-09-05T19:41:59.762Z,1725565319.762 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-05T19:41:59.762Z,1725565319.762 [Default:CheckIn:C.Wait] Stopped 2024-09-05T19:41:59.762Z,1725565319.762 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T19:41:59.765Z,1725565319.765 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-09-05T19:41:59.765Z,1725565319.765 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-09-05T19:41:59.765Z,1725565319.765 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-09-05T19:41:59.765Z,1725565319.765 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-09-05T19:41:59.766Z,1725565319.766 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-09-05T19:41:59.766Z,1725565319.766 [BuoyancyServo](INFO): Powering down 2024-09-05T19:41:59.781Z,1725565319.781 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-09-05T19:41:59.781Z,1725565319.781 [ElevatorServo](INFO): Powering down 2024-09-05T19:41:59.781Z,1725565319.781 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-09-05T19:41:59.782Z,1725565319.782 [MassServo](INFO): Powering down 2024-09-05T19:41:59.782Z,1725565319.782 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-09-05T19:41:59.782Z,1725565319.782 [RudderServo](INFO): Powering down 2024-09-05T19:41:59.783Z,1725565319.783 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-09-05T19:41:59.783Z,1725565319.783 [ThrusterHE](INFO): Powering down 2024-09-05T19:41:59.784Z,1725565319.784 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-09-05T19:41:59.785Z,1725565319.785 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-09-05T19:41:59.785Z,1725565319.785 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-09-05T19:41:59.785Z,1725565319.785 [CBIT](DEBUG): Powering off loads. 2024-09-05T19:41:59.796Z,1725565319.796 [CBIT](DEBUG): Disabling WDT. 2024-09-05T19:41:59.808Z,1725565319.808 [CBIT](DEBUG): Opening all GF detection circuits. 2024-09-05T19:41:59.809Z,1725565319.809 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.981Z,1725565319.981 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.984Z,1725565319.984 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.990Z,1725565319.990 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:41:59.996Z,1725565319.996 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:42:00.068Z,1725565320.068 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:42:00.074Z,1725565320.074 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:42:00.115Z,1725565320.115 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-09-05T19:42:00.192Z,1725565320.192 [logger ThreadHandler](INFO): Thread cancelled.