2024-09-05T17:18:14.183Z,1725556694.183 [Supervisor](DEBUG): Initializing supervisor. 2024-09-05T17:18:14.187Z,1725556694.187 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-09-05T17:18:14.188Z,1725556694.188 [SyncHandler](INFO): Protected caller Thread ID is 3811 2024-09-05T17:18:14.188Z,1725556694.188 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-09-05T17:18:14.189Z,1725556694.189 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-09-05T17:18:14.189Z,1725556694.189 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3812 2024-09-05T17:18:14.193Z,1725556694.193 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-09-05T17:18:14.210Z,1725556694.210 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-09-05T17:18:14.211Z,1725556694.211 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-09-05T17:18:14.212Z,1725556694.212 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3813 2024-09-05T17:18:14.216Z,1725556694.216 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-09-05T17:18:14.217Z,1725556694.217 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-09-05T17:18:14.217Z,1725556694.217 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3814 2024-09-05T17:18:14.219Z,1725556694.219 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-09-05T17:18:14.220Z,1725556694.220 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-09-05T17:18:14.220Z,1725556694.220 [logger ThreadHandler](INFO): Protected caller Thread ID is 3815 2024-09-05T17:18:14.224Z,1725556694.224 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-09-05T17:18:14.224Z,1725556694.224 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-09-05T17:18:14.226Z,1725556694.226 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-09-05T17:18:14.646Z,1725556694.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-09-05T17:18:14.646Z,1725556694.646 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-09-05T17:18:15.786Z,1725556695.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-09-05T17:18:15.787Z,1725556695.787 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-09-05T17:18:15.961Z,1725556695.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-09-05T17:18:15.961Z,1725556695.961 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-09-05T17:18:16.549Z,1725556696.549 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-09-05T17:18:16.550Z,1725556696.550 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-09-05T17:18:16.662Z,1725556696.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-09-05T17:18:16.663Z,1725556696.663 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-09-05T17:18:16.759Z,1725556696.759 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-09-05T17:18:17.207Z,1725556697.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-09-05T17:18:17.208Z,1725556697.208 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-09-05T17:18:17.468Z,1725556697.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-09-05T17:18:17.469Z,1725556697.469 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-09-05T17:18:17.625Z,1725556697.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-09-05T17:18:17.626Z,1725556697.626 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-09-05T17:18:17.900Z,1725556697.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-09-05T17:18:17.900Z,1725556697.900 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-09-05T17:18:18.539Z,1725556698.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-09-05T17:18:18.540Z,1725556698.540 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-09-05T17:18:19.113Z,1725556699.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-09-05T17:18:19.113Z,1725556699.113 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-09-05T17:18:19.208Z,1725556699.208 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-09-05T17:18:19.785Z,1725556699.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-09-05T17:18:19.786Z,1725556699.786 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-09-05T17:18:20.050Z,1725556700.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-09-05T17:18:20.051Z,1725556700.051 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-09-05T17:18:20.376Z,1725556700.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-09-05T17:18:20.378Z,1725556700.378 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2024-09-05T17:18:20.379Z,1725556700.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2024-09-05T17:18:20.502Z,1725556700.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2024-09-05T17:18:20.671Z,1725556700.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2024-09-05T17:18:20.771Z,1725556700.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2024-09-05T17:18:20.869Z,1725556700.869 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2024-09-05T17:18:20.994Z,1725556700.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg 2024-09-05T17:18:21.093Z,1725556701.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2024-09-05T17:18:21.214Z,1725556701.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2024-09-05T17:18:21.499Z,1725556701.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-09-05T17:18:21.499Z,1725556701.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2024-09-05T17:18:21.650Z,1725556701.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2024-09-05T17:18:21.758Z,1725556701.758 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2024-09-05T17:18:21.866Z,1725556701.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2024-09-05T17:18:22.030Z,1725556702.030 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2024-09-05T17:18:22.031Z,1725556702.031 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-09-05T17:18:22.039Z,1725556702.039 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-09-05T17:18:22.157Z,1725556702.157 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-09-05T17:18:22.158Z,1725556702.158 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-09-05T17:18:22.168Z,1725556702.168 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-09-05T17:18:22.168Z,1725556702.168 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-09-05T17:18:22.220Z,1725556702.220 [DepthRateCalculator] Loaded 2024-09-05T17:18:22.221Z,1725556702.221 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-09-05T17:18:22.226Z,1725556702.226 [PitchRateCalculator] Loaded 2024-09-05T17:18:22.226Z,1725556702.226 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-09-05T17:18:22.237Z,1725556702.237 [SpeedCalculator] Loaded 2024-09-05T17:18:22.237Z,1725556702.237 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-09-05T17:18:22.242Z,1725556702.242 [YawRateCalculator] Loaded 2024-09-05T17:18:22.242Z,1725556702.242 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-09-05T17:18:22.263Z,1725556702.263 [ElevatorOffsetCalculator] Loaded 2024-09-05T17:18:22.263Z,1725556702.263 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-09-05T17:18:22.264Z,1725556702.264 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-09-05T17:18:22.264Z,1725556702.264 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-09-05T17:18:22.319Z,1725556702.319 [VerticalControl](DEBUG): Construct VerticalControl. 2024-09-05T17:18:22.374Z,1725556702.374 [VerticalControl] Loaded 2024-09-05T17:18:22.374Z,1725556702.374 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-09-05T17:18:22.377Z,1725556702.377 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-09-05T17:18:22.422Z,1725556702.422 [HorizontalControl] Loaded 2024-09-05T17:18:22.422Z,1725556702.422 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-09-05T17:18:22.425Z,1725556702.425 [SpeedControl](DEBUG): Construct SpeedControl. 2024-09-05T17:18:22.427Z,1725556702.427 [SpeedControl] Loaded 2024-09-05T17:18:22.428Z,1725556702.428 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-09-05T17:18:22.430Z,1725556702.430 [LoopControl](DEBUG): Construct LoopControl. 2024-09-05T17:18:22.431Z,1725556702.431 [LoopControl] Loaded 2024-09-05T17:18:22.431Z,1725556702.431 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-09-05T17:18:22.431Z,1725556702.431 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-09-05T17:18:22.432Z,1725556702.432 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-09-05T17:18:22.673Z,1725556702.673 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-09-05T17:18:22.673Z,1725556702.673 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-09-05T17:18:22.687Z,1725556702.687 [NavChart] Loaded 2024-09-05T17:18:22.687Z,1725556702.687 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-09-05T17:18:22.693Z,1725556702.693 [UniversalFixResidualReporter] Loaded 2024-09-05T17:18:22.693Z,1725556702.693 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-09-05T17:18:22.693Z,1725556702.693 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-09-05T17:18:22.694Z,1725556702.694 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-09-05T17:18:22.880Z,1725556702.880 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-09-05T17:18:22.881Z,1725556702.881 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-09-05T17:18:23.355Z,1725556703.355 [AHRS_M2] Loaded 2024-09-05T17:18:23.356Z,1725556703.356 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-09-05T17:18:23.395Z,1725556703.395 [BackseatComponent] Loaded 2024-09-05T17:18:23.395Z,1725556703.395 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-09-05T17:18:23.397Z,1725556703.397 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409384E0 2024-09-05T17:18:23.397Z,1725556703.397 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3941 2024-09-05T17:18:23.400Z,1725556703.400 [LcmUniversalReporter] Loaded 2024-09-05T17:18:23.400Z,1725556703.400 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-09-05T17:18:24.245Z,1725556704.245 [BPC1] Loaded 2024-09-05T17:18:24.245Z,1725556704.245 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-09-05T17:18:24.342Z,1725556704.342 [DATMMP] Loaded 2024-09-05T17:18:24.342Z,1725556704.342 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2024-09-05T17:18:24.345Z,1725556704.345 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409694E0 2024-09-05T17:18:24.345Z,1725556704.345 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 3942 2024-09-05T17:18:24.521Z,1725556704.521 [DataOverHttps] Loaded 2024-09-05T17:18:24.522Z,1725556704.522 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-09-05T17:18:24.523Z,1725556704.523 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409994E0 2024-09-05T17:18:24.523Z,1725556704.523 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3943 2024-09-05T17:18:24.593Z,1725556704.593 [Depth_Keller] Loaded 2024-09-05T17:18:24.594Z,1725556704.594 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-09-05T17:18:24.724Z,1725556704.724 [DVL_micro] Loaded 2024-09-05T17:18:24.724Z,1725556704.724 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2024-09-05T17:18:24.913Z,1725556704.913 [NAL9602] Loaded 2024-09-05T17:18:24.914Z,1725556704.914 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-09-05T17:18:24.944Z,1725556704.944 [Onboard] Loaded 2024-09-05T17:18:24.944Z,1725556704.944 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-09-05T17:18:24.945Z,1725556704.945 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409C94E0 2024-09-05T17:18:24.946Z,1725556704.946 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3944 2024-09-05T17:18:24.960Z,1725556704.960 [Power24vConverter] Loaded 2024-09-05T17:18:24.960Z,1725556704.960 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-09-05T17:18:24.976Z,1725556704.976 [Radio_Surface] Loaded 2024-09-05T17:18:24.976Z,1725556704.976 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-09-05T17:18:24.977Z,1725556704.977 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F94E0 2024-09-05T17:18:24.977Z,1725556704.977 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3945 2024-09-05T17:18:25.005Z,1725556705.005 [Sonardyne_Nano] Loaded 2024-09-05T17:18:25.005Z,1725556705.005 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-09-05T17:18:25.006Z,1725556705.006 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-09-05T17:18:25.006Z,1725556705.006 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-09-05T17:18:25.313Z,1725556705.313 [BuoyancyServo] Loaded 2024-09-05T17:18:25.313Z,1725556705.313 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-09-05T17:18:25.336Z,1725556705.336 [ElevatorServo] Loaded 2024-09-05T17:18:25.336Z,1725556705.336 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-09-05T17:18:25.358Z,1725556705.358 [MassServo] Loaded 2024-09-05T17:18:25.358Z,1725556705.358 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-09-05T17:18:25.379Z,1725556705.379 [RudderServo] Loaded 2024-09-05T17:18:25.379Z,1725556705.379 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-09-05T17:18:25.395Z,1725556705.395 [ThrusterHE] Loaded 2024-09-05T17:18:25.396Z,1725556705.396 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-09-05T17:18:25.396Z,1725556705.396 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-09-05T17:18:25.397Z,1725556705.397 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-09-05T17:18:25.469Z,1725556705.469 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-09-05T17:18:25.470Z,1725556705.470 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-09-05T17:18:25.497Z,1725556705.497 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-09-05T17:18:25.497Z,1725556705.497 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-09-05T17:18:25.903Z,1725556705.903 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-09-05T17:18:25.903Z,1725556705.903 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-09-05T17:18:26.085Z,1725556706.085 [CTD_Seabird] Loaded 2024-09-05T17:18:26.085Z,1725556706.085 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-09-05T17:18:26.086Z,1725556706.086 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB34E0 2024-09-05T17:18:26.086Z,1725556706.086 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3949 2024-09-05T17:18:26.122Z,1725556706.122 [ESPComponent] Loaded 2024-09-05T17:18:26.123Z,1725556706.123 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-09-05T17:18:26.145Z,1725556706.145 [PAR_Licor] Loaded 2024-09-05T17:18:26.145Z,1725556706.145 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-09-05T17:18:26.190Z,1725556706.190 [WetLabsBB2FL] Loaded 2024-09-05T17:18:26.190Z,1725556706.190 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-09-05T17:18:26.191Z,1725556706.191 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BE34E0 2024-09-05T17:18:26.191Z,1725556706.191 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3950 2024-09-05T17:18:26.192Z,1725556706.192 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-09-05T17:18:26.193Z,1725556706.193 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-09-05T17:18:26.468Z,1725556706.468 [SBIT](DEBUG): Construct Startup Built In Test. 2024-09-05T17:18:26.477Z,1725556706.477 [SBIT] Loaded 2024-09-05T17:18:26.477Z,1725556706.477 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-09-05T17:18:26.480Z,1725556706.480 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-09-05T17:18:26.493Z,1725556706.493 [IBIT] Loaded 2024-09-05T17:18:26.493Z,1725556706.493 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-09-05T17:18:26.499Z,1725556706.499 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-09-05T17:18:26.604Z,1725556706.604 [CBIT] Loaded 2024-09-05T17:18:26.604Z,1725556706.604 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-09-05T17:18:26.605Z,1725556706.605 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-09-05T17:18:26.611Z,1725556706.611 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-09-05T17:18:26.614Z,1725556706.614 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-09-05T17:18:26.625Z,1725556706.625 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-09-05T17:18:26.626Z,1725556706.626 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0 2024-09-05T17:18:26.627Z,1725556706.627 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3958 2024-09-05T17:18:26.631Z,1725556706.631 [Supervisor](INFO): Main Thread ID is 3810 2024-09-05T17:18:26.631Z,1725556706.631 [Supervisor](DEBUG): Running supervisor. 2024-09-05T17:18:26.632Z,1725556706.632 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3959 2024-09-05T17:18:26.633Z,1725556706.633 [CommandExec](INFO): Initializing the command executive. 2024-09-05T17:18:26.634Z,1725556706.634 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3960 2024-09-05T17:18:26.638Z,1725556706.638 [controlThread ThreadHandler](INFO): Handler Thread ID is 3961 2024-09-05T17:18:26.638Z,1725556706.638 [controlThread](DEBUG): Initializing ControlThread 2024-09-05T17:18:26.639Z,1725556706.639 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-09-05T17:18:26.639Z,1725556706.639 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-09-05T17:18:26.639Z,1725556706.639 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-09-05T17:18:26.640Z,1725556706.640 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-09-05T17:18:26.640Z,1725556706.640 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-09-05T17:18:26.641Z,1725556706.641 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-09-05T17:18:26.642Z,1725556706.642 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-09-05T17:18:26.643Z,1725556706.643 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-09-05T17:18:26.643Z,1725556706.643 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-09-05T17:18:26.644Z,1725556706.644 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-09-05T17:18:26.645Z,1725556706.645 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-09-05T17:18:26.652Z,1725556706.652 [SBIT](INFO): Initialize SBIT Component. 2024-09-05T17:18:26.653Z,1725556706.653 [SBIT](IMPORTANT): git: 2024-09-04 2024-09-05T17:18:26.653Z,1725556706.653 [SBIT](INFO): git hash: c3a48f2091374af655f27818376c2e16c84a5fcd 2024-09-05T17:18:26.653Z,1725556706.653 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-09-05T17:18:26.654Z,1725556706.654 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-09-05T17:18:26.655Z,1725556706.655 [SBIT](INFO): Beginning SBIT in 128.000000 seconds. 2024-09-05T17:18:26.656Z,1725556706.656 [IBIT](INFO): Initialize IBIT Component. 2024-09-05T17:18:26.657Z,1725556706.657 [CBIT](DEBUG): Initialize CBIT Component. 2024-09-05T17:18:26.658Z,1725556706.658 [logger ThreadHandler](INFO): Handler Thread ID is 3962 2024-09-05T17:18:26.668Z,1725556706.668 [CBIT](DEBUG): Initialized mux pins. 2024-09-05T17:18:26.668Z,1725556706.668 [CBIT](DEBUG): Initializing the watchdog timer. 2024-09-05T17:18:26.676Z,1725556706.676 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3963 2024-09-05T17:18:26.688Z,1725556706.688 [DATMMP ThreadHandler](INFO): Handler Thread ID is 3964 2024-09-05T17:18:26.689Z,1725556706.689 [DATMMP](INFO): Start 2024-09-05T17:18:26.689Z,1725556706.689 [DATMMP](INFO): Powering up 2024-09-05T17:18:26.689Z,1725556706.689 [DATMMP](DEBUG): Initializing DATMMP. 2024-09-05T17:18:26.692Z,1725556706.692 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-09-05T17:18:26.692Z,1725556706.692 [CBIT](DEBUG): Initializing heartbeat. 2024-09-05T17:18:26.694Z,1725556706.694 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3966 2024-09-05T17:18:26.696Z,1725556706.696 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-09-05T17:18:26.705Z,1725556706.705 [Onboard ThreadHandler](INFO): Handler Thread ID is 3967 2024-09-05T17:18:26.734Z,1725556706.734 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3968 2024-09-05T17:18:26.744Z,1725556706.744 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3969 2024-09-05T17:18:26.745Z,1725556706.745 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-09-05T17:18:26.749Z,1725556706.749 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3971 2024-09-05T17:18:26.752Z,1725556706.752 [WetLabsBB2FL](INFO): Powering up 2024-09-05T17:18:26.754Z,1725556706.754 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3973 2024-09-05T17:18:26.756Z,1725556706.756 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-09-05T17:18:26.756Z,1725556706.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-09-05T17:18:26.757Z,1725556706.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-09-05T17:18:26.757Z,1725556706.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-09-05T17:18:26.757Z,1725556706.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-09-05T17:18:26.757Z,1725556706.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-09-05T17:18:26.757Z,1725556706.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-09-05T17:18:26.757Z,1725556706.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-09-05T17:18:26.764Z,1725556706.764 [CBIT](DEBUG): Deactivating GF circuits. 2024-09-05T17:18:26.764Z,1725556706.764 [CBIT](DEBUG): Deactivating emergency mode. 2024-09-05T17:18:26.804Z,1725556706.804 [CBIT](DEBUG): Backplane powered. 2024-09-05T17:18:26.805Z,1725556706.805 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-09-05T17:18:26.806Z,1725556706.806 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-09-05T17:18:26.806Z,1725556706.806 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-09-05T17:18:26.807Z,1725556706.807 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-09-05T17:18:26.849Z,1725556706.849 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-09-05T17:18:26.860Z,1725556706.860 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-09-05T17:18:26.884Z,1725556706.884 [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-05T17:18:26.885Z,1725556706.885 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-09-05T17:18:26.885Z,1725556706.885 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-09-05T17:18:26.886Z,1725556706.886 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-09-05T17:18:26.940Z,1725556706.940 [DATMMP](INFO): Starting 2024-09-05T17:18:27.136Z,1725556707.136 [Radio_Surface](INFO): Powering up 2024-09-05T17:18:27.192Z,1725556707.192 [DATMMP](INFO): Starting 2024-09-05T17:18:27.229Z,1725556707.229 [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-05T17:18:27.235Z,1725556707.235 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-09-05T17:18:27.252Z,1725556707.252 [Default:A.Wait](DEBUG): Construct Wait. 2024-09-05T17:18:27.255Z,1725556707.255 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-09-05T17:18:27.287Z,1725556707.287 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-09-05T17:18:27.298Z,1725556707.298 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-09-05T17:18:27.317Z,1725556707.317 [Default:E.Execute](DEBUG): Construct Execute. 2024-09-05T17:18:27.320Z,1725556707.320 [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-05T17:18:27.336Z,1725556707.336 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-09-05T17:18:27.348Z,1725556707.348 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-09-05T17:18:27.433Z,1725556707.433 [Power24vConverter](INFO): Powering up. 2024-09-05T17:18:27.434Z,1725556707.434 [Sonardyne_Nano](INFO): Initializing. 2024-09-05T17:18:27.444Z,1725556707.444 [DATMMP](INFO): Starting 2024-09-05T17:18:27.494Z,1725556707.494 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-09-05T17:18:27.500Z,1725556707.500 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-09-05T17:18:27.501Z,1725556707.501 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-09-05T17:18:27.508Z,1725556707.508 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-09-05T17:18:27.509Z,1725556707.509 [MassServo](DEBUG): Initializing EZServoServo. 2024-09-05T17:18:27.516Z,1725556707.516 [MassServo](DEBUG): Initializing MassServo. 2024-09-05T17:18:27.517Z,1725556707.517 [RudderServo](DEBUG): Initializing EZServoServo. 2024-09-05T17:18:27.524Z,1725556707.524 [RudderServo](DEBUG): Initializing RudderServo. 2024-09-05T17:18:27.525Z,1725556707.525 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-09-05T17:18:27.532Z,1725556707.532 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-09-05T17:18:27.708Z,1725556707.708 [DATMMP](INFO): Starting 2024-09-05T17:18:27.960Z,1725556707.960 [DATMMP](INFO): Starting 2024-09-05T17:18:28.212Z,1725556708.212 [DATMMP](INFO): Starting 2024-09-05T17:18:28.464Z,1725556708.464 [DATMMP](INFO): Starting 2024-09-05T17:18:28.717Z,1725556708.717 [DATMMP](INFO): Starting 2024-09-05T17:18:28.968Z,1725556708.968 [DATMMP](INFO): Starting 2024-09-05T17:18:29.220Z,1725556709.220 [DATMMP](INFO): Starting 2024-09-05T17:18:29.368Z,1725556709.368 [WetLabsBB2FL](INFO): Powering down 2024-09-05T17:18:29.372Z,1725556709.372 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-09-05T17:18:29.472Z,1725556709.472 [DATMMP](INFO): Starting 2024-09-05T17:18:29.724Z,1725556709.724 [DATMMP](INFO): Starting 2024-09-05T17:18:29.976Z,1725556709.976 [DATMMP](INFO): Starting 2024-09-05T17:18:30.228Z,1725556710.228 [DATMMP](INFO): Starting 2024-09-05T17:18:30.480Z,1725556710.480 [DATMMP](INFO): Starting 2024-09-05T17:18:30.661Z,1725556710.661 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2024-09-05T17:18:30.732Z,1725556710.732 [DATMMP](INFO): Starting 2024-09-05T17:18:30.984Z,1725556710.984 [DATMMP](INFO): Starting 2024-09-05T17:18:31.236Z,1725556711.236 [DATMMP](INFO): Starting 2024-09-05T17:18:31.488Z,1725556711.488 [DATMMP](INFO): Starting 2024-09-05T17:18:31.740Z,1725556711.740 [DATMMP](INFO): Starting 2024-09-05T17:18:31.992Z,1725556711.992 [DATMMP](INFO): Starting 2024-09-05T17:18:32.244Z,1725556712.244 [DATMMP](INFO): Starting 2024-09-05T17:18:32.497Z,1725556712.497 [DATMMP](INFO): Starting 2024-09-05T17:18:32.748Z,1725556712.748 [DATMMP](INFO): Starting 2024-09-05T17:18:33.000Z,1725556713.000 [DATMMP](INFO): Starting 2024-09-05T17:18:33.130Z,1725556713.130 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-09-05T17:18:33.252Z,1725556713.252 [DATMMP](INFO): Starting 2024-09-05T17:18:33.504Z,1725556713.504 [DATMMP](INFO): Starting 2024-09-05T17:18:33.756Z,1725556713.756 [DATMMP](INFO): Starting 2024-09-05T17:18:33.776Z,1725556713.776 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-09-05T17:18:34.008Z,1725556714.008 [DATMMP](INFO): Starting 2024-09-05T17:18:34.260Z,1725556714.260 [DATMMP](INFO): Starting 2024-09-05T17:18:34.512Z,1725556714.512 [DATMMP](INFO): Starting 2024-09-05T17:18:34.764Z,1725556714.764 [DATMMP](INFO): Starting 2024-09-05T17:18:35.016Z,1725556715.016 [DATMMP](INFO): Starting 2024-09-05T17:18:35.268Z,1725556715.268 [DATMMP](INFO): Starting 2024-09-05T17:18:35.520Z,1725556715.520 [DATMMP](INFO): Starting 2024-09-05T17:18:35.772Z,1725556715.772 [DATMMP](INFO): Starting 2024-09-05T17:18:36.024Z,1725556716.024 [DATMMP](INFO): Starting 2024-09-05T17:18:36.276Z,1725556716.276 [DATMMP](INFO): Starting 2024-09-05T17:18:36.528Z,1725556716.528 [DATMMP](INFO): Starting 2024-09-05T17:18:36.780Z,1725556716.780 [DATMMP](INFO): Starting 2024-09-05T17:18:37.032Z,1725556717.032 [DATMMP](INFO): Starting 2024-09-05T17:18:37.285Z,1725556717.285 [DATMMP](INFO): Starting 2024-09-05T17:18:37.536Z,1725556717.536 [DATMMP](INFO): Starting 2024-09-05T17:18:37.788Z,1725556717.788 [DATMMP](INFO): Starting 2024-09-05T17:18:38.040Z,1725556718.040 [DATMMP](INFO): Starting 2024-09-05T17:18:38.292Z,1725556718.292 [DATMMP](INFO): Starting 2024-09-05T17:18:38.544Z,1725556718.544 [DATMMP](INFO): Starting 2024-09-05T17:18:38.796Z,1725556718.796 [DATMMP](INFO): Starting 2024-09-05T17:18:38.797Z,1725556718.797 [DATMMP](INFO): DAT read: 2024-09-05T17:18:38.797Z,1725556718.797 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-09-05T17:18:39.048Z,1725556719.048 [DATMMP](INFO): Starting 2024-09-05T17:18:39.300Z,1725556719.300 [DATMMP](INFO): Starting 2024-09-05T17:18:39.552Z,1725556719.552 [DATMMP](INFO): Starting 2024-09-05T17:18:39.663Z,1725556719.663 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.013571 2024-09-05T17:18:39.804Z,1725556719.804 [DATMMP](INFO): Starting 2024-09-05T17:18:40.056Z,1725556720.056 [DATMMP](INFO): Starting 2024-09-05T17:18:40.308Z,1725556720.308 [DATMMP](INFO): Starting 2024-09-05T17:18:40.309Z,1725556720.309 [DATMMP](INFO): DAT read: MF Frequency Band 2024-09-05T17:18:40.310Z,1725556720.310 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-09-05T17:18:40.311Z,1725556720.311 [DATMMP](INFO): DAT read: Aug 5 2000 17:18:34 2024-09-05T17:18:40.560Z,1725556720.560 [DATMMP](INFO): Starting 2024-09-05T17:18:40.812Z,1725556720.812 [DATMMP](INFO): Starting 2024-09-05T17:18:41.064Z,1725556721.064 [DATMMP](INFO): Starting 2024-09-05T17:18:41.316Z,1725556721.316 [DATMMP](INFO): Starting 2024-09-05T17:18:41.568Z,1725556721.568 [DATMMP](INFO): Starting 2024-09-05T17:18:41.569Z,1725556721.569 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2024-09-05T17:18:41.570Z,1725556721.570 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-09-05T17:18:41.571Z,1725556721.571 [DATMMP](INFO): commRate: 800 2024-09-05T17:18:41.820Z,1725556721.820 [DATMMP](INFO): Starting 2024-09-05T17:18:42.072Z,1725556722.072 [DATMMP](INFO): Starting 2024-09-05T17:18:42.324Z,1725556722.324 [DATMMP](INFO): Starting 2024-09-05T17:18:42.577Z,1725556722.577 [DATMMP](INFO): Starting 2024-09-05T17:18:42.828Z,1725556722.828 [DATMMP](INFO): Starting 2024-09-05T17:18:43.080Z,1725556723.080 [DATMMP](INFO): Starting 2024-09-05T17:18:43.332Z,1725556723.332 [DATMMP](INFO): Starting 2024-09-05T17:18:43.584Z,1725556723.584 [DATMMP](INFO): Starting 2024-09-05T17:18:43.636Z,1725556723.636 [DATMMP](INFO): entering command mode 2024-09-05T17:18:43.836Z,1725556723.836 [DATMMP](INFO): Starting 2024-09-05T17:18:43.837Z,1725556723.837 [DATMMP](INFO): DAT read: 2024-09-05T17:18:43.837Z,1725556723.837 [DATMMP](INFO): DAT read: user:1> 2024-09-05T17:18:43.837Z,1725556723.837 [DATMMP](DEBUG): read user prompt 1: user:1> 2024-09-05T17:18:43.838Z,1725556723.838 [DATMMP](INFO): entering MMP mode 2024-09-05T17:18:44.088Z,1725556724.088 [DATMMP](INFO): Starting 2024-09-05T17:18:44.089Z,1725556724.089 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T17:18:44.090Z,1725556724.090 [DATMMP](INFO): status rx: x1 2024-09-05T17:18:44.091Z,1725556724.091 [DATMMP](INFO): MMP status: 1: started 2024-09-05T17:18:44.091Z,1725556724.091 [DATMMP](INFO): Received message type: status 2024-09-05T17:18:44.091Z,1725556724.091 [DATMMP](INFO): Handled 2024-09-05T17:18:44.340Z,1725556724.340 [DATMMP](INFO): Starting 2024-09-05T17:18:44.341Z,1725556724.341 [DATMMP](INFO): Sent Tx Power Config 8 2024-09-05T17:18:44.342Z,1725556724.342 [DATMMP](INFO): Setting local address: 11 2024-09-05T17:18:44.343Z,1725556724.343 [DATMMP](INFO): Setting time to: 17:18:44 And date to:9/5/2024 2024-09-05T17:18:44.592Z,1725556724.592 [DATMMP](INFO): Starting 2024-09-05T17:18:44.593Z,1725556724.593 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T17:18:44.593Z,1725556724.593 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2024-09-05T17:18:44.593Z,1725556724.593 [DATMMP](INFO): Received message type: mdmpower 2024-09-05T17:18:44.594Z,1725556724.594 [DATMMP](INFO): Handled 2024-09-05T17:18:44.844Z,1725556724.844 [DATMMP](INFO): Starting 2024-09-05T17:18:44.845Z,1725556724.845 [DATMMP](INFO): GSXN notify for xid: 10 2024-09-05T17:18:44.845Z,1725556724.845 [DATMMP](INFO): sreg_txpower rx: x8 2024-09-05T17:18:44.845Z,1725556724.845 [DATMMP](INFO): Received message type: sreg_txpower 2024-09-05T17:18:44.846Z,1725556724.846 [DATMMP](INFO): Match for : sreg_txpower 2024-09-05T17:18:44.846Z,1725556724.846 [DATMMP](INFO): ACK 2024-09-05T17:18:45.096Z,1725556725.096 [DATMMP](INFO): Starting 2024-09-05T17:18:45.097Z,1725556725.097 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T17:18:45.097Z,1725556725.097 [DATMMP](INFO): mdmlocal rx: xB x0 x0 x0 2024-09-05T17:18:45.098Z,1725556725.098 [DATMMP](INFO): Received message type: mdmlocal 2024-09-05T17:18:45.098Z,1725556725.098 [DATMMP](INFO): Handled 2024-09-05T17:18:45.348Z,1725556725.348 [DATMMP](INFO): Starting 2024-09-05T17:18:45.349Z,1725556725.349 [DATMMP](INFO): GSXN notify for xid: 11 2024-09-05T17:18:45.349Z,1725556725.349 [DATMMP](INFO): sreg_locaddr rx: xB 2024-09-05T17:18:45.349Z,1725556725.349 [DATMMP](INFO): Received message type: sreg_locaddr 2024-09-05T17:18:45.350Z,1725556725.350 [DATMMP](INFO): Match for : sreg_locaddr 2024-09-05T17:18:45.350Z,1725556725.350 [DATMMP](INFO): ACK 2024-09-05T17:18:45.600Z,1725556725.600 [DATMMP](INFO): Starting 2024-09-05T17:18:45.601Z,1725556725.601 [DATMMP](INFO): GSXN notify for xid: 12 2024-09-05T17:18:45.602Z,1725556725.602 [DATMMP](INFO): timedate rx: x0 x11 x12 x2C x8 x5 x7C x0 2024-09-05T17:18:45.602Z,1725556725.602 [DATMMP](INFO): Received message type: timedate 2024-09-05T17:18:45.602Z,1725556725.602 [DATMMP](INFO): Match for : timedate 2024-09-05T17:18:45.602Z,1725556725.602 [DATMMP](INFO): ACK 2024-09-05T17:18:45.854Z,1725556725.854 [DATMMP](INFO): Starting 2024-09-05T17:18:45.856Z,1725556725.856 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T17:18:45.857Z,1725556725.857 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2024-09-05T17:18:45.857Z,1725556725.857 [DATMMP](INFO): Received message type: sys_verb 2024-09-05T17:18:45.857Z,1725556725.857 [DATMMP](INFO): Handled 2024-09-05T17:18:46.104Z,1725556726.104 [DATMMP](INFO): Starting 2024-09-05T17:18:46.105Z,1725556726.105 [DATMMP](INFO): GSXN notify for xid: 13 2024-09-05T17:18:46.105Z,1725556726.105 [DATMMP](INFO): sreg_verbosity rx: x0 2024-09-05T17:18:46.105Z,1725556726.105 [DATMMP](INFO): Received message type: sreg_verbosity 2024-09-05T17:18:46.105Z,1725556726.105 [DATMMP](INFO): Match for : sreg_verbosity 2024-09-05T17:18:46.106Z,1725556726.106 [DATMMP](INFO): ACK 2024-09-05T17:18:46.356Z,1725556726.356 [DATMMP](INFO): Starting 2024-09-05T17:18:46.357Z,1725556726.357 [DATMMP](INFO): GSXN notify for xid: 14 2024-09-05T17:18:46.357Z,1725556726.357 [DATMMP](INFO): version rx: x2 x5 2024-09-05T17:18:46.357Z,1725556726.357 [DATMMP](INFO): DatMMPVersion: 2.5 2024-09-05T17:18:46.358Z,1725556726.358 [DATMMP](INFO): Received message type: version 2024-09-05T17:18:46.358Z,1725556726.358 [DATMMP](INFO): Match for : version 2024-09-05T17:18:46.358Z,1725556726.358 [DATMMP](INFO): ACK 2024-09-05T17:18:46.358Z,1725556726.358 [DATMMP](INFO): in MMP mode: config complete going to runnable 2024-09-05T17:18:55.226Z,1725556735.226 [NAL9602](INFO): Powering up NAL9602 2024-09-05T17:18:59.713Z,1725556739.713 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T17:18:59.714Z,1725556739.714 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2024-09-05T17:18:59.714Z,1725556739.714 [DATMMP](INFO): Received message type: timesync 2024-09-05T17:18:59.714Z,1725556739.714 [DATMMP](INFO): Handled 2024-09-05T17:19:05.073Z,1725556745.073 [MassServo](ERROR): getPosition uart error serial timeout 2024-09-05T17:19:05.073Z,1725556745.073 [MassServo](FAULT): Invalid EZ Servo response:"\n" 2024-09-05T17:19:05.073Z,1725556745.073 [MassServo] Communications Fault, FailCount= 1 2024-09-05T17:19:05.073Z,1725556745.073 [MassServo](ERROR): Communications Fault 2024-09-05T17:19:05.082Z,1725556745.082 [CBIT](ERROR): Communications Fault in component: MassServo 2024-09-05T17:19:05.355Z,1725556745.355 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-09-05T17:19:05.355Z,1725556745.355 [MassServo](INFO): Powering down 2024-09-05T17:19:06.134Z,1725556746.134 [NAL9602](INFO): NAL9602 initialized 2024-09-05T17:19:06.215Z,1725556746.215 [MassServo](DEBUG): Initializing EZServoServo. 2024-09-05T17:19:06.331Z,1725556746.331 [MassServo](DEBUG): Initializing MassServo. 2024-09-05T17:19:06.338Z,1725556746.338 [CBIT](INFO): Clearing failed state for component MassServo 2024-09-05T17:19:06.339Z,1725556746.339 [MassServo] No Fault, FailCount= 1 2024-09-05T17:19:09.541Z,1725556749.541 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-05T17:19:09.541Z,1725556749.541 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-09-05T17:19:09.542Z,1725556749.542 [DATMMP](INFO): Received message type: timesync 2024-09-05T17:19:09.542Z,1725556749.542 [DATMMP](INFO): Handled 2024-09-05T17:20:35.467Z,1725556835.467 [SBIT](IMPORTANT): Beginning Startup BIT 2024-09-05T17:20:35.471Z,1725556835.471 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T17:20:46.483Z,1725556846.483 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.050743 CHAN A1 (24V): 0.016719 CHAN A2 (12V): 0.387963 CHAN A3 (5V): -0.024053 CHAN B0 (3.3V): -0.011435 CHAN B1 (3.15aV): 0.113420 CHAN B2 (3.15bV): -0.006883 CHAN B3 (GND): -0.013106 OPEN: -0.062134 Full Scale: +/- 1 mA 2024-09-05T17:21:26.826Z,1725556886.826 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-09-05T17:21:26.826Z,1725556886.826 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:21:26.892Z,1725556886.892 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:21:27.234Z,1725556887.234 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:21:27.234Z,1725556887.234 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-09-05T17:21:29.681Z,1725556889.681 [SBIT](IMPORTANT): SBIT PASSED 2024-09-05T17:21:29.681Z,1725556889.681 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-09-05T17:21:29.682Z,1725556889.682 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-09-05T17:21:29.682Z,1725556889.682 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-09-05T17:21:29.682Z,1725556889.682 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-09-05T17:21:29.682Z,1725556889.682 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar; 2024-09-05T17:21:29.683Z,1725556889.683 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2024-09-05T17:21:29.683Z,1725556889.683 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-09-05T17:21:29.683Z,1725556889.683 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=186.137647 cubic_centimeter; 2024-09-05T17:21:29.683Z,1725556889.683 [SBIT](IMPORTANT): VerticalControl.massDefault=27.004010 millimeter; 2024-09-05T17:21:30.175Z,1725556890.175 [MissionManager](IMPORTANT): Started mission Startup 2024-09-05T17:21:30.175Z,1725556890.175 [Startup] Running Loop=1 2024-09-05T17:21:30.175Z,1725556890.175 [Startup](DEBUG): Aggregate::initialize Startup 2024-09-05T17:21:30.175Z,1725556890.175 [Startup:A.GoToSurface] Running Loop=1 2024-09-05T17:21:30.175Z,1725556890.175 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-09-05T17:21:30.176Z,1725556890.176 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-09-05T17:21:30.180Z,1725556890.180 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-09-05T17:21:30.181Z,1725556890.181 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-09-05T17:21:30.181Z,1725556890.181 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-09-05T17:21:30.181Z,1725556890.181 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-09-05T17:21:30.182Z,1725556890.182 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-09-05T17:21:30.183Z,1725556890.183 [Startup:StartupSatComms] Running Loop=1 2024-09-05T17:21:30.184Z,1725556890.184 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-09-05T17:21:30.220Z,1725556890.220 [Startup:StartupSatComms:A] Running Loop=1 2024-09-05T17:21:30.507Z,1725556890.507 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-09-05T17:22:11.291Z,1725556931.291 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-05T17:22:30.276Z,1725556950.276 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-05T17:21:30.2Z 2024-09-05T17:22:30.276Z,1725556950.276 [Startup:StartupSatComms:A] Stopped 2024-09-05T17:22:30.276Z,1725556950.276 [Startup:StartupSatComms:B] Running Loop=1 2024-09-05T17:22:30.667Z,1725556950.667 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-09-05T17:22:37.952Z,1725556957.952 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240905T170728/Courier0004.lzma 2024-09-05T17:22:38.954Z,1725556958.954 [DataOverHttps](INFO): Moved sent file to Logs/20240905T170728/Courier0004.lzma.bak 2024-09-05T17:22:38.954Z,1725556958.954 [DataOverHttps](INFO): SBD MOMSN=23130663 2024-09-05T17:22:55.012Z,1725556975.012 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240905T171814/Courier0000.lzma 2024-09-05T17:22:56.014Z,1725556976.014 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Courier0000.lzma.bak 2024-09-05T17:22:56.014Z,1725556976.014 [DataOverHttps](INFO): SBD MOMSN=23130665 2024-09-05T17:23:11.232Z,1725556991.232 [DataOverHttps](INFO): Sending 441 bytes from file Logs/20240905T170728/Express0005.lzma 2024-09-05T17:23:12.234Z,1725556992.234 [DataOverHttps](INFO): Moved sent file to Logs/20240905T170728/Express0005.lzma.bak 2024-09-05T17:23:12.234Z,1725556992.234 [DataOverHttps](INFO): SBD MOMSN=23130668 2024-09-05T17:23:28.441Z,1725557008.441 [DataOverHttps](INFO): Sending 903 bytes from file Logs/20240905T171814/Express0001.lzma 2024-09-05T17:23:29.442Z,1725557009.442 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Express0001.lzma.bak 2024-09-05T17:23:29.442Z,1725557009.442 [DataOverHttps](INFO): SBD MOMSN=23130679 2024-09-05T17:23:30.494Z,1725557010.494 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-05T17:22:30.3Z 2024-09-05T17:23:30.494Z,1725557010.494 [Startup:StartupSatComms:B] Stopped 2024-09-05T17:23:30.495Z,1725557010.495 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-09-05T17:23:30.495Z,1725557010.495 [Startup:StartupSatComms] Stopped 2024-09-05T17:23:30.495Z,1725557010.495 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-09-05T17:23:30.496Z,1725557010.496 [Startup](INFO): Completed Startup 2024-09-05T17:23:30.496Z,1725557010.496 [MissionManager](INFO): Startup is completed. 2024-09-05T17:23:30.496Z,1725557010.496 [MissionManager](INFO): Uninitializing Mission Startup 2024-09-05T17:23:30.496Z,1725557010.496 [Startup] Stopped 2024-09-05T17:23:30.497Z,1725557010.497 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-09-05T17:23:30.497Z,1725557010.497 [Startup:A.GoToSurface] Stopped 2024-09-05T17:23:30.497Z,1725557010.497 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-09-05T17:23:31.053Z,1725557011.053 [MissionManager](IMPORTANT): Started mission Default 2024-09-05T17:23:31.053Z,1725557011.053 [Default] Running Loop=1 2024-09-05T17:23:31.053Z,1725557011.053 [Default](DEBUG): Aggregate::initialize Default 2024-09-05T17:23:31.054Z,1725557011.054 [Default:B.GoToSurface] Running Loop=1 2024-09-05T17:23:31.054Z,1725557011.054 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-09-05T17:23:31.054Z,1725557011.054 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-09-05T17:23:31.054Z,1725557011.054 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-09-05T17:23:31.054Z,1725557011.054 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-09-05T17:23:31.055Z,1725557011.055 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-09-05T17:23:31.055Z,1725557011.055 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-09-05T17:23:31.055Z,1725557011.055 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-09-05T17:23:31.056Z,1725557011.056 [Default:A.Wait] Running Loop=1 2024-09-05T17:23:31.056Z,1725557011.056 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-09-05T17:23:44.285Z,1725557024.285 [Default:A.Wait](INFO): Done Waiting. 2024-09-05T17:23:44.285Z,1725557024.285 [Default:A.Wait] Stopped 2024-09-05T17:23:44.285Z,1725557024.285 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T17:23:44.618Z,1725557024.618 [Default:CheckIn] Running Loop=1 2024-09-05T17:23:44.618Z,1725557024.618 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T17:23:44.619Z,1725557024.619 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T17:23:45.030Z,1725557025.030 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-09-05T17:24:09.258Z,1725557049.258 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-09-05T17:24:14.909Z,1725557054.909 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 3. 2024-09-05T17:24:14.912Z,1725557054.912 [BPC1](INFO): Received data from all battery sticks. 2024-09-05T17:24:27.869Z,1725557067.869 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-09-05T17:24:27.869Z,1725557067.869 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:24:27.879Z,1725557067.879 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:24:28.261Z,1725557068.261 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:24:28.261Z,1725557068.261 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-09-05T17:27:28.877Z,1725557248.877 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-09-05T17:27:28.877Z,1725557248.877 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:27:28.888Z,1725557248.888 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:27:29.276Z,1725557249.276 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:27:29.292Z,1725557249.292 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-09-05T17:28:44.835Z,1725557324.835 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-05T17:23:44.6Z 2024-09-05T17:28:44.835Z,1725557324.835 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T17:28:44.835Z,1725557324.835 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T17:28:45.212Z,1725557325.212 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-09-05T17:28:51.728Z,1725557331.728 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240905T171814/Courier0004.lzma 2024-09-05T17:28:52.730Z,1725557332.730 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Courier0004.lzma.bak 2024-09-05T17:28:52.730Z,1725557332.730 [DataOverHttps](INFO): SBD MOMSN=23130770 2024-09-05T17:29:07.043Z,1725557347.043 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-05T17:29:07.044Z,1725557347.044 [NAL9602] Data Fault, FailCount= 1 2024-09-05T17:29:07.044Z,1725557347.044 [NAL9602](ERROR): Data Fault 2024-09-05T17:29:07.115Z,1725557347.115 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-05T17:29:07.425Z,1725557347.425 [NAL9602](INFO): Powering down 2024-09-05T17:29:08.259Z,1725557348.259 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-05T17:29:08.259Z,1725557348.259 [NAL9602] No Fault, FailCount= 1 2024-09-05T17:29:08.980Z,1725557348.980 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20240905T171814/Express0005.lzma 2024-09-05T17:29:09.982Z,1725557349.982 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Express0005.lzma.bak 2024-09-05T17:29:09.982Z,1725557349.982 [DataOverHttps](INFO): SBD MOMSN=23130772 2024-09-05T17:29:11.078Z,1725557351.078 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T17:29:11.078Z,1725557351.078 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T17:29:11.078Z,1725557351.078 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T17:29:37.730Z,1725557377.730 [NAL9602](INFO): Powering up NAL9602 2024-09-05T17:29:48.634Z,1725557388.634 [NAL9602](INFO): NAL9602 initialized 2024-09-05T17:30:29.850Z,1725557429.850 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-09-05T17:30:29.850Z,1725557429.850 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:30:29.861Z,1725557429.861 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:30:30.271Z,1725557430.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:30:30.271Z,1725557430.271 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-09-05T17:33:30.937Z,1725557610.937 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-09-05T17:33:30.937Z,1725557610.937 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:33:30.948Z,1725557610.948 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:33:31.394Z,1725557611.394 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:33:31.394Z,1725557611.394 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-09-05T17:34:11.747Z,1725557651.747 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-05T17:34:11.747Z,1725557651.747 [Default:CheckIn:C.Wait] Stopped 2024-09-05T17:34:11.747Z,1725557651.747 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T17:34:11.747Z,1725557651.747 [Default:CheckIn:D] Running Loop=1 2024-09-05T17:34:12.150Z,1725557652.150 [Default:CheckIn:D] Stopped 2024-09-05T17:34:12.150Z,1725557652.150 [Default:CheckIn:E] Running Loop=1 2024-09-05T17:34:12.575Z,1725557652.575 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.684946 min 2024-09-05T17:34:12.575Z,1725557652.575 [Default:CheckIn:E] Stopped 2024-09-05T17:34:12.575Z,1725557652.575 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-05T17:34:12.576Z,1725557652.576 [Default:CheckIn] Stopped 2024-09-05T17:34:12.576Z,1725557652.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-05T17:34:12.576Z,1725557652.576 [Default:CheckIn](INFO): Running loop #2 2024-09-05T17:34:12.577Z,1725557652.577 [Default:CheckIn] Running Loop=2 2024-09-05T17:34:12.577Z,1725557652.577 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T17:34:12.577Z,1725557652.577 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T17:36:31.933Z,1725557791.933 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-09-05T17:36:31.933Z,1725557791.933 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:36:31.944Z,1725557791.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:36:32.350Z,1725557792.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:36:32.351Z,1725557792.351 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-09-05T17:39:12.745Z,1725557952.745 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-05T17:34:12.6Z 2024-09-05T17:39:12.745Z,1725557952.745 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T17:39:12.745Z,1725557952.745 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T17:39:20.115Z,1725557960.115 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240905T171814/Courier0007.lzma 2024-09-05T17:39:21.118Z,1725557961.118 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Courier0007.lzma.bak 2024-09-05T17:39:21.118Z,1725557961.118 [DataOverHttps](INFO): SBD MOMSN=23130809 2024-09-05T17:39:32.980Z,1725557972.980 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-09-05T17:39:32.980Z,1725557972.980 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:39:32.995Z,1725557972.995 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:39:33.410Z,1725557973.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:39:33.410Z,1725557973.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-09-05T17:39:37.144Z,1725557977.144 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20240905T171814/Express0008.lzma 2024-09-05T17:39:38.146Z,1725557978.146 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Express0008.lzma.bak 2024-09-05T17:39:38.146Z,1725557978.146 [DataOverHttps](INFO): SBD MOMSN=23130811 2024-09-05T17:39:39.407Z,1725557979.407 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T17:39:39.407Z,1725557979.407 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T17:39:39.407Z,1725557979.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T17:39:49.495Z,1725557989.495 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-05T17:39:49.495Z,1725557989.495 [NAL9602] Data Fault, FailCount= 2 2024-09-05T17:39:49.512Z,1725557989.512 [NAL9602](ERROR): Data Fault 2024-09-05T17:39:49.565Z,1725557989.565 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-05T17:39:49.907Z,1725557989.907 [NAL9602](INFO): Powering down 2024-09-05T17:39:50.727Z,1725557990.727 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-05T17:39:50.727Z,1725557990.727 [NAL9602] No Fault, FailCount= 2 2024-09-05T17:40:12.520Z,1725558012.520 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2024-09-05T17:40:12.520Z,1725558012.520 [AHRS_M2] Data Fault, FailCount= 1 2024-09-05T17:40:12.520Z,1725558012.520 [AHRS_M2](ERROR): Data Fault 2024-09-05T17:40:12.546Z,1725558012.546 [CBIT](ERROR): Data Fault in component: AHRS_M2 2024-09-05T17:40:12.913Z,1725558012.913 [AHRS_M2](INFO): Powering down 2024-09-05T17:40:13.773Z,1725558013.773 [CBIT](INFO): Clearing failed state for component AHRS_M2 2024-09-05T17:40:13.773Z,1725558013.773 [AHRS_M2] No Fault, FailCount= 1 2024-09-05T17:40:14.125Z,1725558014.125 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-09-05T17:40:20.198Z,1725558020.198 [NAL9602](INFO): Powering up NAL9602 2024-09-05T17:40:29.502Z,1725558029.502 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -24.00 mm (1 active estimators). 2024-09-05T17:40:31.102Z,1725558031.102 [NAL9602](INFO): NAL9602 initialized 2024-09-05T17:40:38.016Z,1725558038.016 [VerticalControl](CRITICAL): Excessive depth excursion=13.736926 m, failToGoUpDepth_=1.040080 m, depthRate=0.493385 m/s, pitch=0.053135 deg. 2024-09-05T17:40:38.253Z,1725558038.253 [CommandExec](FAULT): Scheduling is paused 2024-09-05T17:40:38.254Z,1725558038.254 [CBIT](INFO): Critical error at 20240905T174038 2024-09-05T17:40:38.254Z,1725558038.254 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-09-05T17:40:39.589Z,1725558039.589 [DVL_micro](ERROR): No DVL communication! Re-initializing 2024-09-05T17:40:39.589Z,1725558039.589 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T17:40:39.589Z,1725558039.589 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:40:39.768Z,1725558039.768 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:40:40.109Z,1725558040.109 [DVL_micro](INFO): Powering down 2024-09-05T17:40:41.044Z,1725558041.044 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:40:41.044Z,1725558041.044 [DVL_micro] No Fault, FailCount= 1 2024-09-05T17:40:52.131Z,1725558052.131 [VerticalControl](CRITICAL): Dropping drop weight at excessive depth excursion=23.440380, failToGoUpDepth_=1.040080, depthRate=0.815789 m/s, pitch=0.048164 deg. 2024-09-05T17:40:52.194Z,1725558052.194 [CBIT](INFO): Critical error at 20240905T174052 2024-09-05T17:40:58.585Z,1725558058.585 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:40:58.585Z,1725558058.585 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T17:40:58.585Z,1725558058.585 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:40:58.668Z,1725558058.668 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:40:59.080Z,1725558059.080 [DVL_micro](INFO): Powering down 2024-09-05T17:40:59.943Z,1725558059.943 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:40:59.944Z,1725558059.944 [DVL_micro] No Fault, FailCount= 2 2024-09-05T17:41:05.152Z,1725558065.152 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:41:17.437Z,1725558077.437 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:41:17.437Z,1725558077.437 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T17:41:17.437Z,1725558077.437 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:41:17.499Z,1725558077.499 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:41:17.500Z,1725558077.500 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T17:41:17.940Z,1725558077.940 [DVL_micro](INFO): Powering down 2024-09-05T17:41:18.074Z,1725558078.074 [CBIT](INFO): Critical error at 20240905T174117 2024-09-05T17:41:22.541Z,1725558082.541 [CommandExec](IMPORTANT): got command get depth 2024-09-05T17:41:22.541Z,1725558082.541 [CommandExec](IMPORTANT): depth 44.139133 m 2024-09-05T17:41:26.155Z,1725558086.155 [CommandExec](IMPORTANT): got command show best depth 2024-09-05T17:41:26.155Z,1725558086.155 [CommandExec](IMPORTANT): depth best is Depth_Keller.depth 2024-09-05T17:41:32.189Z,1725558092.189 [CommandExec](IMPORTANT): got command failComponent 2024-09-05T17:41:32.189Z,1725558092.189 [CommandExec](IMPORTANT): Failed components: 2024-09-05T17:41:32.189Z,1725558092.189 [CommandExec](IMPORTANT): DVL_micro: Communications Fault 2024-09-05T17:41:35.618Z,1725558095.618 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:41:41.883Z,1725558101.883 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller 2024-09-05T17:41:41.883Z,1725558101.883 [Depth_Keller] Hardware Fault, FailCount= 1 2024-09-05T17:41:41.883Z,1725558101.883 [Depth_Keller](ERROR): Hardware Fault 2024-09-05T17:41:41.883Z,1725558101.883 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault 2024-09-05T17:41:42.179Z,1725558102.179 [CBIT](ERROR): Hardware Fault in component: Depth_Keller 2024-09-05T17:41:42.180Z,1725558102.180 [CBIT](INFO): Clearing failed state for component Depth_Keller 2024-09-05T17:41:42.180Z,1725558102.180 [Depth_Keller] No Fault, FailCount= 1 2024-09-05T17:41:47.073Z,1725558107.073 [CommandExec](IMPORTANT): got command failComponent 2024-09-05T17:41:47.073Z,1725558107.073 [CommandExec](IMPORTANT): Failed components: 2024-09-05T17:41:47.073Z,1725558107.073 [CommandExec](IMPORTANT): DVL_micro: Communications Fault 2024-09-05T17:41:52.548Z,1725558112.548 [CommandExec](IMPORTANT): got command get depth 2024-09-05T17:41:52.549Z,1725558112.549 [CommandExec](IMPORTANT): depth -0.164231 m 2024-09-05T17:42:07.236Z,1725558127.236 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:42:34.001Z,1725558154.001 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2024-09-05T17:42:34.001Z,1725558154.001 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:42:34.037Z,1725558154.037 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:42:34.449Z,1725558154.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:42:34.449Z,1725558154.449 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2024-09-05T17:42:37.634Z,1725558157.634 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:43:09.240Z,1725558189.240 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:43:39.606Z,1725558219.606 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:44:11.216Z,1725558251.216 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:44:17.776Z,1725558257.776 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:44:17.776Z,1725558257.776 [DVL_micro] No Fault, FailCount= 3 2024-09-05T17:44:35.521Z,1725558275.521 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:44:35.521Z,1725558275.521 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T17:44:35.521Z,1725558275.521 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:44:35.539Z,1725558275.539 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:44:36.020Z,1725558276.020 [DVL_micro](INFO): Powering down 2024-09-05T17:44:36.751Z,1725558276.751 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:44:36.751Z,1725558276.751 [DVL_micro] No Fault, FailCount= 1 2024-09-05T17:44:39.973Z,1725558279.973 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-05T17:44:39.973Z,1725558279.973 [Default:CheckIn:C.Wait] Stopped 2024-09-05T17:44:39.973Z,1725558279.973 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T17:44:39.973Z,1725558279.973 [Default:CheckIn:D] Running Loop=1 2024-09-05T17:44:40.377Z,1725558280.377 [Default:CheckIn:D] Stopped 2024-09-05T17:44:40.378Z,1725558280.378 [Default:CheckIn:E] Running Loop=1 2024-09-05T17:44:40.782Z,1725558280.782 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.155402 min 2024-09-05T17:44:40.782Z,1725558280.782 [Default:CheckIn:E] Stopped 2024-09-05T17:44:40.783Z,1725558280.783 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-05T17:44:40.783Z,1725558280.783 [Default:CheckIn] Stopped 2024-09-05T17:44:40.783Z,1725558280.783 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-05T17:44:40.783Z,1725558280.783 [Default:CheckIn](INFO): Running loop #3 2024-09-05T17:44:40.783Z,1725558280.783 [Default:CheckIn] Running Loop=3 2024-09-05T17:44:40.783Z,1725558280.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T17:44:40.783Z,1725558280.783 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T17:44:41.582Z,1725558281.582 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:44:54.509Z,1725558294.509 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:44:54.509Z,1725558294.509 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T17:44:54.509Z,1725558294.509 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:44:54.528Z,1725558294.528 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:44:55.012Z,1725558295.012 [DVL_micro](INFO): Powering down 2024-09-05T17:44:55.738Z,1725558295.738 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:44:55.738Z,1725558295.738 [DVL_micro] No Fault, FailCount= 2 2024-09-05T17:45:13.200Z,1725558313.200 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:45:13.662Z,1725558313.662 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:45:13.663Z,1725558313.663 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T17:45:13.663Z,1725558313.663 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:45:13.682Z,1725558313.682 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:45:13.682Z,1725558313.682 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T17:45:14.148Z,1725558314.148 [DVL_micro](INFO): Powering down 2024-09-05T17:45:14.174Z,1725558314.174 [CBIT](INFO): Critical error at 20240905T174513 2024-09-05T17:45:35.113Z,1725558335.113 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2024-09-05T17:45:35.113Z,1725558335.113 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:45:35.128Z,1725558335.128 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:45:35.524Z,1725558335.524 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:45:35.525Z,1725558335.525 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2024-09-05T17:45:43.554Z,1725558343.554 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:46:15.164Z,1725558375.164 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:46:45.518Z,1725558405.518 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:47:17.128Z,1725558437.128 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:47:47.474Z,1725558467.474 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:48:13.762Z,1725558493.762 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:48:13.762Z,1725558493.762 [DVL_micro] No Fault, FailCount= 3 2024-09-05T17:48:19.092Z,1725558499.092 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:48:31.258Z,1725558511.258 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:48:31.258Z,1725558511.258 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T17:48:31.258Z,1725558511.258 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:48:31.302Z,1725558511.302 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:48:31.760Z,1725558511.760 [DVL_micro](INFO): Powering down 2024-09-05T17:48:32.503Z,1725558512.503 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:48:32.504Z,1725558512.504 [DVL_micro] No Fault, FailCount= 1 2024-09-05T17:48:36.122Z,1725558516.122 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2024-09-05T17:48:36.122Z,1725558516.122 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:48:36.133Z,1725558516.133 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:48:36.540Z,1725558516.540 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:48:36.540Z,1725558516.540 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2024-09-05T17:48:49.446Z,1725558529.446 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:48:50.250Z,1725558530.250 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:48:50.250Z,1725558530.250 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T17:48:50.250Z,1725558530.250 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:48:50.291Z,1725558530.291 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:48:50.752Z,1725558530.752 [DVL_micro](INFO): Powering down 2024-09-05T17:48:51.487Z,1725558531.487 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:48:51.487Z,1725558531.487 [DVL_micro] No Fault, FailCount= 2 2024-09-05T17:49:09.253Z,1725558549.253 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:49:09.254Z,1725558549.254 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T17:49:09.254Z,1725558549.254 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:49:09.302Z,1725558549.302 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:49:09.302Z,1725558549.302 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T17:49:09.736Z,1725558549.736 [DVL_micro](INFO): Powering down 2024-09-05T17:49:09.792Z,1725558549.792 [CBIT](INFO): Critical error at 20240905T174909 2024-09-05T17:49:21.060Z,1725558561.060 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:49:40.918Z,1725558580.918 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-05T17:44:40.8Z 2024-09-05T17:49:40.918Z,1725558580.918 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T17:49:40.918Z,1725558580.918 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T17:49:46.312Z,1725558586.312 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20240905T171814/Courier0010.lzma 2024-09-05T17:49:47.314Z,1725558587.314 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Courier0010.lzma.bak 2024-09-05T17:49:47.314Z,1725558587.314 [DataOverHttps](INFO): SBD MOMSN=23130845 2024-09-05T17:49:51.410Z,1725558591.410 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:50:02.672Z,1725558602.672 [DataOverHttps](INFO): Sending 532 bytes from file Logs/20240905T171814/Express0011.lzma 2024-09-05T17:50:03.674Z,1725558603.674 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Express0011.lzma.bak 2024-09-05T17:50:03.674Z,1725558603.674 [DataOverHttps](INFO): SBD MOMSN=23130854 2024-09-05T17:50:04.749Z,1725558604.749 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T17:50:04.749Z,1725558604.749 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T17:50:04.749Z,1725558604.749 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T17:50:23.024Z,1725558623.024 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T17:50:53.386Z,1725558653.386 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T17:50:54.193Z,1725558654.193 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-05T17:50:54.193Z,1725558654.193 [NAL9602] Data Fault, FailCount= 3 2024-09-05T17:50:54.193Z,1725558654.193 [NAL9602](ERROR): Data Fault 2024-09-05T17:50:54.211Z,1725558654.211 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-05T17:50:54.597Z,1725558654.597 [NAL9602](INFO): Powering down 2024-09-05T17:50:55.001Z,1725558655.001 [NAL9602](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2024-09-05T17:50:55.002Z,1725558655.002 [NAL9602] Hardware Fault, FailCount= 3 2024-09-05T17:50:55.002Z,1725558655.002 [NAL9602](ERROR): Hardware Fault 2024-09-05T17:50:55.422Z,1725558655.422 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-05T17:50:55.423Z,1725558655.423 [NAL9602] No Fault, FailCount= 3 2024-09-05T17:51:24.894Z,1725558684.894 [NAL9602](INFO): Powering up NAL9602 2024-09-05T17:51:30.489Z,1725558690.489 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T17:51:30.580Z,1725558690.580 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T17:51:35.805Z,1725558695.805 [NAL9602](INFO): NAL9602 initialized 2024-09-05T17:51:37.050Z,1725558697.050 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2024-09-05T17:51:37.050Z,1725558697.050 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:51:37.088Z,1725558697.088 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:51:37.448Z,1725558697.448 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:51:37.448Z,1725558697.448 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2024-09-05T17:51:41.505Z,1725558701.505 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.031884 CHAN A1 (24V): -0.205696 CHAN A2 (12V): 0.019666 CHAN A3 (5V): -0.009581 CHAN B0 (3.3V): -0.006038 CHAN B1 (3.15aV): -0.003389 CHAN B2 (3.15bV): -0.002151 CHAN B3 (GND): -0.009498 OPEN: 0.003354 Full Scale: +/- 1 mA 2024-09-05T17:52:09.370Z,1725558729.370 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:52:09.370Z,1725558729.370 [DVL_micro] No Fault, FailCount= 3 2024-09-05T17:52:27.118Z,1725558747.118 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:52:27.118Z,1725558747.118 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T17:52:27.118Z,1725558747.118 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:52:27.187Z,1725558747.187 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:52:27.612Z,1725558747.612 [DVL_micro](INFO): Powering down 2024-09-05T17:52:28.378Z,1725558748.378 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:52:28.378Z,1725558748.378 [DVL_micro] No Fault, FailCount= 1 2024-09-05T17:52:46.101Z,1725558766.101 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:52:46.101Z,1725558766.101 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T17:52:46.101Z,1725558766.101 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:52:46.148Z,1725558766.148 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:52:46.600Z,1725558766.600 [DVL_micro](INFO): Powering down 2024-09-05T17:52:47.331Z,1725558767.331 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:52:47.331Z,1725558767.331 [DVL_micro] No Fault, FailCount= 2 2024-09-05T17:53:05.085Z,1725558785.085 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:53:05.085Z,1725558785.085 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T17:53:05.085Z,1725558785.085 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:53:05.147Z,1725558785.147 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:53:05.147Z,1725558785.147 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T17:53:05.584Z,1725558785.584 [DVL_micro](INFO): Powering down 2024-09-05T17:53:05.634Z,1725558785.634 [CBIT](INFO): Critical error at 20240905T175305 2024-09-05T17:54:38.017Z,1725558878.017 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2024-09-05T17:54:38.017Z,1725558878.017 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:54:38.029Z,1725558878.029 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:54:38.432Z,1725558878.432 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:54:38.433Z,1725558878.433 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2024-09-05T17:55:05.489Z,1725558905.489 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-05T17:55:05.489Z,1725558905.489 [Default:CheckIn:C.Wait] Stopped 2024-09-05T17:55:05.489Z,1725558905.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T17:55:05.489Z,1725558905.489 [Default:CheckIn:D] Running Loop=1 2024-09-05T17:55:05.910Z,1725558905.910 [Default:CheckIn:D] Stopped 2024-09-05T17:55:05.910Z,1725558905.910 [Default:CheckIn:E] Running Loop=1 2024-09-05T17:55:06.320Z,1725558906.320 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.580937 min 2024-09-05T17:55:06.321Z,1725558906.321 [Default:CheckIn:E] Stopped 2024-09-05T17:55:06.321Z,1725558906.321 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-05T17:55:06.321Z,1725558906.321 [Default:CheckIn] Stopped 2024-09-05T17:55:06.321Z,1725558906.321 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-05T17:55:06.321Z,1725558906.321 [Default:CheckIn](INFO): Running loop #4 2024-09-05T17:55:06.321Z,1725558906.321 [Default:CheckIn] Running Loop=4 2024-09-05T17:55:06.321Z,1725558906.321 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T17:55:06.321Z,1725558906.321 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T17:56:05.317Z,1725558965.317 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:56:05.317Z,1725558965.317 [DVL_micro] No Fault, FailCount= 3 2024-09-05T17:56:23.049Z,1725558983.049 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:56:23.049Z,1725558983.049 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T17:56:23.049Z,1725558983.049 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:56:23.099Z,1725558983.099 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:56:23.552Z,1725558983.552 [DVL_micro](INFO): Powering down 2024-09-05T17:56:24.289Z,1725558984.289 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:56:24.289Z,1725558984.289 [DVL_micro] No Fault, FailCount= 1 2024-09-05T17:56:42.037Z,1725559002.037 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:56:42.037Z,1725559002.037 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T17:56:42.037Z,1725559002.037 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:56:42.055Z,1725559002.055 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:56:42.525Z,1725559002.525 [DVL_micro](INFO): Powering down 2024-09-05T17:56:43.273Z,1725559003.273 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T17:56:43.273Z,1725559003.273 [DVL_micro] No Fault, FailCount= 2 2024-09-05T17:57:01.025Z,1725559021.025 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T17:57:01.025Z,1725559021.025 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T17:57:01.025Z,1725559021.025 [DVL_micro](ERROR): Communications Fault 2024-09-05T17:57:01.044Z,1725559021.044 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T17:57:01.044Z,1725559021.044 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T17:57:01.541Z,1725559021.541 [DVL_micro](INFO): Powering down 2024-09-05T17:57:01.557Z,1725559021.557 [CBIT](INFO): Critical error at 20240905T175701 2024-09-05T17:57:39.034Z,1725559059.034 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2024-09-05T17:57:39.034Z,1725559059.034 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T17:57:39.045Z,1725559059.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T17:57:39.430Z,1725559059.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T17:57:39.431Z,1725559059.431 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2024-09-05T18:00:01.275Z,1725559201.275 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:00:01.276Z,1725559201.276 [DVL_micro] No Fault, FailCount= 3 2024-09-05T18:00:06.542Z,1725559206.542 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-05T17:55:06.3Z 2024-09-05T18:00:06.542Z,1725559206.542 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T18:00:06.542Z,1725559206.542 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T18:00:14.164Z,1725559214.164 [DataOverHttps](INFO): Sending 97 bytes from file Logs/20240905T171814/Courier0013.lzma 2024-09-05T18:00:15.162Z,1725559215.162 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Courier0013.lzma.bak 2024-09-05T18:00:15.162Z,1725559215.162 [DataOverHttps](INFO): SBD MOMSN=23130900 2024-09-05T18:00:19.045Z,1725559219.045 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:00:19.045Z,1725559219.045 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T18:00:19.045Z,1725559219.045 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:00:19.115Z,1725559219.115 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:00:19.548Z,1725559219.548 [DVL_micro](INFO): Powering down 2024-09-05T18:00:20.310Z,1725559220.310 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:00:20.310Z,1725559220.310 [DVL_micro] No Fault, FailCount= 1 2024-09-05T18:00:31.109Z,1725559231.109 [DataOverHttps](INFO): Sending 476 bytes from file Logs/20240905T171814/Express0014.lzma 2024-09-05T18:00:32.110Z,1725559232.110 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Express0014.lzma.bak 2024-09-05T18:00:32.110Z,1725559232.110 [DataOverHttps](INFO): SBD MOMSN=23130909 2024-09-05T18:00:33.198Z,1725559233.198 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T18:00:33.198Z,1725559233.198 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T18:00:33.198Z,1725559233.198 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T18:00:38.033Z,1725559238.033 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:00:38.033Z,1725559238.033 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T18:00:38.033Z,1725559238.033 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:00:38.111Z,1725559238.111 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:00:38.544Z,1725559238.544 [DVL_micro](INFO): Powering down 2024-09-05T18:00:39.277Z,1725559239.277 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:00:39.277Z,1725559239.277 [DVL_micro] No Fault, FailCount= 2 2024-09-05T18:00:40.077Z,1725559240.077 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2024-09-05T18:00:40.077Z,1725559240.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:00:40.109Z,1725559240.109 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:00:40.502Z,1725559240.502 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:00:40.502Z,1725559240.502 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2024-09-05T18:00:57.025Z,1725559257.025 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:00:57.025Z,1725559257.025 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T18:00:57.025Z,1725559257.025 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:00:57.043Z,1725559257.043 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:00:57.061Z,1725559257.061 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:00:57.525Z,1725559257.525 [DVL_micro](INFO): Powering down 2024-09-05T18:00:57.543Z,1725559257.543 [CBIT](INFO): Critical error at 20240905T180057 2024-09-05T18:01:37.829Z,1725559297.829 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-05T18:01:37.829Z,1725559297.829 [NAL9602] Data Fault, FailCount= 4 2024-09-05T18:01:37.829Z,1725559297.829 [NAL9602](ERROR): Data Fault 2024-09-05T18:01:37.878Z,1725559297.878 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-05T18:01:38.243Z,1725559298.243 [NAL9602](INFO): Powering down 2024-09-05T18:01:39.076Z,1725559299.076 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-05T18:01:39.076Z,1725559299.076 [NAL9602] No Fault, FailCount= 4 2024-09-05T18:02:08.533Z,1725559328.533 [NAL9602](INFO): Powering up NAL9602 2024-09-05T18:02:19.437Z,1725559339.437 [NAL9602](INFO): NAL9602 initialized 2024-09-05T18:03:41.085Z,1725559421.085 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2024-09-05T18:03:41.085Z,1725559421.085 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:03:41.096Z,1725559421.096 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:03:41.491Z,1725559421.491 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:03:41.491Z,1725559421.491 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2024-09-05T18:03:57.269Z,1725559437.269 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:03:57.269Z,1725559437.269 [DVL_micro] No Fault, FailCount= 3 2024-09-05T18:04:14.994Z,1725559454.994 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:04:14.994Z,1725559454.994 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T18:04:14.994Z,1725559454.994 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:04:15.047Z,1725559455.047 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:04:15.488Z,1725559455.488 [DVL_micro](INFO): Powering down 2024-09-05T18:04:16.236Z,1725559456.236 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:04:16.236Z,1725559456.236 [DVL_micro] No Fault, FailCount= 1 2024-09-05T18:04:33.974Z,1725559473.974 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:04:33.974Z,1725559473.974 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T18:04:33.974Z,1725559473.974 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:04:34.011Z,1725559474.011 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:04:34.476Z,1725559474.476 [DVL_micro](INFO): Powering down 2024-09-05T18:04:35.211Z,1725559475.211 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:04:35.211Z,1725559475.211 [DVL_micro] No Fault, FailCount= 2 2024-09-05T18:04:52.969Z,1725559492.969 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:04:52.969Z,1725559492.969 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T18:04:52.969Z,1725559492.969 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:04:53.007Z,1725559493.007 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:04:53.007Z,1725559493.007 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:04:53.460Z,1725559493.460 [DVL_micro](INFO): Powering down 2024-09-05T18:04:53.482Z,1725559493.482 [CBIT](INFO): Critical error at 20240905T180453 2024-09-05T18:05:33.795Z,1725559533.795 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-05T18:05:33.795Z,1725559533.795 [Default:CheckIn:C.Wait] Stopped 2024-09-05T18:05:33.796Z,1725559533.796 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T18:05:33.796Z,1725559533.796 [Default:CheckIn:D] Running Loop=1 2024-09-05T18:05:34.190Z,1725559534.190 [Default:CheckIn:D] Stopped 2024-09-05T18:05:34.190Z,1725559534.190 [Default:CheckIn:E] Running Loop=1 2024-09-05T18:05:34.607Z,1725559534.607 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.052279 min 2024-09-05T18:05:34.607Z,1725559534.607 [Default:CheckIn:E] Stopped 2024-09-05T18:05:34.607Z,1725559534.607 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-05T18:05:34.607Z,1725559534.607 [Default:CheckIn] Stopped 2024-09-05T18:05:34.607Z,1725559534.607 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-05T18:05:34.607Z,1725559534.607 [Default:CheckIn](INFO): Running loop #5 2024-09-05T18:05:34.607Z,1725559534.607 [Default:CheckIn] Running Loop=5 2024-09-05T18:05:34.607Z,1725559534.607 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T18:05:34.607Z,1725559534.607 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T18:06:42.080Z,1725559602.080 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2024-09-05T18:06:42.080Z,1725559602.080 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:06:42.090Z,1725559602.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:06:42.497Z,1725559602.497 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:06:42.497Z,1725559602.497 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2024-09-05T18:07:53.195Z,1725559673.195 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:07:53.196Z,1725559673.196 [DVL_micro] No Fault, FailCount= 3 2024-09-05T18:08:10.926Z,1725559690.926 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:08:10.926Z,1725559690.926 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T18:08:10.926Z,1725559690.926 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:08:10.969Z,1725559690.969 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:08:11.432Z,1725559691.432 [DVL_micro](INFO): Powering down 2024-09-05T18:08:12.198Z,1725559692.198 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:08:12.198Z,1725559692.198 [DVL_micro] No Fault, FailCount= 1 2024-09-05T18:08:29.921Z,1725559709.921 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:08:29.921Z,1725559709.921 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T18:08:29.921Z,1725559709.921 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:08:29.939Z,1725559709.939 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:08:30.414Z,1725559710.414 [DVL_micro](INFO): Powering down 2024-09-05T18:08:31.184Z,1725559711.184 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:08:31.184Z,1725559711.184 [DVL_micro] No Fault, FailCount= 2 2024-09-05T18:08:48.905Z,1725559728.905 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:08:48.905Z,1725559728.905 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T18:08:48.905Z,1725559728.905 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:08:48.944Z,1725559728.944 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:08:48.944Z,1725559728.944 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:08:49.404Z,1725559729.404 [DVL_micro](INFO): Powering down 2024-09-05T18:08:49.451Z,1725559729.451 [CBIT](INFO): Critical error at 20240905T180848 2024-09-05T18:09:43.077Z,1725559783.077 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2024-09-05T18:09:43.077Z,1725559783.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:09:43.088Z,1725559783.088 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:09:43.491Z,1725559783.491 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:09:43.492Z,1725559783.492 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2024-09-05T18:10:34.799Z,1725559834.799 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-05T18:05:34.6Z 2024-09-05T18:10:34.799Z,1725559834.799 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T18:10:34.799Z,1725559834.799 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T18:10:42.215Z,1725559842.215 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20240905T171814/Courier0016.lzma 2024-09-05T18:10:43.218Z,1725559843.218 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Courier0016.lzma.bak 2024-09-05T18:10:43.218Z,1725559843.218 [DataOverHttps](INFO): SBD MOMSN=23130939 2024-09-05T18:10:58.988Z,1725559858.988 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20240905T171814/Express0017.lzma 2024-09-05T18:10:59.990Z,1725559859.990 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Express0017.lzma.bak 2024-09-05T18:10:59.990Z,1725559859.990 [DataOverHttps](INFO): SBD MOMSN=23130948 2024-09-05T18:11:01.050Z,1725559861.050 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T18:11:01.050Z,1725559861.050 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T18:11:01.050Z,1725559861.050 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T18:11:49.141Z,1725559909.141 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:11:49.141Z,1725559909.141 [DVL_micro] No Fault, FailCount= 3 2024-09-05T18:12:06.889Z,1725559926.889 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:12:06.889Z,1725559926.889 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T18:12:06.889Z,1725559926.889 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:12:06.907Z,1725559926.907 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:12:07.388Z,1725559927.388 [DVL_micro](INFO): Powering down 2024-09-05T18:12:08.142Z,1725559928.142 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:12:08.142Z,1725559928.142 [DVL_micro] No Fault, FailCount= 1 2024-09-05T18:12:22.646Z,1725559942.646 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-05T18:12:22.646Z,1725559942.646 [NAL9602] Data Fault, FailCount= 5 2024-09-05T18:12:22.646Z,1725559942.646 [NAL9602](ERROR): Data Fault 2024-09-05T18:12:22.707Z,1725559942.707 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-05T18:12:22.708Z,1725559942.708 [CBIT](CRITICAL): Data Fault in component: NAL9602 2024-09-05T18:12:23.045Z,1725559943.045 [NAL9602](INFO): Powering down 2024-09-05T18:12:23.072Z,1725559943.072 [CBIT](INFO): Critical error at 20240905T181222 2024-09-05T18:12:25.882Z,1725559945.882 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:12:25.882Z,1725559945.882 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T18:12:25.882Z,1725559945.882 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:12:25.899Z,1725559945.899 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:12:26.368Z,1725559946.368 [DVL_micro](INFO): Powering down 2024-09-05T18:12:27.125Z,1725559947.125 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:12:27.125Z,1725559947.125 [DVL_micro] No Fault, FailCount= 2 2024-09-05T18:12:44.071Z,1725559964.071 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2024-09-05T18:12:44.071Z,1725559964.071 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:12:44.082Z,1725559964.082 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:12:44.498Z,1725559964.498 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:12:44.498Z,1725559964.498 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2024-09-05T18:12:44.866Z,1725559964.866 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:12:44.866Z,1725559964.866 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T18:12:44.866Z,1725559964.866 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:12:44.906Z,1725559964.906 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:12:44.907Z,1725559964.907 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:12:45.376Z,1725559965.376 [DVL_micro](INFO): Powering down 2024-09-05T18:12:45.400Z,1725559965.400 [CBIT](INFO): Critical error at 20240905T181244 2024-09-05T18:14:22.689Z,1725560062.689 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-05T18:14:22.689Z,1725560062.689 [NAL9602] No Fault, FailCount= 5 2024-09-05T18:14:23.066Z,1725560063.066 [NAL9602](INFO): Powering up NAL9602 2024-09-05T18:14:33.970Z,1725560073.970 [NAL9602](INFO): NAL9602 initialized 2024-09-05T18:15:45.093Z,1725560145.093 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2024-09-05T18:15:45.093Z,1725560145.093 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:15:45.103Z,1725560145.103 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:15:45.104Z,1725560145.104 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:15:45.104Z,1725560145.104 [DVL_micro] No Fault, FailCount= 3 2024-09-05T18:15:45.510Z,1725560145.510 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:15:45.511Z,1725560145.511 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2024-09-05T18:16:01.678Z,1725560161.678 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-05T18:16:01.678Z,1725560161.678 [Default:CheckIn:C.Wait] Stopped 2024-09-05T18:16:01.678Z,1725560161.678 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T18:16:01.678Z,1725560161.678 [Default:CheckIn:D] Running Loop=1 2024-09-05T18:16:02.073Z,1725560162.073 [Default:CheckIn:D] Stopped 2024-09-05T18:16:02.073Z,1725560162.073 [Default:CheckIn:E] Running Loop=1 2024-09-05T18:16:02.479Z,1725560162.479 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.516988 min 2024-09-05T18:16:02.479Z,1725560162.479 [Default:CheckIn:E] Stopped 2024-09-05T18:16:02.479Z,1725560162.479 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-05T18:16:02.479Z,1725560162.479 [Default:CheckIn] Stopped 2024-09-05T18:16:02.479Z,1725560162.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-05T18:16:02.479Z,1725560162.479 [Default:CheckIn](INFO): Running loop #6 2024-09-05T18:16:02.479Z,1725560162.479 [Default:CheckIn] Running Loop=6 2024-09-05T18:16:02.479Z,1725560162.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T18:16:02.480Z,1725560162.480 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T18:16:02.861Z,1725560162.861 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:16:02.861Z,1725560162.861 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T18:16:02.861Z,1725560162.861 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:16:02.995Z,1725560162.995 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:16:03.356Z,1725560163.356 [DVL_micro](INFO): Powering down 2024-09-05T18:16:04.091Z,1725560164.091 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:16:04.091Z,1725560164.091 [DVL_micro] No Fault, FailCount= 1 2024-09-05T18:16:21.861Z,1725560181.861 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:16:21.861Z,1725560181.861 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T18:16:21.861Z,1725560181.861 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:16:21.899Z,1725560181.899 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:16:22.348Z,1725560182.348 [DVL_micro](INFO): Powering down 2024-09-05T18:16:23.103Z,1725560183.103 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:16:23.103Z,1725560183.103 [DVL_micro] No Fault, FailCount= 2 2024-09-05T18:16:40.841Z,1725560200.841 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:16:40.841Z,1725560200.841 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T18:16:40.841Z,1725560200.841 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:16:40.899Z,1725560200.899 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:16:40.899Z,1725560200.899 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:16:41.344Z,1725560201.344 [DVL_micro](INFO): Powering down 2024-09-05T18:16:41.382Z,1725560201.382 [CBIT](INFO): Critical error at 20240905T181640 2024-09-05T18:17:23.733Z,1725560243.733 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T18:17:24.104Z,1725560244.104 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T18:17:35.065Z,1725560255.065 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.072524 CHAN A1 (24V): -0.200031 CHAN A2 (12V): 0.023891 CHAN A3 (5V): -0.016322 CHAN B0 (3.3V): -0.005740 CHAN B1 (3.15aV): -0.002955 CHAN B2 (3.15bV): 0.063096 CHAN B3 (GND): -0.018984 OPEN: -0.035275 Full Scale: +/- 1 mA 2024-09-05T18:18:19.613Z,1725560299.613 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T18:18:19.864Z,1725560299.864 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T18:18:30.759Z,1725560310.759 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.023385 CHAN A1 (24V): -0.210654 CHAN A2 (12V): 0.017108 CHAN A3 (5V): -0.011045 CHAN B0 (3.3V): -0.006546 CHAN B1 (3.15aV): -0.004235 CHAN B2 (3.15bV): -0.001895 CHAN B3 (GND): -0.010187 OPEN: -0.002255 Full Scale: +/- 1 mA 2024-09-05T18:18:44.373Z,1725560324.373 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T18:18:44.504Z,1725560324.504 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T18:18:46.108Z,1725560326.108 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2024-09-05T18:18:46.108Z,1725560326.108 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:18:46.151Z,1725560326.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:18:46.534Z,1725560326.534 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:18:46.534Z,1725560326.534 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2024-09-05T18:18:55.416Z,1725560335.416 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.031360 CHAN A1 (24V): -0.207706 CHAN A2 (12V): 0.019559 CHAN A3 (5V): -0.009292 CHAN B0 (3.3V): -0.006602 CHAN B1 (3.15aV): -0.003637 CHAN B2 (3.15bV): -0.001462 CHAN B3 (GND): -0.009931 OPEN: 0.003625 Full Scale: +/- 1 mA 2024-09-05T18:19:03.837Z,1725560343.837 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T18:19:03.901Z,1725560343.901 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T18:19:14.820Z,1725560354.820 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.032834 CHAN A1 (24V): 0.023082 CHAN A2 (12V): -0.014320 CHAN A3 (5V): -0.021683 CHAN B0 (3.3V): -0.010947 CHAN B1 (3.15aV): -0.006280 CHAN B2 (3.15bV): -0.003717 CHAN B3 (GND): -0.010795 OPEN: 0.003731 Full Scale: +/- 1 mA 2024-09-05T18:19:28.193Z,1725560368.193 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T18:19:28.544Z,1725560368.544 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T18:19:39.438Z,1725560379.438 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.032594 CHAN A1 (24V): 0.024702 CHAN A2 (12V): -0.013912 CHAN A3 (5V): -0.020513 CHAN B0 (3.3V): -0.011917 CHAN B1 (3.15aV): -0.006197 CHAN B2 (3.15bV): -0.003280 CHAN B3 (GND): -0.011104 OPEN: 0.003005 Full Scale: +/- 1 mA 2024-09-05T18:19:41.052Z,1725560381.052 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:19:41.052Z,1725560381.052 [DVL_micro] No Fault, FailCount= 3 2024-09-05T18:19:56.381Z,1725560396.381 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2024-09-05T18:19:56.381Z,1725560396.381 [DVL_micro] Data Fault, FailCount= 1 2024-09-05T18:19:56.381Z,1725560396.381 [DVL_micro](ERROR): Data Fault 2024-09-05T18:19:56.416Z,1725560396.416 [CBIT](ERROR): Data Fault in component: DVL_micro 2024-09-05T18:19:56.880Z,1725560396.880 [DVL_micro](INFO): Powering down 2024-09-05T18:19:57.646Z,1725560397.646 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:19:57.646Z,1725560397.646 [DVL_micro] No Fault, FailCount= 1 2024-09-05T18:20:02.677Z,1725560402.677 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T18:20:02.893Z,1725560402.893 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T18:20:13.366Z,1725560413.366 [DVL_micro](INFO): Failure count cleared after critical for DVL_micro 2024-09-05T18:20:13.799Z,1725560413.799 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.033014 CHAN A1 (24V): 0.023364 CHAN A2 (12V): -0.013540 CHAN A3 (5V): -0.021683 CHAN B0 (3.3V): -0.011575 CHAN B1 (3.15aV): -0.005353 CHAN B2 (3.15bV): -0.003252 CHAN B3 (GND): -0.010038 OPEN: 0.003055 Full Scale: +/- 1 mA 2024-09-05T18:20:31.529Z,1725560431.529 [DVL_micro](ERROR): No DVL communication! Re-initializing 2024-09-05T18:20:31.529Z,1725560431.529 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T18:20:31.529Z,1725560431.529 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:20:31.603Z,1725560431.603 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:20:32.028Z,1725560432.028 [DVL_micro](INFO): Powering down 2024-09-05T18:20:32.787Z,1725560432.787 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:20:32.787Z,1725560432.787 [DVL_micro] No Fault, FailCount= 1 2024-09-05T18:20:42.977Z,1725560442.977 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T18:20:43.275Z,1725560443.275 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T18:20:48.096Z,1725560448.096 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2024-09-05T18:20:48.096Z,1725560448.096 [AHRS_M2] Data Fault, FailCount= 1 2024-09-05T18:20:48.096Z,1725560448.096 [AHRS_M2](ERROR): Data Fault 2024-09-05T18:20:48.122Z,1725560448.122 [CBIT](ERROR): Data Fault in component: AHRS_M2 2024-09-05T18:20:48.489Z,1725560448.489 [AHRS_M2](INFO): Powering down 2024-09-05T18:20:49.321Z,1725560449.321 [CBIT](INFO): Clearing failed state for component AHRS_M2 2024-09-05T18:20:49.321Z,1725560449.321 [AHRS_M2] No Fault, FailCount= 1 2024-09-05T18:20:49.701Z,1725560449.701 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-09-05T18:20:50.517Z,1725560450.517 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:20:50.517Z,1725560450.517 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T18:20:50.517Z,1725560450.517 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:20:50.557Z,1725560450.557 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:20:51.016Z,1725560451.016 [DVL_micro](INFO): Powering down 2024-09-05T18:20:51.742Z,1725560451.742 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:20:51.742Z,1725560451.742 [DVL_micro] No Fault, FailCount= 2 2024-09-05T18:20:54.186Z,1725560454.186 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.031678 CHAN A1 (24V): -0.172949 CHAN A2 (12V): 0.013791 CHAN A3 (5V): -0.011069 CHAN B0 (3.3V): -0.007312 CHAN B1 (3.15aV): -0.003995 CHAN B2 (3.15bV): -0.001351 CHAN B3 (GND): -0.010272 OPEN: 0.002790 Full Scale: +/- 1 mA 2024-09-05T18:20:57.052Z,1725560457.052 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T18:21:02.655Z,1725560462.655 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-05T18:16:02.5Z 2024-09-05T18:21:02.655Z,1725560462.655 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T18:21:02.655Z,1725560462.655 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T18:21:09.513Z,1725560469.513 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:21:09.513Z,1725560469.513 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T18:21:09.513Z,1725560469.513 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:21:09.532Z,1725560469.532 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:21:09.532Z,1725560469.532 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:21:09.876Z,1725560469.876 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20240905T171814/Courier0019.lzma 2024-09-05T18:21:10.000Z,1725560470.000 [DVL_micro](INFO): Powering down 2024-09-05T18:21:10.022Z,1725560470.022 [CBIT](INFO): Critical error at 20240905T182109 2024-09-05T18:21:10.878Z,1725560470.878 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Courier0019.lzma.bak 2024-09-05T18:21:10.878Z,1725560470.878 [DataOverHttps](INFO): SBD MOMSN=23130979 2024-09-05T18:21:26.225Z,1725560486.225 [DataOverHttps](INFO): Sending 729 bytes from file Logs/20240905T171814/Express0020.lzma 2024-09-05T18:21:27.226Z,1725560487.226 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Express0020.lzma.bak 2024-09-05T18:21:27.226Z,1725560487.226 [DataOverHttps](INFO): SBD MOMSN=23130986 2024-09-05T18:21:28.596Z,1725560488.596 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T18:21:28.622Z,1725560488.622 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T18:21:28.622Z,1725560488.622 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T18:21:28.622Z,1725560488.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T18:21:47.266Z,1725560507.266 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2024-09-05T18:21:47.266Z,1725560507.266 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:21:47.277Z,1725560507.277 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:21:47.689Z,1725560507.689 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:21:47.689Z,1725560507.689 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2024-09-05T18:21:58.974Z,1725560518.974 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T18:22:30.624Z,1725560550.624 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T18:23:00.974Z,1725560580.974 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T18:23:32.588Z,1725560612.588 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T18:24:02.942Z,1725560642.942 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T18:24:09.826Z,1725560649.826 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:24:09.826Z,1725560649.826 [DVL_micro] No Fault, FailCount= 3 2024-09-05T18:24:27.581Z,1725560667.581 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:24:27.581Z,1725560667.581 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T18:24:27.581Z,1725560667.581 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:24:27.651Z,1725560667.651 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:24:28.080Z,1725560668.080 [DVL_micro](INFO): Powering down 2024-09-05T18:24:28.836Z,1725560668.836 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:24:28.836Z,1725560668.836 [DVL_micro] No Fault, FailCount= 1 2024-09-05T18:24:34.564Z,1725560674.564 [NAL9602](ERROR): getFix uart error: serial timeout 2024-09-05T18:24:46.334Z,1725560686.334 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:24:46.334Z,1725560686.334 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T18:24:46.334Z,1725560686.334 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:24:46.380Z,1725560686.380 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:24:46.828Z,1725560686.828 [DVL_micro](INFO): Powering down 2024-09-05T18:24:47.577Z,1725560687.577 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:24:47.577Z,1725560687.577 [DVL_micro] No Fault, FailCount= 2 2024-09-05T18:24:48.374Z,1725560688.374 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2024-09-05T18:24:48.374Z,1725560688.374 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:24:48.385Z,1725560688.385 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:24:48.789Z,1725560688.789 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:24:48.789Z,1725560688.789 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2024-09-05T18:25:04.922Z,1725560704.922 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-09-05T18:25:05.321Z,1725560705.321 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:25:05.321Z,1725560705.321 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T18:25:05.321Z,1725560705.321 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:25:05.373Z,1725560705.373 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:25:05.374Z,1725560705.374 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:25:05.824Z,1725560705.824 [DVL_micro](INFO): Powering down 2024-09-05T18:25:05.826Z,1725560705.826 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-05T18:25:05.826Z,1725560705.826 [NAL9602] Data Fault, FailCount= 1 2024-09-05T18:25:05.826Z,1725560705.826 [NAL9602](ERROR): Data Fault 2024-09-05T18:25:05.846Z,1725560705.846 [CBIT](INFO): Critical error at 20240905T182505 2024-09-05T18:25:05.889Z,1725560705.889 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-05T18:25:06.129Z,1725560706.129 [NAL9602](INFO): Powering down 2024-09-05T18:25:06.969Z,1725560706.969 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-05T18:25:06.969Z,1725560706.969 [NAL9602] No Fault, FailCount= 1 2024-09-05T18:25:36.461Z,1725560736.461 [NAL9602](INFO): Powering up NAL9602 2024-09-05T18:25:47.370Z,1725560747.370 [NAL9602](INFO): NAL9602 initialized 2024-09-05T18:26:29.099Z,1725560789.099 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-05T18:26:29.099Z,1725560789.099 [Default:CheckIn:C.Wait] Stopped 2024-09-05T18:26:29.099Z,1725560789.099 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-05T18:26:29.099Z,1725560789.099 [Default:CheckIn:D] Running Loop=1 2024-09-05T18:26:29.519Z,1725560789.519 [Default:CheckIn:D] Stopped 2024-09-05T18:26:29.520Z,1725560789.520 [Default:CheckIn:E] Running Loop=1 2024-09-05T18:26:29.932Z,1725560789.932 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.974434 min 2024-09-05T18:26:29.932Z,1725560789.932 [Default:CheckIn:E] Stopped 2024-09-05T18:26:29.932Z,1725560789.932 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-05T18:26:29.932Z,1725560789.932 [Default:CheckIn] Stopped 2024-09-05T18:26:29.932Z,1725560789.932 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-05T18:26:29.932Z,1725560789.932 [Default:CheckIn](INFO): Running loop #7 2024-09-05T18:26:29.933Z,1725560789.933 [Default:CheckIn] Running Loop=7 2024-09-05T18:26:29.933Z,1725560789.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-05T18:26:29.933Z,1725560789.933 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-05T18:26:47.757Z,1725560807.757 [CommandExec](IMPORTANT): got command failComponent 2024-09-05T18:26:47.757Z,1725560807.757 [CommandExec](IMPORTANT): Failed components: 2024-09-05T18:26:47.757Z,1725560807.757 [CommandExec](IMPORTANT): DVL_micro: Communications Fault 2024-09-05T18:26:51.183Z,1725560811.183 [CommandExec](IMPORTANT): got command failComponent none DVL_micro 2024-09-05T18:26:51.183Z,1725560811.183 [DVL_micro] No Fault, FailCount= 3 2024-09-05T18:26:51.183Z,1725560811.183 [CommandExec](IMPORTANT): DVL_micro failureMode is No Fault 2024-09-05T18:26:52.813Z,1725560812.813 [CommandExec](IMPORTANT): got command failComponent 2024-09-05T18:26:52.813Z,1725560812.813 [CommandExec](IMPORTANT): Failed components: 2024-09-05T18:26:52.813Z,1725560812.813 [CommandExec](IMPORTANT): No failed Components. 2024-09-05T18:26:55.725Z,1725560815.725 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T18:26:55.798Z,1725560815.798 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T18:27:06.073Z,1725560826.073 [CommandExec](IMPORTANT): got command strobe off 2024-09-05T18:27:06.074Z,1725560826.074 [CommandExec](IMPORTANT): Deactivating strobe 2024-09-05T18:27:06.737Z,1725560826.737 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.033262 CHAN A1 (24V): -0.202585 CHAN A2 (12V): 0.019557 CHAN A3 (5V): -0.008888 CHAN B0 (3.3V): -0.006805 CHAN B1 (3.15aV): -0.003230 CHAN B2 (3.15bV): -0.002080 CHAN B3 (GND): -0.009471 OPEN: 0.002789 Full Scale: +/- 1 mA 2024-09-05T18:27:08.697Z,1725560828.697 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:27:08.697Z,1725560828.697 [DVL_micro] Communications Fault, FailCount= 4 2024-09-05T18:27:08.697Z,1725560828.697 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:27:08.770Z,1725560828.770 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:27:08.771Z,1725560828.771 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:27:09.200Z,1725560829.200 [DVL_micro](INFO): Powering down 2024-09-05T18:27:09.245Z,1725560829.245 [CBIT](INFO): Critical error at 20240905T182708 2024-09-05T18:27:09.517Z,1725560829.517 [CommandExec](IMPORTANT): got command gfscan 2024-09-05T18:27:09.559Z,1725560829.559 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-05T18:27:20.452Z,1725560840.452 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.032096 CHAN A1 (24V): -0.204772 CHAN A2 (12V): 0.018688 CHAN A3 (5V): -0.009425 CHAN B0 (3.3V): -0.006843 CHAN B1 (3.15aV): -0.003783 CHAN B2 (3.15bV): -0.002074 CHAN B3 (GND): -0.010119 OPEN: 0.003048 Full Scale: +/- 1 mA 2024-09-05T18:27:28.110Z,1725560848.110 [CommandExec](IMPORTANT): got command configSet list 2024-09-05T18:27:28.110Z,1725560848.110 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-09-05T18:27:28.111Z,1725560848.111 [CommandExec](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-09-05T18:27:28.124Z,1725560848.124 [CommandExec](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-09-05T18:27:28.125Z,1725560848.125 [CommandExec](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-09-05T18:27:28.125Z,1725560848.125 [CommandExec](IMPORTANT): Depth_Keller.offset=3.6 decibar; 2024-09-05T18:27:28.126Z,1725560848.126 [CommandExec](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2024-09-05T18:27:28.127Z,1725560848.127 [CommandExec](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-09-05T18:27:28.127Z,1725560848.127 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=186.137647 cubic_centimeter; 2024-09-05T18:27:28.232Z,1725560848.232 [CommandExec](IMPORTANT): VerticalControl.massDefault=27.004010 millimeter; 2024-09-05T18:27:49.541Z,1725560869.541 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23 2024-09-05T18:27:49.541Z,1725560869.541 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:27:49.605Z,1725560869.605 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:27:49.967Z,1725560869.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:27:49.967Z,1725560869.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23 2024-09-05T18:29:34.311Z,1725560974.311 [CommandExec](IMPORTANT): got command get depth 2024-09-05T18:29:34.311Z,1725560974.311 [CommandExec](IMPORTANT): depth -0.151722 m 2024-09-05T18:30:09.128Z,1725561009.128 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:30:09.128Z,1725561009.128 [DVL_micro] No Fault, FailCount= 4 2024-09-05T18:30:26.853Z,1725561026.853 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:30:26.853Z,1725561026.853 [DVL_micro] Communications Fault, FailCount= 1 2024-09-05T18:30:26.853Z,1725561026.853 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:30:26.976Z,1725561026.976 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:30:27.328Z,1725561027.328 [DVL_micro](INFO): Powering down 2024-09-05T18:30:28.078Z,1725561028.078 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:30:28.078Z,1725561028.078 [DVL_micro] No Fault, FailCount= 1 2024-09-05T18:30:45.821Z,1725561045.821 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:30:45.821Z,1725561045.821 [DVL_micro] Communications Fault, FailCount= 2 2024-09-05T18:30:45.821Z,1725561045.821 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:30:45.839Z,1725561045.839 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:30:46.320Z,1725561046.320 [DVL_micro](INFO): Powering down 2024-09-05T18:30:47.050Z,1725561047.050 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-09-05T18:30:47.050Z,1725561047.050 [DVL_micro] No Fault, FailCount= 2 2024-09-05T18:30:50.716Z,1725561050.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24 2024-09-05T18:30:50.716Z,1725561050.716 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-05T18:30:50.727Z,1725561050.727 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-05T18:30:51.109Z,1725561051.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-05T18:30:51.110Z,1725561051.110 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24 2024-09-05T18:31:01.303Z,1725561061.303 [CommandExec](IMPORTANT): got command get DVL_micro.loadControl 2024-09-05T18:31:01.303Z,1725561061.303 [CommandExec](IMPORTANT): DVL_micro.loadControl /dev/loadB4 2024-09-05T18:31:04.870Z,1725561064.870 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:31:04.870Z,1725561064.870 [DVL_micro] Communications Fault, FailCount= 3 2024-09-05T18:31:04.870Z,1725561064.870 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:31:04.953Z,1725561064.953 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:31:04.954Z,1725561064.954 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:31:05.338Z,1725561065.338 [DVL_micro](INFO): Powering down 2024-09-05T18:31:05.375Z,1725561065.375 [CBIT](INFO): Critical error at 20240905T183104 2024-09-05T18:31:14.791Z,1725561074.791 [CommandExec](IMPORTANT): got command failComponent none DVL_micro 2024-09-05T18:31:14.791Z,1725561074.791 [DVL_micro] No Fault, FailCount= 3 2024-09-05T18:31:14.791Z,1725561074.791 [CommandExec](IMPORTANT): DVL_micro failureMode is No Fault 2024-09-05T18:31:30.332Z,1725561090.332 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-05T18:26:29.9Z 2024-09-05T18:31:30.332Z,1725561090.332 [Default:CheckIn:Read_GPS] Stopped 2024-09-05T18:31:30.332Z,1725561090.332 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-05T18:31:32.431Z,1725561092.431 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-09-05T18:31:32.431Z,1725561092.431 [DVL_micro] Communications Fault, FailCount= 4 2024-09-05T18:31:32.431Z,1725561092.431 [DVL_micro](ERROR): Communications Fault 2024-09-05T18:31:32.524Z,1725561092.524 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-09-05T18:31:32.525Z,1725561092.525 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-09-05T18:31:32.828Z,1725561092.828 [DVL_micro](INFO): Powering down 2024-09-05T18:31:32.921Z,1725561092.921 [CBIT](INFO): Critical error at 20240905T183132 2024-09-05T18:31:37.908Z,1725561097.908 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20240905T171814/Courier0022.lzma 2024-09-05T18:31:38.910Z,1725561098.910 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Courier0022.lzma.bak 2024-09-05T18:31:38.910Z,1725561098.910 [DataOverHttps](INFO): SBD MOMSN=23131052 2024-09-05T18:31:57.917Z,1725561117.917 [DataOverHttps](INFO): Sending 913 bytes from file Logs/20240905T171814/Express0023.lzma 2024-09-05T18:31:58.918Z,1725561118.918 [DataOverHttps](INFO): Moved sent file to Logs/20240905T171814/Express0023.lzma.bak 2024-09-05T18:31:58.918Z,1725561118.918 [DataOverHttps](INFO): SBD MOMSN=23131058 2024-09-05T18:31:59.949Z,1725561119.949 [Default:CheckIn:Read_Iridium] Stopped 2024-09-05T18:31:59.949Z,1725561119.949 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-05T18:31:59.949Z,1725561119.949 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-05T18:32:07.077Z,1725561127.077 [CommandExec](IMPORTANT): got command burn on 2024-09-05T18:32:07.077Z,1725561127.077 [CommandExec](IMPORTANT): Activating dropweight wire 2024-09-05T18:32:31.582Z,1725561151.582 [CommandExec](IMPORTANT): got command burn off 2024-09-05T18:32:31.582Z,1725561151.582 [CommandExec](IMPORTANT): Deactivating dropweight wire 2024-09-05T18:32:39.157Z,1725561159.157 [CommandExec](IMPORTANT): got command restart system 2024-09-05T18:32:41.276Z,1725561161.276 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T18:32:41.277Z,1725561161.277 [CommandExec](INFO): Uninitializing the command executive. 2024-09-05T18:32:41.277Z,1725561161.277 [CommandExec](INFO): Uninitializing the command scheduler. 2024-09-05T18:32:41.277Z,1725561161.277 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-09-05T18:32:41.448Z,1725561161.448 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-09-05T18:32:41.448Z,1725561161.448 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-09-05T18:32:41.449Z,1725561161.449 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-09-05T18:32:41.449Z,1725561161.449 [NavChartDb](INFO): Join timeout helper Thread ID is 2824 2024-09-05T18:32:41.688Z,1725561161.688 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T18:32:41.689Z,1725561161.689 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-09-05T18:32:41.700Z,1725561161.700 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-09-05T18:32:41.700Z,1725561161.700 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-09-05T18:32:41.701Z,1725561161.701 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2826 2024-09-05T18:32:42.116Z,1725561162.116 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T18:32:42.117Z,1725561162.117 [WetLabsBB2FL](INFO): Powering down 2024-09-05T18:32:42.118Z,1725561162.118 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-09-05T18:32:42.120Z,1725561162.120 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-09-05T18:32:42.120Z,1725561162.120 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-09-05T18:32:42.121Z,1725561162.121 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2827 2024-09-05T18:32:42.340Z,1725561162.340 [CTD_Seabird](INFO): Powering down 2024-09-05T18:32:42.352Z,1725561162.352 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-05T18:32:42.353Z,1725561162.353 [CTD_Seabird](INFO): Powering down 2024-09-05T18:32:42.364Z,1725561162.364 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-09-05T18:32:42.372Z,1725561162.372 [ComponentRegistry](INFO):