2025-03-10T16:29:34.982Z,1741624174.982 [Supervisor](DEBUG): Initializing supervisor. 2025-03-10T16:29:34.987Z,1741624174.987 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-03-10T16:29:34.988Z,1741624174.988 [SyncHandler](INFO): Protected caller Thread ID is 1135 2025-03-10T16:29:34.988Z,1741624174.988 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-03-10T16:29:34.990Z,1741624174.990 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-03-10T16:29:34.990Z,1741624174.990 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1136 2025-03-10T16:29:34.994Z,1741624174.994 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-03-10T16:29:35.014Z,1741624175.014 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-03-10T16:29:35.014Z,1741624175.014 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-03-10T16:29:35.015Z,1741624175.015 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1137 2025-03-10T16:29:35.019Z,1741624175.019 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-03-10T16:29:35.020Z,1741624175.020 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-03-10T16:29:35.020Z,1741624175.020 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1138 2025-03-10T16:29:35.023Z,1741624175.023 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-03-10T16:29:35.024Z,1741624175.024 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-03-10T16:29:35.024Z,1741624175.024 [logger ThreadHandler](INFO): Protected caller Thread ID is 1139 2025-03-10T16:29:35.028Z,1741624175.028 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-03-10T16:29:35.028Z,1741624175.028 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-03-10T16:29:35.030Z,1741624175.030 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-03-10T16:29:35.258Z,1741624175.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-03-10T16:29:35.259Z,1741624175.259 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-03-10T16:29:35.431Z,1741624175.431 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-03-10T16:29:35.432Z,1741624175.432 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-03-10T16:29:35.541Z,1741624175.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-03-10T16:29:35.542Z,1741624175.542 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-03-10T16:29:36.040Z,1741624176.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-03-10T16:29:36.041Z,1741624176.041 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-03-10T16:29:36.401Z,1741624176.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-03-10T16:29:36.401Z,1741624176.401 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-03-10T16:29:36.757Z,1741624176.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-03-10T16:29:36.758Z,1741624176.758 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-03-10T16:29:36.957Z,1741624176.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-03-10T16:29:36.957Z,1741624176.957 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-03-10T16:29:37.046Z,1741624177.046 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-03-10T16:29:37.136Z,1741624177.136 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-03-10T16:29:37.561Z,1741624177.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-03-10T16:29:37.561Z,1741624177.561 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-03-10T16:29:37.863Z,1741624177.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-03-10T16:29:37.864Z,1741624177.864 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-03-10T16:29:38.429Z,1741624178.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-03-10T16:29:38.430Z,1741624178.430 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-03-10T16:29:38.685Z,1741624178.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-03-10T16:29:38.685Z,1741624178.685 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-03-10T16:29:38.941Z,1741624178.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-03-10T16:29:38.942Z,1741624178.942 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-03-10T16:29:39.092Z,1741624179.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-03-10T16:29:39.092Z,1741624179.092 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-03-10T16:29:39.973Z,1741624179.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-03-10T16:29:39.975Z,1741624179.975 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2025-03-10T16:29:39.976Z,1741624179.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2025-03-10T16:29:40.069Z,1741624180.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2025-03-10T16:29:40.208Z,1741624180.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2025-03-10T16:29:40.527Z,1741624180.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-03-10T16:29:40.527Z,1741624180.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2025-03-10T16:29:40.769Z,1741624180.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2025-03-10T16:29:40.929Z,1741624180.929 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2025-03-10T16:29:41.201Z,1741624181.201 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2025-03-10T16:29:41.332Z,1741624181.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2025-03-10T16:29:41.429Z,1741624181.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2025-03-10T16:29:41.526Z,1741624181.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2025-03-10T16:29:41.628Z,1741624181.628 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2025-03-10T16:29:41.807Z,1741624181.807 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2025-03-10T16:29:41.807Z,1741624181.807 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-03-10T16:29:41.820Z,1741624181.820 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-03-10T16:29:41.872Z,1741624181.872 [DepthRateCalculator] Loaded 2025-03-10T16:29:41.873Z,1741624181.873 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-03-10T16:29:41.878Z,1741624181.878 [PitchRateCalculator] Loaded 2025-03-10T16:29:41.878Z,1741624181.878 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-03-10T16:29:41.889Z,1741624181.889 [SpeedCalculator] Loaded 2025-03-10T16:29:41.889Z,1741624181.889 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-03-10T16:29:41.894Z,1741624181.894 [YawRateCalculator] Loaded 2025-03-10T16:29:41.894Z,1741624181.894 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-03-10T16:29:41.916Z,1741624181.916 [ElevatorOffsetCalculator] Loaded 2025-03-10T16:29:41.916Z,1741624181.916 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-03-10T16:29:41.917Z,1741624181.917 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-03-10T16:29:41.917Z,1741624181.917 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-03-10T16:29:41.946Z,1741624181.946 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-03-10T16:29:41.947Z,1741624181.947 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-03-10T16:29:41.957Z,1741624181.957 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-03-10T16:29:41.958Z,1741624181.958 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-03-10T16:29:42.461Z,1741624182.461 [AHRS_M2] Loaded 2025-03-10T16:29:42.461Z,1741624182.461 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-03-10T16:29:42.499Z,1741624182.499 [BackseatComponent] Loaded 2025-03-10T16:29:42.500Z,1741624182.500 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-03-10T16:29:42.501Z,1741624182.501 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0 2025-03-10T16:29:42.501Z,1741624182.501 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1221 2025-03-10T16:29:42.504Z,1741624182.504 [LcmUniversalReporter] Loaded 2025-03-10T16:29:42.504Z,1741624182.504 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-03-10T16:29:43.294Z,1741624183.294 [BPC1] Loaded 2025-03-10T16:29:43.294Z,1741624183.294 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-03-10T16:29:43.369Z,1741624183.369 [DataOverHttps] Loaded 2025-03-10T16:29:43.370Z,1741624183.370 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-03-10T16:29:43.371Z,1741624183.371 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0 2025-03-10T16:29:43.371Z,1741624183.371 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1222 2025-03-10T16:29:43.393Z,1741624183.393 [Depth_Keller] Loaded 2025-03-10T16:29:43.394Z,1741624183.394 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-03-10T16:29:43.398Z,1741624183.398 [DropWeight] Loaded 2025-03-10T16:29:43.399Z,1741624183.399 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-03-10T16:29:43.420Z,1741624183.420 [MultiRay](INFO): Running on single LCB 2025-03-10T16:29:43.420Z,1741624183.420 [MultiRay] Loaded 2025-03-10T16:29:43.420Z,1741624183.420 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2025-03-10T16:29:43.485Z,1741624183.485 [NAL9602] Loaded 2025-03-10T16:29:43.485Z,1741624183.485 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-03-10T16:29:43.515Z,1741624183.515 [Onboard] Loaded 2025-03-10T16:29:43.515Z,1741624183.515 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-03-10T16:29:43.516Z,1741624183.516 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0 2025-03-10T16:29:43.516Z,1741624183.516 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1223 2025-03-10T16:29:43.522Z,1741624183.522 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2025-03-10T16:29:43.536Z,1741624183.536 [PowerOnly] Loaded 2025-03-10T16:29:43.536Z,1741624183.536 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2025-03-10T16:29:43.550Z,1741624183.550 [Power24vConverter] Loaded 2025-03-10T16:29:43.550Z,1741624183.550 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-03-10T16:29:43.567Z,1741624183.567 [Radio_Surface] Loaded 2025-03-10T16:29:43.567Z,1741624183.567 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-03-10T16:29:43.568Z,1741624183.568 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409054E0 2025-03-10T16:29:43.569Z,1741624183.569 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1224 2025-03-10T16:29:43.594Z,1741624183.594 [Waterlinked] Loaded 2025-03-10T16:29:43.594Z,1741624183.594 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2025-03-10T16:29:43.595Z,1741624183.595 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-03-10T16:29:43.595Z,1741624183.595 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-03-10T16:29:43.708Z,1741624183.708 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-03-10T16:29:43.708Z,1741624183.708 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-03-10T16:29:43.721Z,1741624183.721 [NavChart] Loaded 2025-03-10T16:29:43.722Z,1741624183.722 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-03-10T16:29:43.727Z,1741624183.727 [UniversalFixResidualReporter] Loaded 2025-03-10T16:29:43.728Z,1741624183.728 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-03-10T16:29:43.728Z,1741624183.728 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-03-10T16:29:43.729Z,1741624183.729 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-03-10T16:29:43.894Z,1741624183.894 [SBIT](DEBUG): Construct Startup Built In Test. 2025-03-10T16:29:43.908Z,1741624183.908 [SBIT] Loaded 2025-03-10T16:29:43.908Z,1741624183.908 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-03-10T16:29:43.911Z,1741624183.911 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-03-10T16:29:43.924Z,1741624183.924 [IBIT] Loaded 2025-03-10T16:29:43.924Z,1741624183.924 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-03-10T16:29:43.930Z,1741624183.930 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-03-10T16:29:44.008Z,1741624184.008 [CBIT] Loaded 2025-03-10T16:29:44.009Z,1741624184.009 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-03-10T16:29:44.041Z,1741624184.041 [GFScanner] Loaded 2025-03-10T16:29:44.041Z,1741624184.041 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-03-10T16:29:44.041Z,1741624184.041 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-03-10T16:29:44.042Z,1741624184.042 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-03-10T16:29:44.211Z,1741624184.211 [CTD_Seabird] Loaded 2025-03-10T16:29:44.211Z,1741624184.211 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-03-10T16:29:44.212Z,1741624184.212 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0 2025-03-10T16:29:44.213Z,1741624184.213 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1225 2025-03-10T16:29:44.235Z,1741624184.235 [PAR_Licor] Loaded 2025-03-10T16:29:44.235Z,1741624184.235 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-03-10T16:29:44.281Z,1741624184.281 [WetLabsBB2FL] Loaded 2025-03-10T16:29:44.281Z,1741624184.281 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-03-10T16:29:44.282Z,1741624184.282 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0 2025-03-10T16:29:44.283Z,1741624184.283 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1226 2025-03-10T16:29:44.284Z,1741624184.284 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-03-10T16:29:44.284Z,1741624184.284 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-03-10T16:29:44.425Z,1741624184.425 [BuoyancyServo] Loaded 2025-03-10T16:29:44.425Z,1741624184.425 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-03-10T16:29:44.449Z,1741624184.449 [ElevatorServo] Loaded 2025-03-10T16:29:44.449Z,1741624184.449 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-03-10T16:29:44.473Z,1741624184.473 [MassServo] Loaded 2025-03-10T16:29:44.473Z,1741624184.473 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-03-10T16:29:44.496Z,1741624184.496 [RudderServo] Loaded 2025-03-10T16:29:44.496Z,1741624184.496 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-03-10T16:29:44.515Z,1741624184.515 [ThrusterHE] Loaded 2025-03-10T16:29:44.515Z,1741624184.515 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-03-10T16:29:44.515Z,1741624184.515 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-03-10T16:29:44.516Z,1741624184.516 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-03-10T16:29:44.570Z,1741624184.570 [VerticalControl](DEBUG): Construct VerticalControl. 2025-03-10T16:29:44.623Z,1741624184.623 [VerticalControl] Loaded 2025-03-10T16:29:44.623Z,1741624184.623 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-03-10T16:29:44.626Z,1741624184.626 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-03-10T16:29:44.671Z,1741624184.671 [HorizontalControl] Loaded 2025-03-10T16:29:44.671Z,1741624184.671 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-03-10T16:29:44.673Z,1741624184.673 [SpeedControl](DEBUG): Construct SpeedControl. 2025-03-10T16:29:44.675Z,1741624184.675 [SpeedControl] Loaded 2025-03-10T16:29:44.675Z,1741624184.675 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-03-10T16:29:44.678Z,1741624184.678 [LoopControl](DEBUG): Construct LoopControl. 2025-03-10T16:29:44.679Z,1741624184.679 [LoopControl] Loaded 2025-03-10T16:29:44.679Z,1741624184.679 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-03-10T16:29:44.680Z,1741624184.680 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-03-10T16:29:44.680Z,1741624184.680 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-03-10T16:29:44.817Z,1741624184.817 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-03-10T16:29:44.817Z,1741624184.817 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-03-10T16:29:44.999Z,1741624184.999 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-03-10T16:29:44.000Z,1741624185.000 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-03-10T16:29:45.352Z,1741624185.352 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-03-10T16:29:45.353Z,1741624185.353 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-03-10T16:29:45.413Z,1741624185.413 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-03-10T16:29:45.419Z,1741624185.419 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-03-10T16:29:45.422Z,1741624185.422 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-03-10T16:29:45.433Z,1741624185.433 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-03-10T16:29:45.434Z,1741624185.434 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-03-10T16:29:45.435Z,1741624185.435 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1227 2025-03-10T16:29:45.439Z,1741624185.439 [Supervisor](INFO): Main Thread ID is 829 2025-03-10T16:29:45.439Z,1741624185.439 [Supervisor](DEBUG): Running supervisor. 2025-03-10T16:29:45.440Z,1741624185.440 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1228 2025-03-10T16:29:45.440Z,1741624185.440 [CommandExec](INFO): Initializing the command executive. 2025-03-10T16:29:45.442Z,1741624185.442 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1229 2025-03-10T16:29:45.444Z,1741624185.444 [controlThread ThreadHandler](INFO): Handler Thread ID is 1230 2025-03-10T16:29:45.445Z,1741624185.445 [controlThread](DEBUG): Initializing ControlThread 2025-03-10T16:29:45.445Z,1741624185.445 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-03-10T16:29:45.446Z,1741624185.446 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-03-10T16:29:45.446Z,1741624185.446 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-03-10T16:29:45.447Z,1741624185.447 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-03-10T16:29:45.447Z,1741624185.447 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-03-10T16:29:45.454Z,1741624185.454 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-03-10T16:29:45.454Z,1741624185.454 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-03-10T16:29:45.454Z,1741624185.454 [SBIT](INFO): Initialize SBIT Component. 2025-03-10T16:29:45.455Z,1741624185.455 [SBIT](IMPORTANT): git: 2025-03-06_B-3-g06c58acc0 2025-03-10T16:29:45.456Z,1741624185.456 [SBIT](INFO): git hash: 06c58acc0c21dac1722dadabfa9aaff283a6c1e9 2025-03-10T16:29:45.456Z,1741624185.456 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-03-10T16:29:45.457Z,1741624185.457 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-03-10T16:29:45.458Z,1741624185.458 [SBIT](INFO): Beginning SBIT in 73.000000 seconds. 2025-03-10T16:29:45.459Z,1741624185.459 [IBIT](INFO): Initialize IBIT Component. 2025-03-10T16:29:45.460Z,1741624185.460 [CBIT](DEBUG): Initialize CBIT Component. 2025-03-10T16:29:45.461Z,1741624185.461 [logger ThreadHandler](INFO): Handler Thread ID is 1231 2025-03-10T16:29:45.471Z,1741624185.471 [CBIT](DEBUG): Initialized mux pins. 2025-03-10T16:29:45.471Z,1741624185.471 [CBIT](DEBUG): Initializing the watchdog timer. 2025-03-10T16:29:45.480Z,1741624185.480 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1232 2025-03-10T16:29:45.491Z,1741624185.491 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1233 2025-03-10T16:29:45.493Z,1741624185.493 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-03-10T16:29:45.495Z,1741624185.495 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-03-10T16:29:45.495Z,1741624185.495 [CBIT](DEBUG): Initializing heartbeat. 2025-03-10T16:29:45.503Z,1741624185.503 [Onboard ThreadHandler](INFO): Handler Thread ID is 1234 2025-03-10T16:29:45.521Z,1741624185.521 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1235 2025-03-10T16:29:45.544Z,1741624185.544 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1236 2025-03-10T16:29:45.544Z,1741624185.544 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-03-10T16:29:45.548Z,1741624185.548 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1238 2025-03-10T16:29:45.551Z,1741624185.551 [WetLabsBB2FL](INFO): Powering up 2025-03-10T16:29:45.552Z,1741624185.552 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1240 2025-03-10T16:29:45.555Z,1741624185.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-03-10T16:29:45.555Z,1741624185.555 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-03-10T16:29:45.556Z,1741624185.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-03-10T16:29:45.556Z,1741624185.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-03-10T16:29:45.556Z,1741624185.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-03-10T16:29:45.556Z,1741624185.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-03-10T16:29:45.556Z,1741624185.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-03-10T16:29:45.556Z,1741624185.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-03-10T16:29:45.567Z,1741624185.567 [CBIT](DEBUG): Deactivating emergency mode. 2025-03-10T16:29:45.607Z,1741624185.607 [CBIT](DEBUG): Backplane powered. 2025-03-10T16:29:45.607Z,1741624185.607 [GFScanner](DEBUG): Initializing GFScanner 2025-03-10T16:29:45.607Z,1741624185.607 [GFScanner](DEBUG): Deactivating GF circuits. 2025-03-10T16:29:45.616Z,1741624185.616 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-03-10T16:29:45.618Z,1741624185.618 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-03-10T16:29:45.619Z,1741624185.619 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-03-10T16:29:45.619Z,1741624185.619 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-03-10T16:29:45.620Z,1741624185.620 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-03-10T16:29:45.621Z,1741624185.621 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-03-10T16:29:45.621Z,1741624185.621 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-03-10T16:29:45.622Z,1741624185.622 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-03-10T16:29:45.714Z,1741624185.714 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-03-10T16:29:45.740Z,1741624185.740 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-10T16:29:45.760Z,1741624185.760 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2025-03-10T16:29:45.761Z,1741624185.761 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-03-10T16:29:45.761Z,1741624185.761 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-03-10T16:29:45.762Z,1741624185.762 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-03-10T16:29:45.935Z,1741624185.935 [Radio_Surface](INFO): Powering up 2025-03-10T16:29:45.995Z,1741624185.995 [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 2025-03-10T16:29:46.000Z,1741624186.000 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-03-10T16:29:46.002Z,1741624186.002 [Default:A.Wait](DEBUG): Construct Wait. 2025-03-10T16:29:46.004Z,1741624186.004 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-10T16:29:46.040Z,1741624186.040 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-03-10T16:29:46.043Z,1741624186.043 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-03-10T16:29:46.049Z,1741624186.049 [Default:E.Execute](DEBUG): Construct Execute. 2025-03-10T16:29:46.076Z,1741624186.076 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2025-03-10T16:29:46.080Z,1741624186.080 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-03-10T16:29:46.088Z,1741624186.088 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-03-10T16:29:46.142Z,1741624186.142 [MultiRay](INFO): Powering up MultiRay Lights 2025-03-10T16:29:46.156Z,1741624186.156 [Power24vConverter](INFO): Powering up. 2025-03-10T16:29:46.224Z,1741624186.224 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-03-10T16:29:46.231Z,1741624186.231 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-03-10T16:29:46.232Z,1741624186.232 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-10T16:29:46.243Z,1741624186.243 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-10T16:29:46.244Z,1741624186.244 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-10T16:29:46.251Z,1741624186.251 [MassServo](DEBUG): Initializing MassServo. 2025-03-10T16:29:46.252Z,1741624186.252 [RudderServo](DEBUG): Initializing EZServoServo. 2025-03-10T16:29:46.259Z,1741624186.259 [RudderServo](DEBUG): Initializing RudderServo. 2025-03-10T16:29:46.260Z,1741624186.260 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-10T16:29:46.267Z,1741624186.267 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-10T16:29:46.536Z,1741624186.536 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-10T16:29:46.536Z,1741624186.536 [DropWeight] Hardware Fault, FailCount= 1 2025-03-10T16:29:46.536Z,1741624186.536 [DropWeight](ERROR): Hardware Fault 2025-03-10T16:29:46.582Z,1741624186.582 [CommandExec](FAULT): Scheduling is paused 2025-03-10T16:29:46.582Z,1741624186.582 [CBIT](INFO): Critical error at 20250310T162946 2025-03-10T16:29:46.587Z,1741624186.587 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-03-10T16:29:46.589Z,1741624186.589 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-10T16:29:46.590Z,1741624186.590 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-10T16:29:46.936Z,1741624186.936 [CBIT](INFO): Critical error at 20250310T162946 2025-03-10T16:29:48.191Z,1741624188.191 [WetLabsBB2FL](INFO): Powering down 2025-03-10T16:29:52.104Z,1741624192.104 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-03-10T16:29:52.582Z,1741624192.582 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-03-10T16:30:13.605Z,1741624213.605 [NAL9602](INFO): Powering up NAL9602 2025-03-10T16:30:23.011Z,1741624223.011 [Waterlinked](INFO): Powering down 2025-03-10T16:30:24.501Z,1741624224.501 [NAL9602](INFO): NAL9602 initialized 2025-03-10T16:30:35.844Z,1741624235.844 [CommandExec](IMPORTANT): got command strobe off 2025-03-10T16:30:35.844Z,1741624235.844 [CommandExec](IMPORTANT): Deactivating strobe 2025-03-10T16:30:59.280Z,1741624259.280 [SBIT](IMPORTANT): Beginning Startup BIT 2025-03-10T16:30:59.284Z,1741624259.284 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-03-10T16:31:03.880Z,1741624263.880 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.006587 CHAN A1 (24V): 0.042122 CHAN A2 (12V): -0.005300 CHAN A3 (5V): -0.002770 CHAN B0 (3.3V): -0.000789 CHAN B1 (3.15aV): -0.001788 CHAN B2 (3.15bV): -0.001156 CHAN B3 (GND): -0.000553 OPEN: 0.004979 Full Scale: +/- 1 mA 2025-03-10T16:31:52.622Z,1741624312.622 [SBIT](IMPORTANT): SBIT PASSED 2025-03-10T16:31:52.622Z,1741624312.622 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-03-10T16:31:52.624Z,1741624312.624 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2025-03-10T16:31:52.624Z,1741624312.624 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2025-03-10T16:31:52.624Z,1741624312.624 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2025-03-10T16:31:52.624Z,1741624312.624 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2025-03-10T16:31:52.624Z,1741624312.624 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter; 2025-03-10T16:31:52.624Z,1741624312.624 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter; 2025-03-10T16:31:52.624Z,1741624312.624 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool; 2025-03-10T16:31:52.624Z,1741624312.624 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool; 2025-03-10T16:31:52.625Z,1741624312.625 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool; 2025-03-10T16:31:52.625Z,1741624312.625 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool; 2025-03-10T16:31:52.625Z,1741624312.625 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool; 2025-03-10T16:31:52.625Z,1741624312.625 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=185.902950 cubic_centimeter; 2025-03-10T16:31:52.625Z,1741624312.625 [SBIT](IMPORTANT): VerticalControl.massDefault=13.432478 millimeter; 2025-03-10T16:31:53.037Z,1741624313.037 [MissionManager](IMPORTANT): Started mission Startup 2025-03-10T16:31:53.037Z,1741624313.037 [Startup] Running Loop=1 2025-03-10T16:31:53.037Z,1741624313.037 [Startup](DEBUG): Aggregate::initialize Startup 2025-03-10T16:31:53.037Z,1741624313.037 [Startup:A.GoToSurface] Running Loop=1 2025-03-10T16:31:53.037Z,1741624313.037 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-10T16:31:53.038Z,1741624313.038 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-10T16:31:53.038Z,1741624313.038 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-10T16:31:53.039Z,1741624313.039 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-10T16:31:53.039Z,1741624313.039 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-10T16:31:53.040Z,1741624313.040 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-10T16:31:53.040Z,1741624313.040 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-10T16:31:53.042Z,1741624313.042 [Startup:StartupSatComms] Running Loop=1 2025-03-10T16:31:53.043Z,1741624313.043 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-03-10T16:31:53.043Z,1741624313.043 [Startup:StartupSatComms:A] Running Loop=1 2025-03-10T16:31:53.417Z,1741624313.417 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-03-10T16:32:13.382Z,1741624333.382 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005198 2025-03-10T16:32:45.547Z,1741624365.547 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-03-10T16:32:45.547Z,1741624365.547 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:32:45.562Z,1741624365.562 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:32:45.975Z,1741624365.975 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:32:45.976Z,1741624365.976 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-03-10T16:32:53.240Z,1741624373.240 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-10T16:31:53.0Z 2025-03-10T16:32:53.240Z,1741624373.240 [Startup:StartupSatComms:A] Stopped 2025-03-10T16:32:53.240Z,1741624373.240 [Startup:StartupSatComms:B] Running Loop=1 2025-03-10T16:32:53.755Z,1741624373.755 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-10T16:32:55.120Z,1741624375.120 [CommandExec](IMPORTANT): got command set platform_communications 1 bool 2025-03-10T16:33:02.022Z,1741624382.022 [CommandExec](IMPORTANT): got command set platform_communications 1 bool 2025-03-10T16:33:03.291Z,1741624383.291 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T161547/Courier0007.lzma 2025-03-10T16:33:04.293Z,1741624384.293 [DataOverHttps](INFO): Moved sent file to Logs/20250310T161547/Courier0007.lzma.bak 2025-03-10T16:33:04.293Z,1741624384.293 [DataOverHttps](INFO): SBD MOMSN=24149141 2025-03-10T16:33:21.963Z,1741624401.963 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20250310T162934/Courier0000.lzma 2025-03-10T16:33:22.965Z,1741624402.965 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0000.lzma.bak 2025-03-10T16:33:22.965Z,1741624402.965 [DataOverHttps](INFO): SBD MOMSN=24149143 2025-03-10T16:33:23.050Z,1741624403.050 [CommandExec](IMPORTANT): got command set platform_communications 1 bool 2025-03-10T16:33:38.483Z,1741624418.483 [DataOverHttps](INFO): Sending 445 bytes from file Logs/20250310T161506/Express0001.lzma 2025-03-10T16:33:39.485Z,1741624419.485 [DataOverHttps](INFO): Moved sent file to Logs/20250310T161506/Express0001.lzma.bak 2025-03-10T16:33:39.485Z,1741624419.485 [DataOverHttps](INFO): SBD MOMSN=24149148 2025-03-10T16:33:49.228Z,1741624429.228 [CommandExec](IMPORTANT): got command show stack 2025-03-10T16:33:49.228Z,1741624429.228 [CommandExec](IMPORTANT): Behavior Stack: 2025-03-10T16:33:49.229Z,1741624429.229 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface 2025-03-10T16:33:49.229Z,1741624429.229 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B 2025-03-10T16:33:53.471Z,1741624433.471 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-10T16:32:53.2Z 2025-03-10T16:33:53.471Z,1741624433.471 [Startup:StartupSatComms:B] Stopped 2025-03-10T16:33:53.473Z,1741624433.473 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-03-10T16:33:53.473Z,1741624433.473 [Startup:StartupSatComms] Stopped 2025-03-10T16:33:53.473Z,1741624433.473 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-03-10T16:33:53.475Z,1741624433.475 [Startup](INFO): Completed Startup 2025-03-10T16:33:53.475Z,1741624433.475 [MissionManager](INFO): Startup is completed. 2025-03-10T16:33:53.476Z,1741624433.476 [MissionManager](INFO): Uninitializing Mission Startup 2025-03-10T16:33:53.476Z,1741624433.476 [Startup] Stopped 2025-03-10T16:33:53.476Z,1741624433.476 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-03-10T16:33:53.476Z,1741624433.476 [Startup:A.GoToSurface] Stopped 2025-03-10T16:33:53.476Z,1741624433.476 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-10T16:33:53.845Z,1741624433.845 [MissionManager](IMPORTANT): Started mission Default 2025-03-10T16:33:53.845Z,1741624433.845 [Default] Running Loop=1 2025-03-10T16:33:53.845Z,1741624433.845 [Default](DEBUG): Aggregate::initialize Default 2025-03-10T16:33:53.845Z,1741624433.845 [Default:B.GoToSurface] Running Loop=1 2025-03-10T16:33:53.846Z,1741624433.846 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-10T16:33:53.846Z,1741624433.846 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-10T16:33:53.846Z,1741624433.846 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-10T16:33:53.846Z,1741624433.846 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-10T16:33:53.883Z,1741624433.883 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-10T16:33:53.883Z,1741624433.883 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-10T16:33:53.884Z,1741624433.884 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-10T16:33:53.884Z,1741624433.884 [Default:A.Wait] Running Loop=1 2025-03-10T16:33:53.884Z,1741624433.884 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:33:56.245Z,1741624436.245 [DataOverHttps](INFO): Sending 1100 bytes from file Logs/20250310T161547/Express0001.lzma 2025-03-10T16:33:57.245Z,1741624437.245 [DataOverHttps](INFO): Moved sent file to Logs/20250310T161547/Express0001.lzma.bak 2025-03-10T16:33:57.245Z,1741624437.245 [DataOverHttps](INFO): SBD MOMSN=24149157 2025-03-10T16:33:59.368Z,1741624439.368 [CommandExec](IMPORTANT): got command set platform_communications 1 bool 2025-03-10T16:34:04.196Z,1741624444.196 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/lab_test_optim.tl 2025-03-10T16:34:04.196Z,1741624444.196 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/lab_test_optim.tl 2025-03-10T16:34:04.196Z,1741624444.196 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/lab_test_optim.tl 2025-03-10T16:34:04.197Z,1741624444.197 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/lab_test_optim.tl 2025-03-10T16:34:04.505Z,1741624444.505 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/lab_test_optim.tl translated into: 15 1 30 2 100 100 Waiting for backseat to start up... Trying to run Optim and Multiray forreal 1 Setting multiray red brightness to 2 Setting muliray white brightness to Recording for 2025-03-10T16:34:04.557Z,1741624444.557 [MissionManager](INFO): DefineArg lab_test_optim.MissionTimeout = 15.000000 min 2025-03-10T16:34:04.601Z,1741624444.601 [MissionManager](INFO): DefineArg lab_test_optim.RecordTime = 1.000000 min 2025-03-10T16:34:04.613Z,1741624444.613 [MissionManager](INFO): DefineArg lab_test_optim.BackseatWait = 30.000000 s 2025-03-10T16:34:04.616Z,1741624444.616 [MissionManager](INFO): DefineArg lab_test_optim.LightMode = 2.000000 count 2025-03-10T16:34:04.619Z,1741624444.619 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessWhite = 100.000000 n/a int32 2025-03-10T16:34:04.622Z,1741624444.622 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessRed = 100.000000 n/a int32 2025-03-10T16:34:04.645Z,1741624444.645 [MissionManager](INFO): DefineOutput lab_test_optim.RecordingEnabled = 0 bool 2025-03-10T16:34:04.648Z,1741624444.648 [MissionManager](INFO): DefineOutput lab_test_optim.LightsEnabled = 0 bool 2025-03-10T16:34:04.649Z,1741624444.649 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-03-10T16:34:04.649Z,1741624444.649 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-03-10T16:34:04.650Z,1741624444.650 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-03-10T16:34:04.705Z,1741624444.705 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-03-10T16:34:04.757Z,1741624444.757 [MissionManager](INFO): DefineArg lab_test_optim:BackseatDriver.EnableBackseat = 0 bool 2025-03-10T16:34:04.781Z,1741624444.781 [lab_test_optim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-03-10T16:34:04.805Z,1741624444.805 [lab_test_optim:Startup:B.Wait](DEBUG): Construct Wait. 2025-03-10T16:34:04.846Z,1741624444.846 [lab_test_optim:Run:H.Wait](DEBUG): Construct Wait. 2025-03-10T16:34:04.856Z,1741624444.856 [lab_test_optim:G.Wait](DEBUG): Construct Wait. 2025-03-10T16:34:04.869Z,1741624444.869 [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 lab_test_optim { arguments { MissionTimeout = 15 minute """ Backseat will stay on for this duration, independent of Optim state """ RecordTime = 1 minute """ How long to record after startup sequence (hopefully) turns on Optim camera """ BackseatWait = 30 second """ How long to wait for the backseat to power up before attempting to power the Optim """ LightMode = 2 count """ Mode for Multiray lights. 0 is off, 1 is red, 2 is white """ BrightnessWhite = 100 none_int """ White Multiray LED brightness, 0-100 """ BrightnessRed = 100 none_int """ Red Multiray LED brightness, 0-100 """ } output { RecordingEnabled = false LightsEnabled = false } timeout duration=MissionTimeout insert Insert/BackseatDriver.tl assign in parallel BackseatDriver:EnableBackseat = true readData strategy="MinError" { while ( RecordingEnabled ) Sensor:PowerOnly.sampleLoad1 } readData strategy="MinError" { while ( LightsEnabled ) Sensor:MultiRay.lightModeLog } aggregate Startup { run in sequence syslog important "Waiting " + BackseatWait~s + " for backseat to start up..." behavior Guidance:Wait { run in sequence set duration = BackseatWait } } aggregate Run { run in sequence syslog important "Trying to run Optim and Multiray forreal" assign in sequence RecordingEnabled = true assign in sequence LightsEnabled = true assign in sequence Sensor:MultiRay.lightModeCommand = LightMode aggregate setRed { run in sequence break if ( LightMode != 1 count ) assign in sequence Sensor:MultiRay.brightnessRed = BrightnessRed syslog info "Setting multiray red brightness to" + BrightnessRed~count } aggregate setWhite { run in sequence break if ( LightMode != 2 count ) assign in sequence Sensor:MultiRay.brightnessWhite = BrightnessWhite syslog info "Setting muliray white brightness to" + BrightnessWhite~count } syslog important "Recording for " + RecordTime~min behavior Guidance:Wait { run in sequence set duration = RecordTime } } behavior Guidance:Wait { run in sequence set duration = MissionTimeout } } 2025-03-10T16:34:04.876Z,1741624444.876 [CommandExec](IMPORTANT): Running ./Missions/Engineering/lab_test_optim.tl 2025-03-10T16:34:05.153Z,1741624445.153 [Default] Stopped 2025-03-10T16:34:05.153Z,1741624445.153 [Default](DEBUG): Aggregate::uninitialize Default 2025-03-10T16:34:05.153Z,1741624445.153 [Default:A.Wait] Stopped 2025-03-10T16:34:05.153Z,1741624445.153 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:34:05.153Z,1741624445.153 [Default:B.GoToSurface] Stopped 2025-03-10T16:34:05.153Z,1741624445.153 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-10T16:34:05.153Z,1741624445.153 [MissionManager](IMPORTANT): Started mission lab_test_optim 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim] Running Loop=1 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim](DEBUG): Aggregate::initialize lab_test_optim 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim:BackseatDriver] Running Loop=1 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::initialize lab_test_optim:BackseatDriver 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim:B] Running Loop=1 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim:C] Running Loop=1 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim:D] Running Loop=1 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim:Startup] Running Loop=1 2025-03-10T16:34:05.154Z,1741624445.154 [lab_test_optim:Startup](DEBUG): Aggregate::initialize lab_test_optim:Startup 2025-03-10T16:34:05.155Z,1741624445.155 [lab_test_optim:Startup:A] Running Loop=1 2025-03-10T16:34:05.156Z,1741624445.156 [lab_test_optim:Startup:A](IMPORTANT): Waiting 30.000000 s for backseat to start up... 2025-03-10T16:34:05.156Z,1741624445.156 [lab_test_optim:Startup:A] Stopped 2025-03-10T16:34:05.156Z,1741624445.156 [lab_test_optim:Startup:B.Wait] Running Loop=1 2025-03-10T16:34:05.156Z,1741624445.156 [lab_test_optim:Startup:B.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:34:05.156Z,1741624445.156 [lab_test_optim:D] Stopped 2025-03-10T16:34:05.156Z,1741624445.156 [lab_test_optim:C] Stopped 2025-03-10T16:34:05.156Z,1741624445.156 [lab_test_optim:B] Running Loop=1 2025-03-10T16:34:05.156Z,1741624445.156 [lab_test_optim:BackseatDriver] Running Loop=1 2025-03-10T16:34:05.157Z,1741624445.157 [lab_test_optim:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat 2025-03-10T16:34:05.255Z,1741624445.255 [BackseatComponent](INFO): Powering up 2025-03-10T16:34:05.256Z,1741624445.256 [BackseatComponent](INFO): Subscribing to LCM channels. 2025-03-10T16:34:35.901Z,1741624475.901 [lab_test_optim:Startup:B.Wait](INFO): Done Waiting. 2025-03-10T16:34:35.901Z,1741624475.901 [lab_test_optim:Startup:B.Wait] Stopped 2025-03-10T16:34:35.901Z,1741624475.901 [lab_test_optim:Startup:B.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:34:35.901Z,1741624475.901 [lab_test_optim:Startup](INFO): Completed lab_test_optim:Startup 2025-03-10T16:34:35.902Z,1741624475.902 [lab_test_optim:Startup] Stopped 2025-03-10T16:34:35.902Z,1741624475.902 [lab_test_optim:Startup](DEBUG): Aggregate::uninitialize lab_test_optim:Startup 2025-03-10T16:34:35.902Z,1741624475.902 [lab_test_optim:Run] Running Loop=1 2025-03-10T16:34:35.902Z,1741624475.902 [lab_test_optim:Run](DEBUG): Aggregate::initialize lab_test_optim:Run 2025-03-10T16:34:35.902Z,1741624475.902 [lab_test_optim:Run:A] Running Loop=1 2025-03-10T16:34:36.264Z,1741624476.264 [lab_test_optim:Run:A](IMPORTANT): Trying to run Optim and Multiray forreal 2025-03-10T16:34:36.264Z,1741624476.264 [lab_test_optim:Run:A] Stopped 2025-03-10T16:34:36.265Z,1741624476.265 [lab_test_optim:Run:B] Running Loop=1 2025-03-10T16:34:36.676Z,1741624476.676 [lab_test_optim:Run:B] Stopped 2025-03-10T16:34:36.676Z,1741624476.676 [lab_test_optim:Run:C] Running Loop=1 2025-03-10T16:34:37.086Z,1741624477.086 [lab_test_optim:Run:C] Stopped 2025-03-10T16:34:37.086Z,1741624477.086 [lab_test_optim:Run:D] Running Loop=1 2025-03-10T16:34:37.086Z,1741624477.086 [lab_test_optim:C] Running Loop=1 2025-03-10T16:34:37.086Z,1741624477.086 [lab_test_optim:C] Running Loop=1 2025-03-10T16:34:37.087Z,1741624477.087 [lab_test_optim:C](DEBUG): Initialize ReadDataComponent to sense PowerOnly.sampleLoad1 2025-03-10T16:34:37.458Z,1741624477.458 [PowerOnly](INFO): Powering up loadControl 2025-03-10T16:34:37.519Z,1741624477.519 [lab_test_optim:Run:D] Stopped 2025-03-10T16:34:37.519Z,1741624477.519 [lab_test_optim:Run:setRed] Running Loop=1 2025-03-10T16:34:37.519Z,1741624477.519 [lab_test_optim:Run:setRed](DEBUG): Aggregate::initialize lab_test_optim:Run:setRed 2025-03-10T16:34:37.519Z,1741624477.519 [lab_test_optim:Run:setRed:A] Running Loop=1 2025-03-10T16:34:37.519Z,1741624477.519 [lab_test_optim:D] Running Loop=1 2025-03-10T16:34:37.519Z,1741624477.519 [lab_test_optim:D] Running Loop=1 2025-03-10T16:34:37.520Z,1741624477.520 [lab_test_optim:D](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog 2025-03-10T16:34:37.880Z,1741624477.880 [lab_test_optim:Run:setRed] Stopped 2025-03-10T16:34:37.881Z,1741624477.881 [lab_test_optim:Run:setRed](DEBUG): Aggregate::uninitialize lab_test_optim:Run:setRed 2025-03-10T16:34:37.881Z,1741624477.881 [lab_test_optim:Run:setRed:A] Stopped 2025-03-10T16:34:37.881Z,1741624477.881 [lab_test_optim:Run:setWhite] Running Loop=1 2025-03-10T16:34:37.881Z,1741624477.881 [lab_test_optim:Run:setWhite](DEBUG): Aggregate::initialize lab_test_optim:Run:setWhite 2025-03-10T16:34:37.881Z,1741624477.881 [lab_test_optim:Run:setWhite:A] Running Loop=1 2025-03-10T16:34:38.297Z,1741624478.297 [lab_test_optim:Run:setWhite:A] Stopped 2025-03-10T16:34:38.341Z,1741624478.341 [lab_test_optim:Run:setWhite:B] Running Loop=1 2025-03-10T16:34:38.721Z,1741624478.721 [lab_test_optim:Run:setWhite:B](INFO): Setting muliray white brightness to 100.000000 count 2025-03-10T16:34:38.721Z,1741624478.721 [lab_test_optim:Run:setWhite:B] Stopped 2025-03-10T16:34:38.721Z,1741624478.721 [lab_test_optim:Run:setWhite](INFO): Completed lab_test_optim:Run:setWhite 2025-03-10T16:34:38.721Z,1741624478.721 [lab_test_optim:Run:setWhite] Stopped 2025-03-10T16:34:38.721Z,1741624478.721 [lab_test_optim:Run:setWhite](DEBUG): Aggregate::uninitialize lab_test_optim:Run:setWhite 2025-03-10T16:34:38.722Z,1741624478.722 [lab_test_optim:Run:G] Running Loop=1 2025-03-10T16:34:39.118Z,1741624479.118 [lab_test_optim:Run:G](IMPORTANT): Recording for 1.000000 min 2025-03-10T16:34:39.118Z,1741624479.118 [lab_test_optim:Run:G] Stopped 2025-03-10T16:34:39.123Z,1741624479.123 [lab_test_optim:Run:H.Wait] Running Loop=1 2025-03-10T16:34:39.123Z,1741624479.123 [lab_test_optim:Run:H.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:35:03.770Z,1741624503.770 [BackseatComponent](IMPORTANT): ikshana: running backseat application. 2025-03-10T16:35:06.283Z,1741624506.283 [BackseatComponent](INFO): Created universal data reader for depth with code: 6 2025-03-10T16:35:06.284Z,1741624506.284 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1 2025-03-10T16:35:28.365Z,1741624528.365 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-10T16:35:39.719Z,1741624539.718 [lab_test_optim:Run:H.Wait](INFO): Done Waiting. 2025-03-10T16:35:39.719Z,1741624539.719 [lab_test_optim:Run:H.Wait] Stopped 2025-03-10T16:35:39.719Z,1741624539.719 [lab_test_optim:Run:H.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:35:39.719Z,1741624539.719 [lab_test_optim:Run](INFO): Completed lab_test_optim:Run 2025-03-10T16:35:39.719Z,1741624539.719 [lab_test_optim:Run] Stopped 2025-03-10T16:35:39.719Z,1741624539.719 [lab_test_optim:Run](DEBUG): Aggregate::uninitialize lab_test_optim:Run 2025-03-10T16:35:39.720Z,1741624539.720 [lab_test_optim:G.Wait] Running Loop=1 2025-03-10T16:35:39.720Z,1741624539.720 [lab_test_optim:G.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:35:46.612Z,1741624546.612 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-03-10T16:35:46.612Z,1741624546.612 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:35:46.628Z,1741624546.628 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:35:47.003Z,1741624547.003 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:35:47.003Z,1741624547.003 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-03-10T16:37:07.748Z,1741624627.748 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2025-03-10T16:37:07.752Z,1741624627.752 [BPC1](INFO): Received data from all battery sticks. 2025-03-10T16:38:47.569Z,1741624727.569 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-03-10T16:38:47.569Z,1741624727.569 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:38:47.584Z,1741624727.584 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:38:47.959Z,1741624727.959 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:38:47.959Z,1741624727.959 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-03-10T16:40:26.517Z,1741624826.517 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T16:40:26.517Z,1741624826.517 [NAL9602] Data Fault, FailCount= 1 2025-03-10T16:40:26.517Z,1741624826.517 [NAL9602](ERROR): Data Fault 2025-03-10T16:40:26.535Z,1741624826.535 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T16:40:26.937Z,1741624826.937 [NAL9602](INFO): Powering down 2025-03-10T16:40:27.782Z,1741624827.782 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T16:40:27.782Z,1741624827.782 [NAL9602] No Fault, FailCount= 1 2025-03-10T16:40:57.224Z,1741624857.224 [NAL9602](INFO): Powering up NAL9602 2025-03-10T16:41:08.140Z,1741624868.140 [NAL9602](INFO): NAL9602 initialized 2025-03-10T16:41:48.568Z,1741624908.568 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-03-10T16:41:48.568Z,1741624908.568 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:41:48.585Z,1741624908.585 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:41:48.979Z,1741624908.979 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:41:48.979Z,1741624908.979 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-03-10T16:44:46.751Z,1741625086.751 [CBIT](INFO): Clearing failed state for component DropWeight 2025-03-10T16:44:46.751Z,1741625086.751 [DropWeight] No Fault, FailCount= 1 2025-03-10T16:44:49.568Z,1741625089.568 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-03-10T16:44:49.568Z,1741625089.568 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:44:49.579Z,1741625089.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:44:49.979Z,1741625089.979 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:44:49.979Z,1741625089.979 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-03-10T16:45:04.512Z,1741625104.512 [lab_test_optim:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.data_dir_logging_rate with code: 1934 2025-03-10T16:47:50.560Z,1741625270.560 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2025-03-10T16:47:50.560Z,1741625270.560 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:47:50.571Z,1741625270.571 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:47:50.979Z,1741625270.979 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:47:50.979Z,1741625270.979 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2025-03-10T16:49:05.274Z,1741625345.274 [lab_test_optim](INFO): Timed out from 2025-03-10T16:34:05.2Z 2025-03-10T16:49:05.274Z,1741625345.274 [MissionManager](INFO): lab_test_optim is completed. 2025-03-10T16:49:05.274Z,1741625345.274 [MissionManager](INFO): Uninitializing Mission lab_test_optim 2025-03-10T16:49:05.274Z,1741625345.274 [lab_test_optim] Stopped 2025-03-10T16:49:05.274Z,1741625345.274 [lab_test_optim](DEBUG): Aggregate::uninitialize lab_test_optim 2025-03-10T16:49:05.274Z,1741625345.274 [lab_test_optim:BackseatDriver] Stopped 2025-03-10T16:49:05.274Z,1741625345.274 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::uninitialize lab_test_optim:BackseatDriver 2025-03-10T16:49:05.275Z,1741625345.275 [lab_test_optim:BackseatDriver:A.BackseatDriver] Stopped 2025-03-10T16:49:05.275Z,1741625345.275 [lab_test_optim:B] Stopped 2025-03-10T16:49:05.275Z,1741625345.275 [lab_test_optim:C] Stopped 2025-03-10T16:49:05.275Z,1741625345.275 [lab_test_optim:D] Stopped 2025-03-10T16:49:05.275Z,1741625345.275 [lab_test_optim:G.Wait] Stopped 2025-03-10T16:49:05.275Z,1741625345.275 [lab_test_optim:G.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:49:05.685Z,1741625345.685 [MissionManager](IMPORTANT): Started mission Default 2025-03-10T16:49:05.686Z,1741625345.686 [Default] Running Loop=1 2025-03-10T16:49:05.686Z,1741625345.686 [Default](DEBUG): Aggregate::initialize Default 2025-03-10T16:49:05.686Z,1741625345.686 [Default:B.GoToSurface] Running Loop=1 2025-03-10T16:49:05.686Z,1741625345.686 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-10T16:49:05.686Z,1741625345.686 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-10T16:49:05.686Z,1741625345.686 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-10T16:49:05.687Z,1741625345.687 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-10T16:49:05.687Z,1741625345.687 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-10T16:49:05.687Z,1741625345.687 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-10T16:49:05.688Z,1741625345.688 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-10T16:49:05.688Z,1741625345.688 [Default:A.Wait] Running Loop=1 2025-03-10T16:49:05.688Z,1741625345.688 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:49:06.282Z,1741625346.282 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-03-10T16:49:06.283Z,1741625346.283 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2025-03-10T16:49:19.014Z,1741625359.014 [Default:A.Wait](INFO): Done Waiting. 2025-03-10T16:49:19.014Z,1741625359.014 [Default:A.Wait] Stopped 2025-03-10T16:49:19.014Z,1741625359.014 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:49:19.428Z,1741625359.428 [Default:CheckIn] Running Loop=1 2025-03-10T16:49:19.428Z,1741625359.428 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T16:49:19.428Z,1741625359.428 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T16:49:19.841Z,1741625359.841 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-03-10T16:49:24.443Z,1741625364.443 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-03-10T16:49:24.444Z,1741625364.444 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-03-10T16:49:24.444Z,1741625364.444 [LcmUniversalReporter](INFO): Deactivating messaging. 2025-03-10T16:49:57.412Z,1741625397.412 [CommandExec](IMPORTANT): got command stop 2025-03-10T16:49:57.412Z,1741625397.412 [CommandExec](IMPORTANT): Scheduling is paused 2025-03-10T16:49:57.412Z,1741625397.412 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-03-10T16:50:35.102Z,1741625435.102 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/multiray_test.tl 2025-03-10T16:50:35.102Z,1741625435.102 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/multiray_test.tl 2025-03-10T16:50:35.102Z,1741625435.102 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/multiray_test.tl 2025-03-10T16:50:35.110Z,1741625435.110 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/multiray_test.tl 2025-03-10T16:50:35.600Z,1741625435.600 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/multiray_test.tl translated into: 5 5 0 1 2 2025-03-10T16:50:35.610Z,1741625435.610 [MissionManager](INFO): DefineArg multiray_test.MissionTimeout = 5.000000 min 2025-03-10T16:50:35.617Z,1741625435.617 [MissionManager](INFO): DefineArg multiray_test.LightDuration = 5.000000 s 2025-03-10T16:50:35.624Z,1741625435.624 [MissionManager](INFO): DefineArg multiray_test.LightMode0 = 0.000000 count 2025-03-10T16:50:35.631Z,1741625435.631 [MissionManager](INFO): DefineArg multiray_test.LightMode1 = 1.000000 count 2025-03-10T16:50:35.634Z,1741625435.634 [MissionManager](INFO): DefineArg multiray_test.LightMode2 = 2.000000 count 2025-03-10T16:50:35.687Z,1741625435.687 [MissionManager](INFO): DefineArg multiray_test.EnabledMultiray = 1 bool 2025-03-10T16:50:35.711Z,1741625435.711 [multiray_test:C.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.724Z,1741625435.724 [multiray_test:E.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.728Z,1741625435.728 [multiray_test:G.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.762Z,1741625435.762 [multiray_test:I.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.765Z,1741625435.765 [multiray_test:K.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.775Z,1741625435.775 [PowerOnly](INFO): Powering down loadControl 2025-03-10T16:50:35.808Z,1741625435.808 [multiray_test:M.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.811Z,1741625435.811 [multiray_test:O.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.814Z,1741625435.814 [multiray_test:Q.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.826Z,1741625435.826 [multiray_test:R.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.846Z,1741625435.846 [multiray_test:T.Wait](DEBUG): Construct Wait. 2025-03-10T16:50:35.851Z,1741625435.851 [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 multiray_test { """ This mission is designed to run in the lab only. Cycles through MultiRay light modes """ arguments { MissionTimeout = 5 minute """ How long to let the mission run. """ LightDuration = 5 second """ How long to wait before changing light modes """ LightMode0 = 0 count """ Light mode = 0 """ LightMode1 = 1 count """ Light mode = 1 """ LightMode2 = 2 count """ Light mode = 2 """ EnabledMultiray = Sensor:MultiRay.loadAtStartup """ Automatically set to true if the Multiray is enabled. """ } timeout duration=MissionTimeout readData strategy="MinError" { while ( EnabledMultiray ) Sensor:MultiRay.lightModeLog } # # # assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0 behavior Guidance:Wait { run in sequence set duration = LightDuration } } 2025-03-10T16:50:35.853Z,1741625435.853 [CommandExec](IMPORTANT): Running ./Missions/Engineering/multiray_test.tl 2025-03-10T16:50:36.179Z,1741625436.179 [Power24vConverter](INFO): Powering down. 2025-03-10T16:50:36.187Z,1741625436.187 [Default] Stopped 2025-03-10T16:50:36.187Z,1741625436.187 [Default](DEBUG): Aggregate::uninitialize Default 2025-03-10T16:50:36.188Z,1741625436.188 [Default:B.GoToSurface] Stopped 2025-03-10T16:50:36.188Z,1741625436.188 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-10T16:50:36.188Z,1741625436.188 [Default:CheckIn] Stopped 2025-03-10T16:50:36.188Z,1741625436.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T16:50:36.188Z,1741625436.188 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T16:50:36.188Z,1741625436.188 [MissionManager](IMPORTANT): Started mission multiray_test 2025-03-10T16:50:36.188Z,1741625436.188 [multiray_test] Running Loop=1 2025-03-10T16:50:36.188Z,1741625436.188 [multiray_test](DEBUG): Aggregate::initialize multiray_test 2025-03-10T16:50:36.188Z,1741625436.188 [multiray_test:A] Running Loop=1 2025-03-10T16:50:36.189Z,1741625436.189 [multiray_test:B] Running Loop=1 2025-03-10T16:50:36.189Z,1741625436.189 [multiray_test:B] Stopped 2025-03-10T16:50:36.189Z,1741625436.189 [multiray_test:C.Wait] Running Loop=1 2025-03-10T16:50:36.189Z,1741625436.189 [multiray_test:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:50:36.189Z,1741625436.189 [multiray_test:A] Running Loop=1 2025-03-10T16:50:36.190Z,1741625436.190 [multiray_test:A](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog 2025-03-10T16:50:37.792Z,1741625437.792 [MultiRay](IMPORTANT): MultiRay all lights OFF 2025-03-10T16:50:41.861Z,1741625441.861 [multiray_test:C.Wait](INFO): Done Waiting. 2025-03-10T16:50:41.861Z,1741625441.861 [multiray_test:C.Wait] Stopped 2025-03-10T16:50:41.861Z,1741625441.861 [multiray_test:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:50:41.862Z,1741625441.862 [multiray_test:D] Running Loop=1 2025-03-10T16:50:42.238Z,1741625442.238 [multiray_test:D] Stopped 2025-03-10T16:50:42.238Z,1741625442.238 [multiray_test:E.Wait] Running Loop=1 2025-03-10T16:50:42.238Z,1741625442.238 [multiray_test:E.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:50:44.652Z,1741625444.652 [MultiRay](IMPORTANT): Multiray red lights ON 2025-03-10T16:50:47.889Z,1741625447.889 [multiray_test:E.Wait](INFO): Done Waiting. 2025-03-10T16:50:47.889Z,1741625447.889 [multiray_test:E.Wait] Stopped 2025-03-10T16:50:47.889Z,1741625447.889 [multiray_test:E.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:50:47.890Z,1741625447.890 [multiray_test:F] Running Loop=1 2025-03-10T16:50:48.313Z,1741625448.313 [multiray_test:F] Stopped 2025-03-10T16:50:48.313Z,1741625448.313 [multiray_test:G.Wait] Running Loop=1 2025-03-10T16:50:48.313Z,1741625448.313 [multiray_test:G.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:50:49.904Z,1741625449.904 [MultiRay](IMPORTANT): MultiRay white lights ON 2025-03-10T16:50:51.577Z,1741625451.577 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2025-03-10T16:50:51.577Z,1741625451.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:50:51.587Z,1741625451.587 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:50:51.960Z,1741625451.960 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:50:51.960Z,1741625451.960 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2025-03-10T16:50:53.953Z,1741625453.953 [multiray_test:G.Wait](INFO): Done Waiting. 2025-03-10T16:50:53.953Z,1741625453.953 [multiray_test:G.Wait] Stopped 2025-03-10T16:50:53.953Z,1741625453.953 [multiray_test:G.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:50:53.953Z,1741625453.953 [multiray_test:H] Running Loop=1 2025-03-10T16:50:54.384Z,1741625454.384 [multiray_test:H] Stopped 2025-03-10T16:50:54.384Z,1741625454.384 [multiray_test:I.Wait] Running Loop=1 2025-03-10T16:50:54.384Z,1741625454.384 [multiray_test:I.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:50:55.158Z,1741625455.158 [MultiRay](IMPORTANT): MultiRay all lights OFF 2025-03-10T16:51:00.012Z,1741625460.012 [multiray_test:I.Wait](INFO): Done Waiting. 2025-03-10T16:51:00.013Z,1741625460.013 [multiray_test:I.Wait] Stopped 2025-03-10T16:51:00.013Z,1741625460.013 [multiray_test:I.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:51:00.013Z,1741625460.013 [multiray_test:J] Running Loop=1 2025-03-10T16:51:00.428Z,1741625460.428 [multiray_test:J] Stopped 2025-03-10T16:51:00.429Z,1741625460.429 [multiray_test:K.Wait] Running Loop=1 2025-03-10T16:51:00.429Z,1741625460.429 [multiray_test:K.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:51:02.832Z,1741625462.832 [MultiRay](IMPORTANT): Multiray red lights ON 2025-03-10T16:51:06.086Z,1741625466.086 [multiray_test:K.Wait](INFO): Done Waiting. 2025-03-10T16:51:06.086Z,1741625466.086 [multiray_test:K.Wait] Stopped 2025-03-10T16:51:06.086Z,1741625466.086 [multiray_test:K.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:51:06.087Z,1741625466.087 [multiray_test:L] Running Loop=1 2025-03-10T16:51:06.493Z,1741625466.493 [multiray_test:L] Stopped 2025-03-10T16:51:06.493Z,1741625466.493 [multiray_test:M.Wait] Running Loop=1 2025-03-10T16:51:06.493Z,1741625466.493 [multiray_test:M.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:51:08.080Z,1741625468.080 [MultiRay](IMPORTANT): MultiRay white lights ON 2025-03-10T16:51:09.293Z,1741625469.293 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T16:51:09.293Z,1741625469.293 [NAL9602] Data Fault, FailCount= 2 2025-03-10T16:51:09.293Z,1741625469.293 [NAL9602](ERROR): Data Fault 2025-03-10T16:51:09.365Z,1741625469.365 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T16:51:09.696Z,1741625469.696 [NAL9602](INFO): Powering down 2025-03-10T16:51:10.552Z,1741625470.552 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T16:51:10.552Z,1741625470.552 [NAL9602] No Fault, FailCount= 2 2025-03-10T16:51:12.140Z,1741625472.140 [multiray_test:M.Wait](INFO): Done Waiting. 2025-03-10T16:51:12.140Z,1741625472.140 [multiray_test:M.Wait] Stopped 2025-03-10T16:51:12.140Z,1741625472.140 [multiray_test:M.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:51:12.140Z,1741625472.140 [multiray_test:N] Running Loop=1 2025-03-10T16:51:12.531Z,1741625472.531 [multiray_test:N] Stopped 2025-03-10T16:51:12.531Z,1741625472.531 [multiray_test:O.Wait] Running Loop=1 2025-03-10T16:51:12.531Z,1741625472.531 [multiray_test:O.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:51:13.338Z,1741625473.338 [MultiRay](IMPORTANT): MultiRay all lights OFF 2025-03-10T16:51:18.188Z,1741625478.188 [multiray_test:O.Wait](INFO): Done Waiting. 2025-03-10T16:51:18.188Z,1741625478.188 [multiray_test:O.Wait] Stopped 2025-03-10T16:51:18.188Z,1741625478.188 [multiray_test:O.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:51:18.188Z,1741625478.188 [multiray_test:P] Running Loop=1 2025-03-10T16:51:18.597Z,1741625478.597 [multiray_test:P] Stopped 2025-03-10T16:51:18.597Z,1741625478.597 [multiray_test:Q.Wait] Running Loop=1 2025-03-10T16:51:18.597Z,1741625478.597 [multiray_test:Q.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:51:21.012Z,1741625481.012 [MultiRay](IMPORTANT): Multiray red lights ON 2025-03-10T16:51:24.252Z,1741625484.252 [multiray_test:Q.Wait](INFO): Done Waiting. 2025-03-10T16:51:24.253Z,1741625484.253 [multiray_test:Q.Wait] Stopped 2025-03-10T16:51:24.253Z,1741625484.253 [multiray_test:Q.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:51:24.253Z,1741625484.253 [multiray_test:U] Running Loop=1 2025-03-10T16:51:24.650Z,1741625484.650 [multiray_test:U] Stopped 2025-03-10T16:51:24.650Z,1741625484.650 [multiray_test:R.Wait] Running Loop=1 2025-03-10T16:51:24.651Z,1741625484.651 [multiray_test:R.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:51:26.260Z,1741625486.260 [MultiRay](IMPORTANT): MultiRay white lights ON 2025-03-10T16:51:30.309Z,1741625490.309 [multiray_test:R.Wait](INFO): Done Waiting. 2025-03-10T16:51:30.309Z,1741625490.309 [multiray_test:R.Wait] Stopped 2025-03-10T16:51:30.309Z,1741625490.309 [multiray_test:R.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:51:30.309Z,1741625490.309 [multiray_test:S] Running Loop=1 2025-03-10T16:51:30.711Z,1741625490.711 [multiray_test:S] Stopped 2025-03-10T16:51:30.711Z,1741625490.711 [multiray_test:T.Wait] Running Loop=1 2025-03-10T16:51:30.711Z,1741625490.711 [multiray_test:T.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:51:31.512Z,1741625491.512 [MultiRay](IMPORTANT): MultiRay all lights OFF 2025-03-10T16:51:36.372Z,1741625496.372 [multiray_test:T.Wait](INFO): Done Waiting. 2025-03-10T16:51:36.372Z,1741625496.372 [multiray_test:T.Wait] Stopped 2025-03-10T16:51:36.372Z,1741625496.372 [multiray_test:T.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:51:36.372Z,1741625496.372 [multiray_test](INFO): Completed multiray_test 2025-03-10T16:51:36.372Z,1741625496.372 [MissionManager](INFO): multiray_test is completed. 2025-03-10T16:51:36.372Z,1741625496.372 [MissionManager](INFO): Uninitializing Mission multiray_test 2025-03-10T16:51:36.372Z,1741625496.372 [multiray_test] Stopped 2025-03-10T16:51:36.373Z,1741625496.373 [multiray_test](DEBUG): Aggregate::uninitialize multiray_test 2025-03-10T16:51:36.373Z,1741625496.373 [multiray_test:A] Stopped 2025-03-10T16:51:36.773Z,1741625496.773 [MissionManager](IMPORTANT): Started mission Default 2025-03-10T16:51:36.774Z,1741625496.774 [Default] Running Loop=1 2025-03-10T16:51:36.774Z,1741625496.774 [Default](DEBUG): Aggregate::initialize Default 2025-03-10T16:51:36.774Z,1741625496.774 [Default:B.GoToSurface] Running Loop=1 2025-03-10T16:51:36.774Z,1741625496.774 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-10T16:51:36.774Z,1741625496.774 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-10T16:51:36.774Z,1741625496.774 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-10T16:51:36.775Z,1741625496.775 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-10T16:51:36.775Z,1741625496.775 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-10T16:51:36.775Z,1741625496.775 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-10T16:51:36.776Z,1741625496.776 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-10T16:51:36.776Z,1741625496.776 [Default:A.Wait] Running Loop=1 2025-03-10T16:51:36.776Z,1741625496.776 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:51:40.000Z,1741625500.000 [NAL9602](INFO): Powering up NAL9602 2025-03-10T16:51:50.109Z,1741625510.109 [Default:A.Wait](INFO): Done Waiting. 2025-03-10T16:51:50.109Z,1741625510.109 [Default:A.Wait] Stopped 2025-03-10T16:51:50.109Z,1741625510.109 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T16:51:50.530Z,1741625510.530 [Default:CheckIn] Running Loop=1 2025-03-10T16:51:50.530Z,1741625510.530 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T16:51:50.530Z,1741625510.530 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T16:51:50.909Z,1741625510.909 [NAL9602](INFO): NAL9602 initialized 2025-03-10T16:53:52.515Z,1741625632.515 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2025-03-10T16:53:52.515Z,1741625632.515 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:53:52.526Z,1741625632.526 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:53:52.940Z,1741625632.940 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:53:52.940Z,1741625632.940 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2025-03-10T16:56:50.680Z,1741625810.680 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T16:51:50.5Z 2025-03-10T16:56:50.680Z,1741625810.680 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T16:56:50.680Z,1741625810.680 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T16:56:51.090Z,1741625811.090 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-10T16:56:53.512Z,1741625813.512 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2025-03-10T16:56:53.512Z,1741625813.512 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:56:53.542Z,1741625813.542 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:56:53.933Z,1741625813.933 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:56:53.933Z,1741625813.933 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2025-03-10T16:56:57.943Z,1741625817.943 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250310T162934/Courier0004.lzma 2025-03-10T16:56:58.945Z,1741625818.945 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0004.lzma.bak 2025-03-10T16:56:58.945Z,1741625818.945 [DataOverHttps](INFO): SBD MOMSN=24149228 2025-03-10T16:57:14.309Z,1741625834.309 [DataOverHttps](INFO): Sending 914 bytes from file Logs/20250310T161547/Express0005.lzma 2025-03-10T16:57:15.309Z,1741625835.309 [DataOverHttps](INFO): Moved sent file to Logs/20250310T161547/Express0005.lzma.bak 2025-03-10T16:57:15.309Z,1741625835.309 [DataOverHttps](INFO): SBD MOMSN=24149230 2025-03-10T16:57:30.986Z,1741625850.986 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20250310T161547/Express0008.lzma 2025-03-10T16:57:31.989Z,1741625851.989 [DataOverHttps](INFO): Moved sent file to Logs/20250310T161547/Express0008.lzma.bak 2025-03-10T16:57:31.989Z,1741625851.989 [DataOverHttps](INFO): SBD MOMSN=24149259 2025-03-10T16:57:47.365Z,1741625867.365 [DataOverHttps](INFO): Sending 977 bytes from file Logs/20250310T162934/Express0001.lzma 2025-03-10T16:57:48.365Z,1741625868.365 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0001.lzma.bak 2025-03-10T16:57:48.365Z,1741625868.365 [DataOverHttps](INFO): SBD MOMSN=24149262 2025-03-10T16:58:03.781Z,1741625883.781 [DataOverHttps](INFO): Sending 760 bytes from file Logs/20250310T162934/Express0005.lzma 2025-03-10T16:58:04.782Z,1741625884.782 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0005.lzma.bak 2025-03-10T16:58:04.783Z,1741625884.783 [DataOverHttps](INFO): SBD MOMSN=24149290 2025-03-10T16:58:06.232Z,1741625886.232 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T16:58:06.233Z,1741625886.233 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T16:58:06.233Z,1741625886.233 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T16:59:54.524Z,1741625994.524 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2025-03-10T16:59:54.524Z,1741625994.524 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T16:59:54.534Z,1741625994.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T16:59:54.946Z,1741625994.946 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T16:59:54.946Z,1741625994.946 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2025-03-10T17:00:03.636Z,1741626003.636 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/lab_test_optim.tl 2025-03-10T17:00:03.636Z,1741626003.636 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/lab_test_optim.tl 2025-03-10T17:00:03.636Z,1741626003.636 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/lab_test_optim.tl 2025-03-10T17:00:03.637Z,1741626003.637 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/lab_test_optim.tl 2025-03-10T17:00:03.938Z,1741626003.938 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/lab_test_optim.tl translated into: 15 1 30 2 100 100 Waiting for backseat to start up... Trying to run Optim and Multiray forreal 1 Setting multiray red brightness to 2 Setting muliray white brightness to Recording for 2025-03-10T17:00:03.943Z,1741626003.943 [MissionManager](INFO): DefineArg lab_test_optim.MissionTimeout = 15.000000 min 2025-03-10T17:00:03.945Z,1741626003.945 [MissionManager](INFO): DefineArg lab_test_optim.RecordTime = 1.000000 min 2025-03-10T17:00:03.947Z,1741626003.947 [MissionManager](INFO): DefineArg lab_test_optim.BackseatWait = 30.000000 s 2025-03-10T17:00:03.949Z,1741626003.949 [MissionManager](INFO): DefineArg lab_test_optim.LightMode = 2.000000 count 2025-03-10T17:00:03.952Z,1741626003.952 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessWhite = 100.000000 n/a int32 2025-03-10T17:00:03.954Z,1741626003.954 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessRed = 100.000000 n/a int32 2025-03-10T17:00:03.956Z,1741626003.956 [MissionManager](INFO): DefineOutput lab_test_optim.RecordingEnabled = 0 bool 2025-03-10T17:00:03.958Z,1741626003.958 [MissionManager](INFO): DefineOutput lab_test_optim.LightsEnabled = 0 bool 2025-03-10T17:00:03.959Z,1741626003.959 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-03-10T17:00:03.959Z,1741626003.959 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-03-10T17:00:03.960Z,1741626003.960 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-03-10T17:00:04.029Z,1741626004.029 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-03-10T17:00:04.032Z,1741626004.032 [MissionManager](INFO): DefineArg lab_test_optim:BackseatDriver.EnableBackseat = 0 bool 2025-03-10T17:00:04.034Z,1741626004.034 [lab_test_optim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-03-10T17:00:04.057Z,1741626004.057 [lab_test_optim:Startup:B.Wait](DEBUG): Construct Wait. 2025-03-10T17:00:04.074Z,1741626004.074 [lab_test_optim:Run:H.Wait](DEBUG): Construct Wait. 2025-03-10T17:00:04.076Z,1741626004.076 [lab_test_optim:G.Wait](DEBUG): Construct Wait. 2025-03-10T17:00:04.081Z,1741626004.081 [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 lab_test_optim { arguments { MissionTimeout = 15 minute """ Backseat will stay on for this duration, independent of Optim state """ RecordTime = 1 minute """ How long to record after startup sequence (hopefully) turns on Optim camera """ BackseatWait = 30 second """ How long to wait for the backseat to power up before attempting to power the Optim """ LightMode = 2 count """ Mode for Multiray lights. 0 is off, 1 is red, 2 is white """ BrightnessWhite = 100 none_int """ White Multiray LED brightness, 0-100 """ BrightnessRed = 100 none_int """ Red Multiray LED brightness, 0-100 """ } output { RecordingEnabled = false LightsEnabled = false } timeout duration=MissionTimeout insert Insert/BackseatDriver.tl assign in parallel BackseatDriver:EnableBackseat = true readData strategy="MinError" { while ( RecordingEnabled ) Sensor:PowerOnly.sampleLoad1 } readData strategy="MinError" { while ( LightsEnabled ) Sensor:MultiRay.lightModeLog } aggregate Startup { run in sequence syslog important "Waiting " + BackseatWait~s + " for backseat to start up..." behavior Guidance:Wait { run in sequence set duration = BackseatWait } } aggregate Run { run in sequence syslog important "Trying to run Optim and Multiray forreal" assign in sequence RecordingEnabled = true assign in sequence LightsEnabled = true assign in sequence Sensor:MultiRay.lightModeCommand = LightMode aggregate setRed { run in sequence break if ( LightMode != 1 count ) assign in sequence Sensor:MultiRay.brightnessRed = BrightnessRed syslog info "Setting multiray red brightness to" + BrightnessRed~count } aggregate setWhite { run in sequence break if ( LightMode != 2 count ) assign in sequence Sensor:MultiRay.brightnessWhite = BrightnessWhite syslog info "Setting muliray white brightness to" + BrightnessWhite~count } syslog important "Recording for " + RecordTime~min behavior Guidance:Wait { run in sequence set duration = RecordTime } } behavior Guidance:Wait { run in sequence set duration = MissionTimeout } } 2025-03-10T17:00:04.083Z,1741626004.083 [CommandExec](IMPORTANT): Running ./Missions/Engineering/lab_test_optim.tl 2025-03-10T17:00:04.210Z,1741626004.210 [Default] Stopped 2025-03-10T17:00:04.210Z,1741626004.210 [Default](DEBUG): Aggregate::uninitialize Default 2025-03-10T17:00:04.210Z,1741626004.210 [Default:B.GoToSurface] Stopped 2025-03-10T17:00:04.210Z,1741626004.210 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-10T17:00:04.211Z,1741626004.211 [Default:CheckIn] Stopped 2025-03-10T17:00:04.211Z,1741626004.211 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T17:00:04.211Z,1741626004.211 [Default:CheckIn:C.Wait] Stopped 2025-03-10T17:00:04.211Z,1741626004.211 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:00:04.211Z,1741626004.211 [MissionManager](IMPORTANT): Started mission lab_test_optim 2025-03-10T17:00:04.211Z,1741626004.211 [lab_test_optim] Running Loop=1 2025-03-10T17:00:04.211Z,1741626004.211 [lab_test_optim](DEBUG): Aggregate::initialize lab_test_optim 2025-03-10T17:00:04.211Z,1741626004.211 [lab_test_optim:BackseatDriver] Running Loop=1 2025-03-10T17:00:04.212Z,1741626004.212 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::initialize lab_test_optim:BackseatDriver 2025-03-10T17:00:04.212Z,1741626004.212 [lab_test_optim:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-03-10T17:00:04.212Z,1741626004.212 [lab_test_optim:B] Running Loop=1 2025-03-10T17:00:04.212Z,1741626004.212 [lab_test_optim:C] Running Loop=1 2025-03-10T17:00:04.212Z,1741626004.212 [lab_test_optim:D] Running Loop=1 2025-03-10T17:00:04.212Z,1741626004.212 [lab_test_optim:Startup] Running Loop=1 2025-03-10T17:00:04.212Z,1741626004.212 [lab_test_optim:Startup](DEBUG): Aggregate::initialize lab_test_optim:Startup 2025-03-10T17:00:04.212Z,1741626004.212 [lab_test_optim:Startup:A] Running Loop=1 2025-03-10T17:00:04.213Z,1741626004.213 [lab_test_optim:Startup:A](IMPORTANT): Waiting 30.000000 s for backseat to start up... 2025-03-10T17:00:04.213Z,1741626004.213 [lab_test_optim:Startup:A] Stopped 2025-03-10T17:00:04.213Z,1741626004.213 [lab_test_optim:Startup:B.Wait] Running Loop=1 2025-03-10T17:00:04.213Z,1741626004.213 [lab_test_optim:Startup:B.Wait](DEBUG): Initialize Wait Component. 2025-03-10T17:00:04.213Z,1741626004.213 [lab_test_optim:D] Stopped 2025-03-10T17:00:04.214Z,1741626004.214 [lab_test_optim:C] Stopped 2025-03-10T17:00:04.214Z,1741626004.214 [lab_test_optim:B] Running Loop=1 2025-03-10T17:00:04.214Z,1741626004.214 [lab_test_optim:BackseatDriver] Running Loop=1 2025-03-10T17:00:04.214Z,1741626004.214 [lab_test_optim:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat 2025-03-10T17:00:04.391Z,1741626004.391 [BackseatComponent](INFO): Powering up 2025-03-10T17:00:04.392Z,1741626004.392 [BackseatComponent](INFO): Subscribing to LCM channels. 2025-03-10T17:00:04.611Z,1741626004.611 [Power24vConverter](INFO): Powering up. 2025-03-10T17:00:34.925Z,1741626034.925 [lab_test_optim:Startup:B.Wait](INFO): Done Waiting. 2025-03-10T17:00:34.925Z,1741626034.925 [lab_test_optim:Startup:B.Wait] Stopped 2025-03-10T17:00:34.925Z,1741626034.925 [lab_test_optim:Startup:B.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:00:34.926Z,1741626034.926 [lab_test_optim:Startup](INFO): Completed lab_test_optim:Startup 2025-03-10T17:00:34.926Z,1741626034.926 [lab_test_optim:Startup] Stopped 2025-03-10T17:00:34.926Z,1741626034.926 [lab_test_optim:Startup](DEBUG): Aggregate::uninitialize lab_test_optim:Startup 2025-03-10T17:00:34.926Z,1741626034.926 [lab_test_optim:Run] Running Loop=1 2025-03-10T17:00:34.926Z,1741626034.926 [lab_test_optim:Run](DEBUG): Aggregate::initialize lab_test_optim:Run 2025-03-10T17:00:34.926Z,1741626034.926 [lab_test_optim:Run:A] Running Loop=1 2025-03-10T17:00:35.323Z,1741626035.323 [lab_test_optim:Run:A](IMPORTANT): Trying to run Optim and Multiray forreal 2025-03-10T17:00:35.323Z,1741626035.323 [lab_test_optim:Run:A] Stopped 2025-03-10T17:00:35.323Z,1741626035.323 [lab_test_optim:Run:B] Running Loop=1 2025-03-10T17:00:35.738Z,1741626035.738 [lab_test_optim:Run:B] Stopped 2025-03-10T17:00:35.738Z,1741626035.738 [lab_test_optim:Run:C] Running Loop=1 2025-03-10T17:00:36.117Z,1741626036.117 [lab_test_optim:Run:C] Stopped 2025-03-10T17:00:36.117Z,1741626036.117 [lab_test_optim:Run:D] Running Loop=1 2025-03-10T17:00:36.117Z,1741626036.117 [lab_test_optim:C] Running Loop=1 2025-03-10T17:00:36.117Z,1741626036.117 [lab_test_optim:C] Running Loop=1 2025-03-10T17:00:36.118Z,1741626036.118 [lab_test_optim:C](DEBUG): Initialize ReadDataComponent to sense PowerOnly.sampleLoad1 2025-03-10T17:00:36.514Z,1741626036.514 [PowerOnly](INFO): Powering up loadControl 2025-03-10T17:00:36.545Z,1741626036.545 [lab_test_optim:Run:D] Stopped 2025-03-10T17:00:36.545Z,1741626036.545 [lab_test_optim:Run:setRed] Running Loop=1 2025-03-10T17:00:36.546Z,1741626036.546 [lab_test_optim:Run:setRed](DEBUG): Aggregate::initialize lab_test_optim:Run:setRed 2025-03-10T17:00:36.546Z,1741626036.546 [lab_test_optim:Run:setRed:A] Running Loop=1 2025-03-10T17:00:36.546Z,1741626036.546 [lab_test_optim:D] Running Loop=1 2025-03-10T17:00:36.546Z,1741626036.546 [lab_test_optim:D] Running Loop=1 2025-03-10T17:00:36.546Z,1741626036.546 [lab_test_optim:D](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog 2025-03-10T17:00:36.919Z,1741626036.919 [lab_test_optim:Run:setRed] Stopped 2025-03-10T17:00:36.919Z,1741626036.919 [lab_test_optim:Run:setRed](DEBUG): Aggregate::uninitialize lab_test_optim:Run:setRed 2025-03-10T17:00:36.919Z,1741626036.919 [lab_test_optim:Run:setRed:A] Stopped 2025-03-10T17:00:36.919Z,1741626036.919 [lab_test_optim:Run:setWhite] Running Loop=1 2025-03-10T17:00:36.919Z,1741626036.919 [lab_test_optim:Run:setWhite](DEBUG): Aggregate::initialize lab_test_optim:Run:setWhite 2025-03-10T17:00:36.919Z,1741626036.919 [lab_test_optim:Run:setWhite:A] Running Loop=1 2025-03-10T17:00:37.352Z,1741626037.352 [lab_test_optim:Run:setWhite:A] Stopped 2025-03-10T17:00:37.353Z,1741626037.353 [lab_test_optim:Run:setWhite:B] Running Loop=1 2025-03-10T17:00:37.770Z,1741626037.770 [lab_test_optim:Run:setWhite:B](INFO): Setting muliray white brightness to 100.000000 count 2025-03-10T17:00:37.770Z,1741626037.770 [lab_test_optim:Run:setWhite:B] Stopped 2025-03-10T17:00:37.770Z,1741626037.770 [lab_test_optim:Run:setWhite](INFO): Completed lab_test_optim:Run:setWhite 2025-03-10T17:00:37.771Z,1741626037.771 [lab_test_optim:Run:setWhite] Stopped 2025-03-10T17:00:37.771Z,1741626037.771 [lab_test_optim:Run:setWhite](DEBUG): Aggregate::uninitialize lab_test_optim:Run:setWhite 2025-03-10T17:00:37.771Z,1741626037.771 [lab_test_optim:Run:G] Running Loop=1 2025-03-10T17:00:38.162Z,1741626038.162 [lab_test_optim:Run:G](IMPORTANT): Recording for 1.000000 min 2025-03-10T17:00:38.162Z,1741626038.162 [lab_test_optim:Run:G] Stopped 2025-03-10T17:00:38.162Z,1741626038.162 [lab_test_optim:Run:H.Wait] Running Loop=1 2025-03-10T17:00:38.162Z,1741626038.162 [lab_test_optim:Run:H.Wait](DEBUG): Initialize Wait Component. 2025-03-10T17:00:38.933Z,1741626038.933 [MultiRay](IMPORTANT): MultiRay white lights ON 2025-03-10T17:01:04.061Z,1741626064.061 [BackseatComponent](IMPORTANT): ikshana: running backseat application. 2025-03-10T17:01:38.780Z,1741626098.780 [lab_test_optim:Run:H.Wait](INFO): Done Waiting. 2025-03-10T17:01:38.780Z,1741626098.780 [lab_test_optim:Run:H.Wait] Stopped 2025-03-10T17:01:38.780Z,1741626098.780 [lab_test_optim:Run:H.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:01:38.780Z,1741626098.780 [lab_test_optim:Run](INFO): Completed lab_test_optim:Run 2025-03-10T17:01:38.780Z,1741626098.780 [lab_test_optim:Run] Stopped 2025-03-10T17:01:38.780Z,1741626098.780 [lab_test_optim:Run](DEBUG): Aggregate::uninitialize lab_test_optim:Run 2025-03-10T17:01:38.780Z,1741626098.780 [lab_test_optim:G.Wait] Running Loop=1 2025-03-10T17:01:38.780Z,1741626098.780 [lab_test_optim:G.Wait](DEBUG): Initialize Wait Component. 2025-03-10T17:01:52.901Z,1741626112.901 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T17:01:52.901Z,1741626112.901 [NAL9602] Data Fault, FailCount= 3 2025-03-10T17:01:52.901Z,1741626112.901 [NAL9602](ERROR): Data Fault 2025-03-10T17:01:52.947Z,1741626112.947 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T17:01:53.301Z,1741626113.301 [NAL9602](INFO): Powering down 2025-03-10T17:01:54.146Z,1741626114.146 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T17:01:54.146Z,1741626114.146 [NAL9602] No Fault, FailCount= 3 2025-03-10T17:02:23.601Z,1741626143.601 [NAL9602](INFO): Powering up NAL9602 2025-03-10T17:02:34.513Z,1741626154.513 [NAL9602](INFO): NAL9602 initialized 2025-03-10T17:02:55.542Z,1741626175.542 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2025-03-10T17:02:55.542Z,1741626175.542 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:02:55.555Z,1741626175.555 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:02:55.939Z,1741626175.939 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:02:55.939Z,1741626175.939 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2025-03-10T17:04:10.832Z,1741626250.832 [CommandExec](IMPORTANT): got command stop 2025-03-10T17:04:10.832Z,1741626250.832 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-03-10T17:04:11.079Z,1741626251.079 [MissionManager](INFO): MissionManager is completed. 2025-03-10T17:04:11.079Z,1741626251.079 [MissionManager](INFO): Uninitializing Mission lab_test_optim 2025-03-10T17:04:11.079Z,1741626251.079 [lab_test_optim] Stopped 2025-03-10T17:04:11.079Z,1741626251.079 [lab_test_optim](DEBUG): Aggregate::uninitialize lab_test_optim 2025-03-10T17:04:11.079Z,1741626251.079 [lab_test_optim:BackseatDriver] Stopped 2025-03-10T17:04:11.079Z,1741626251.079 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::uninitialize lab_test_optim:BackseatDriver 2025-03-10T17:04:11.079Z,1741626251.079 [lab_test_optim:BackseatDriver:A.BackseatDriver] Stopped 2025-03-10T17:04:11.080Z,1741626251.080 [lab_test_optim:B] Stopped 2025-03-10T17:04:11.080Z,1741626251.080 [lab_test_optim:C] Stopped 2025-03-10T17:04:11.080Z,1741626251.080 [lab_test_optim:D] Stopped 2025-03-10T17:04:11.080Z,1741626251.080 [lab_test_optim:G.Wait] Stopped 2025-03-10T17:04:11.080Z,1741626251.080 [lab_test_optim:G.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:04:11.481Z,1741626251.481 [MissionManager](IMPORTANT): Started mission Default 2025-03-10T17:04:11.481Z,1741626251.481 [Default] Running Loop=1 2025-03-10T17:04:11.481Z,1741626251.481 [Default](DEBUG): Aggregate::initialize Default 2025-03-10T17:04:11.481Z,1741626251.481 [Default:B.GoToSurface] Running Loop=1 2025-03-10T17:04:11.481Z,1741626251.481 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-10T17:04:11.481Z,1741626251.481 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-10T17:04:11.482Z,1741626251.482 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-10T17:04:11.482Z,1741626251.482 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-10T17:04:11.482Z,1741626251.482 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-10T17:04:11.483Z,1741626251.483 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-10T17:04:11.483Z,1741626251.483 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-10T17:04:11.484Z,1741626251.484 [Default:A.Wait] Running Loop=1 2025-03-10T17:04:11.484Z,1741626251.484 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-10T17:04:12.816Z,1741626252.816 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-03-10T17:04:12.816Z,1741626252.816 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2025-03-10T17:04:24.834Z,1741626264.834 [Default:A.Wait](INFO): Done Waiting. 2025-03-10T17:04:24.834Z,1741626264.834 [Default:A.Wait] Stopped 2025-03-10T17:04:24.834Z,1741626264.834 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:04:25.232Z,1741626265.232 [Default:CheckIn] Running Loop=1 2025-03-10T17:04:25.232Z,1741626265.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T17:04:25.233Z,1741626265.233 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T17:04:30.979Z,1741626270.979 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-03-10T17:04:30.980Z,1741626270.980 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-03-10T17:04:30.980Z,1741626270.980 [LcmUniversalReporter](INFO): Deactivating messaging. 2025-03-10T17:05:41.579Z,1741626341.579 [PowerOnly](INFO): Powering down loadControl 2025-03-10T17:05:56.533Z,1741626356.533 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2025-03-10T17:05:56.533Z,1741626356.533 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:05:56.544Z,1741626356.544 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:05:56.954Z,1741626356.954 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:05:56.954Z,1741626356.954 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2025-03-10T17:08:57.528Z,1741626537.528 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2025-03-10T17:08:57.528Z,1741626537.528 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:08:57.555Z,1741626537.555 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:08:57.945Z,1741626537.945 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:08:57.945Z,1741626537.945 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2025-03-10T17:09:25.397Z,1741626565.397 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T17:04:25.2Z 2025-03-10T17:09:25.397Z,1741626565.397 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T17:09:25.397Z,1741626565.397 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T17:09:32.858Z,1741626572.858 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T162934/Courier0007.lzma 2025-03-10T17:09:33.861Z,1741626573.861 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0007.lzma.bak 2025-03-10T17:09:33.861Z,1741626573.861 [DataOverHttps](INFO): SBD MOMSN=24149354 2025-03-10T17:09:49.317Z,1741626589.317 [DataOverHttps](INFO): Sending 374 bytes from file Logs/20250310T162934/Express0008.lzma 2025-03-10T17:09:50.317Z,1741626590.317 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0008.lzma.bak 2025-03-10T17:09:50.317Z,1741626590.317 [DataOverHttps](INFO): SBD MOMSN=24149359 2025-03-10T17:09:51.661Z,1741626591.661 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T17:09:51.661Z,1741626591.661 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T17:09:51.661Z,1741626591.661 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T17:11:58.525Z,1741626718.525 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2025-03-10T17:11:58.525Z,1741626718.525 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:11:58.535Z,1741626718.535 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:11:58.940Z,1741626718.940 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:11:58.940Z,1741626718.940 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2025-03-10T17:12:37.685Z,1741626757.685 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T17:12:37.685Z,1741626757.685 [NAL9602] Data Fault, FailCount= 4 2025-03-10T17:12:37.685Z,1741626757.685 [NAL9602](ERROR): Data Fault 2025-03-10T17:12:37.702Z,1741626757.702 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T17:12:38.090Z,1741626758.090 [NAL9602](INFO): Powering down 2025-03-10T17:12:38.913Z,1741626758.913 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T17:12:38.913Z,1741626758.913 [NAL9602] No Fault, FailCount= 4 2025-03-10T17:13:08.388Z,1741626788.388 [NAL9602](INFO): Powering up NAL9602 2025-03-10T17:13:19.293Z,1741626799.293 [NAL9602](INFO): NAL9602 initialized 2025-03-10T17:14:48.176Z,1741626888.176 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-10T17:14:48.176Z,1741626888.176 [DropWeight] Hardware Fault, FailCount= 1 2025-03-10T17:14:48.176Z,1741626888.176 [DropWeight](ERROR): Hardware Fault 2025-03-10T17:14:48.222Z,1741626888.222 [CommandExec](FAULT): Scheduling is paused 2025-03-10T17:14:48.223Z,1741626888.223 [CBIT](INFO): Critical error at 20250310T171448 2025-03-10T17:14:48.225Z,1741626888.225 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-10T17:14:48.226Z,1741626888.226 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-10T17:14:48.618Z,1741626888.618 [CBIT](INFO): Critical error at 20250310T171448 2025-03-10T17:14:52.241Z,1741626892.241 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T17:14:52.241Z,1741626892.241 [Default:CheckIn:C.Wait] Stopped 2025-03-10T17:14:52.241Z,1741626892.241 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:14:52.242Z,1741626892.242 [Default:CheckIn:D] Running Loop=1 2025-03-10T17:14:52.642Z,1741626892.642 [Default:CheckIn:D] Stopped 2025-03-10T17:14:52.642Z,1741626892.642 [Default:CheckIn:E] Running Loop=1 2025-03-10T17:14:53.045Z,1741626893.045 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.686020 min 2025-03-10T17:14:53.045Z,1741626893.045 [Default:CheckIn:E] Stopped 2025-03-10T17:14:53.046Z,1741626893.046 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T17:14:53.046Z,1741626893.046 [Default:CheckIn] Stopped 2025-03-10T17:14:53.046Z,1741626893.046 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T17:14:53.046Z,1741626893.046 [Default:CheckIn](INFO): Running loop #2 2025-03-10T17:14:53.046Z,1741626893.046 [Default:CheckIn] Running Loop=2 2025-03-10T17:14:53.046Z,1741626893.046 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T17:14:53.046Z,1741626893.046 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T17:14:59.502Z,1741626899.502 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2025-03-10T17:14:59.502Z,1741626899.502 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:14:59.513Z,1741626899.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:14:59.932Z,1741626899.932 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:14:59.932Z,1741626899.932 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2025-03-10T17:15:42.313Z,1741626942.313 [Power24vConverter](INFO): Powering down. 2025-03-10T17:18:00.484Z,1741627080.484 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2025-03-10T17:18:00.484Z,1741627080.484 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:18:00.495Z,1741627080.495 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:18:00.904Z,1741627080.904 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:18:00.904Z,1741627080.904 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2025-03-10T17:19:53.225Z,1741627193.225 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T17:14:53.0Z 2025-03-10T17:19:53.225Z,1741627193.225 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T17:19:53.225Z,1741627193.225 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T17:19:58.215Z,1741627198.215 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20250310T162934/Courier0010.lzma 2025-03-10T17:19:59.217Z,1741627199.217 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0010.lzma.bak 2025-03-10T17:19:59.217Z,1741627199.217 [DataOverHttps](INFO): SBD MOMSN=24149416 2025-03-10T17:20:14.663Z,1741627214.663 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20250310T162934/Express0011.lzma 2025-03-10T17:20:15.665Z,1741627215.665 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0011.lzma.bak 2025-03-10T17:20:15.665Z,1741627215.665 [DataOverHttps](INFO): SBD MOMSN=24149420 2025-03-10T17:20:17.065Z,1741627217.065 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T17:20:17.065Z,1741627217.065 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T17:20:17.065Z,1741627217.065 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T17:21:01.492Z,1741627261.492 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2025-03-10T17:21:01.492Z,1741627261.492 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:21:01.503Z,1741627261.503 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:21:01.917Z,1741627261.917 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:21:01.917Z,1741627261.917 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2025-03-10T17:23:20.458Z,1741627400.458 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T17:23:20.459Z,1741627400.459 [NAL9602] Data Fault, FailCount= 5 2025-03-10T17:23:20.459Z,1741627400.459 [NAL9602](ERROR): Data Fault 2025-03-10T17:23:20.476Z,1741627400.476 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T17:23:20.476Z,1741627400.476 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-03-10T17:23:20.858Z,1741627400.858 [NAL9602](INFO): Powering down 2025-03-10T17:23:20.875Z,1741627400.875 [CBIT](INFO): Critical error at 20250310T172320 2025-03-10T17:24:02.483Z,1741627442.483 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2025-03-10T17:24:02.483Z,1741627442.483 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:24:02.493Z,1741627442.493 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:24:02.892Z,1741627442.892 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:24:02.892Z,1741627442.892 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2025-03-10T17:25:17.627Z,1741627517.627 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T17:25:17.627Z,1741627517.627 [Default:CheckIn:C.Wait] Stopped 2025-03-10T17:25:17.627Z,1741627517.627 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:25:17.628Z,1741627517.628 [Default:CheckIn:D] Running Loop=1 2025-03-10T17:25:18.026Z,1741627518.026 [Default:CheckIn:D] Stopped 2025-03-10T17:25:18.026Z,1741627518.026 [Default:CheckIn:E] Running Loop=1 2025-03-10T17:25:18.441Z,1741627518.441 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.109080 min 2025-03-10T17:25:18.441Z,1741627518.441 [Default:CheckIn:E] Stopped 2025-03-10T17:25:18.441Z,1741627518.441 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T17:25:18.441Z,1741627518.441 [Default:CheckIn] Stopped 2025-03-10T17:25:18.441Z,1741627518.441 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T17:25:18.442Z,1741627518.442 [Default:CheckIn](INFO): Running loop #3 2025-03-10T17:25:18.442Z,1741627518.442 [Default:CheckIn] Running Loop=3 2025-03-10T17:25:18.442Z,1741627518.442 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T17:25:18.442Z,1741627518.442 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T17:25:20.474Z,1741627520.474 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T17:25:20.474Z,1741627520.474 [NAL9602] No Fault, FailCount= 5 2025-03-10T17:25:20.846Z,1741627520.846 [NAL9602](INFO): Powering up NAL9602 2025-03-10T17:25:31.757Z,1741627531.757 [NAL9602](INFO): NAL9602 initialized 2025-03-10T17:27:03.489Z,1741627623.489 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2025-03-10T17:27:03.489Z,1741627623.489 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:27:03.529Z,1741627623.529 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:27:03.890Z,1741627623.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:27:03.890Z,1741627623.890 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2025-03-10T17:29:48.329Z,1741627788.329 [CBIT](INFO): Clearing failed state for component DropWeight 2025-03-10T17:29:48.329Z,1741627788.329 [DropWeight] No Fault, FailCount= 1 2025-03-10T17:30:04.462Z,1741627804.462 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2025-03-10T17:30:04.462Z,1741627804.462 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:30:04.473Z,1741627804.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:30:04.913Z,1741627804.913 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:30:04.913Z,1741627804.913 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2025-03-10T17:30:18.618Z,1741627818.618 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T17:25:18.4Z 2025-03-10T17:30:18.618Z,1741627818.618 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T17:30:18.618Z,1741627818.618 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T17:30:22.332Z,1741627822.332 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20250310T162934/Courier0013.lzma 2025-03-10T17:30:23.265Z,1741627823.265 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0013.lzma.bak 2025-03-10T17:30:23.265Z,1741627823.265 [DataOverHttps](INFO): SBD MOMSN=24149443 2025-03-10T17:30:37.683Z,1741627837.683 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250310T162934/Express0014.lzma 2025-03-10T17:30:38.657Z,1741627838.657 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0014.lzma.bak 2025-03-10T17:30:38.657Z,1741627838.657 [DataOverHttps](INFO): SBD MOMSN=24149446 2025-03-10T17:30:40.076Z,1741627840.076 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T17:30:40.076Z,1741627840.076 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T17:30:40.076Z,1741627840.076 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T17:33:05.519Z,1741627985.519 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2025-03-10T17:33:05.519Z,1741627985.519 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:33:05.566Z,1741627985.566 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:33:05.965Z,1741627985.965 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:33:05.965Z,1741627985.965 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2025-03-10T17:35:34.973Z,1741628134.973 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T17:35:34.973Z,1741628134.973 [NAL9602] Data Fault, FailCount= 1 2025-03-10T17:35:34.973Z,1741628134.973 [NAL9602](ERROR): Data Fault 2025-03-10T17:35:35.016Z,1741628135.016 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T17:35:35.380Z,1741628135.380 [NAL9602](INFO): Powering down 2025-03-10T17:35:36.200Z,1741628136.200 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T17:35:36.200Z,1741628136.200 [NAL9602] No Fault, FailCount= 1 2025-03-10T17:35:40.641Z,1741628140.641 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T17:35:40.641Z,1741628140.641 [Default:CheckIn:C.Wait] Stopped 2025-03-10T17:35:40.641Z,1741628140.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:35:40.641Z,1741628140.641 [Default:CheckIn:D] Running Loop=1 2025-03-10T17:35:41.044Z,1741628141.044 [Default:CheckIn:D] Stopped 2025-03-10T17:35:41.044Z,1741628141.044 [Default:CheckIn:E] Running Loop=1 2025-03-10T17:35:41.461Z,1741628141.461 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.492710 min 2025-03-10T17:35:41.461Z,1741628141.461 [Default:CheckIn:E] Stopped 2025-03-10T17:35:41.462Z,1741628141.462 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T17:35:41.462Z,1741628141.462 [Default:CheckIn] Stopped 2025-03-10T17:35:41.462Z,1741628141.462 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T17:35:41.462Z,1741628141.462 [Default:CheckIn](INFO): Running loop #4 2025-03-10T17:35:41.462Z,1741628141.462 [Default:CheckIn] Running Loop=4 2025-03-10T17:35:41.462Z,1741628141.462 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T17:35:41.462Z,1741628141.462 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T17:36:05.676Z,1741628165.676 [NAL9602](INFO): Powering up NAL9602 2025-03-10T17:36:06.495Z,1741628166.495 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2025-03-10T17:36:06.495Z,1741628166.495 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:36:06.534Z,1741628166.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:36:06.902Z,1741628166.902 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:36:06.903Z,1741628166.903 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2025-03-10T17:36:16.589Z,1741628176.589 [NAL9602](INFO): NAL9602 initialized 2025-03-10T17:39:07.492Z,1741628347.492 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23 2025-03-10T17:39:07.492Z,1741628347.492 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:39:07.503Z,1741628347.503 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:39:07.908Z,1741628347.908 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:39:07.908Z,1741628347.908 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23 2025-03-10T17:40:41.853Z,1741628441.853 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T17:35:41.5Z 2025-03-10T17:40:41.853Z,1741628441.853 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T17:40:41.854Z,1741628441.854 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T17:40:46.234Z,1741628446.234 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T162934/Courier0016.lzma 2025-03-10T17:40:47.237Z,1741628447.237 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0016.lzma.bak 2025-03-10T17:40:47.237Z,1741628447.237 [DataOverHttps](INFO): SBD MOMSN=24149468 2025-03-10T17:41:02.615Z,1741628462.615 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250310T162934/Express0017.lzma 2025-03-10T17:41:03.617Z,1741628463.617 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0017.lzma.bak 2025-03-10T17:41:03.617Z,1741628463.617 [DataOverHttps](INFO): SBD MOMSN=24149470 2025-03-10T17:41:04.729Z,1741628464.729 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T17:41:04.729Z,1741628464.729 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T17:41:04.729Z,1741628464.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T17:42:08.524Z,1741628528.524 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24 2025-03-10T17:42:08.524Z,1741628528.524 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:42:08.559Z,1741628528.559 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:42:08.932Z,1741628528.932 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:42:08.932Z,1741628528.932 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24 2025-03-10T17:44:32.741Z,1741628672.741 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-03-10T17:44:32.744Z,1741628672.744 [BPC1](INFO): Received data from all battery sticks. 2025-03-10T17:45:09.525Z,1741628709.525 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25 2025-03-10T17:45:09.525Z,1741628709.525 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:45:09.563Z,1741628709.563 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:45:09.942Z,1741628709.942 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:45:09.942Z,1741628709.942 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25 2025-03-10T17:46:05.285Z,1741628765.285 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T17:46:05.285Z,1741628765.285 [Default:CheckIn:C.Wait] Stopped 2025-03-10T17:46:05.285Z,1741628765.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:46:05.285Z,1741628765.285 [Default:CheckIn:D] Running Loop=1 2025-03-10T17:46:05.669Z,1741628765.669 [Default:CheckIn:D] Stopped 2025-03-10T17:46:05.669Z,1741628765.669 [Default:CheckIn:E] Running Loop=1 2025-03-10T17:46:06.077Z,1741628766.077 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.903133 min 2025-03-10T17:46:06.077Z,1741628766.077 [Default:CheckIn:E] Stopped 2025-03-10T17:46:06.077Z,1741628766.077 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T17:46:06.077Z,1741628766.077 [Default:CheckIn] Stopped 2025-03-10T17:46:06.077Z,1741628766.077 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T17:46:06.077Z,1741628766.077 [Default:CheckIn](INFO): Running loop #5 2025-03-10T17:46:06.077Z,1741628766.077 [Default:CheckIn] Running Loop=5 2025-03-10T17:46:06.077Z,1741628766.077 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T17:46:06.078Z,1741628766.078 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T17:46:17.777Z,1741628777.777 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T17:46:17.777Z,1741628777.777 [NAL9602] Data Fault, FailCount= 2 2025-03-10T17:46:17.777Z,1741628777.777 [NAL9602](ERROR): Data Fault 2025-03-10T17:46:17.822Z,1741628777.822 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T17:46:18.185Z,1741628778.185 [NAL9602](INFO): Powering down 2025-03-10T17:46:19.004Z,1741628779.004 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T17:46:19.004Z,1741628779.004 [NAL9602] No Fault, FailCount= 2 2025-03-10T17:46:48.488Z,1741628808.488 [NAL9602](INFO): Powering up NAL9602 2025-03-10T17:46:59.389Z,1741628819.389 [NAL9602](INFO): NAL9602 initialized 2025-03-10T17:48:10.500Z,1741628890.500 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26 2025-03-10T17:48:10.500Z,1741628890.500 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:48:10.511Z,1741628890.511 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:48:10.936Z,1741628890.936 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:48:10.936Z,1741628890.936 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26 2025-03-10T17:51:06.245Z,1741629066.245 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T17:46:06.1Z 2025-03-10T17:51:06.245Z,1741629066.245 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T17:51:06.245Z,1741629066.245 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T17:51:11.186Z,1741629071.186 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250310T162934/Courier0019.lzma 2025-03-10T17:51:11.520Z,1741629071.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27 2025-03-10T17:51:11.520Z,1741629071.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:51:11.530Z,1741629071.530 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:51:11.909Z,1741629071.909 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:51:11.909Z,1741629071.909 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27 2025-03-10T17:51:12.189Z,1741629072.189 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0019.lzma.bak 2025-03-10T17:51:12.189Z,1741629072.189 [DataOverHttps](INFO): SBD MOMSN=24149532 2025-03-10T17:51:27.499Z,1741629087.499 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250310T162934/Express0020.lzma 2025-03-10T17:51:28.518Z,1741629088.518 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0020.lzma.bak 2025-03-10T17:51:28.519Z,1741629088.519 [DataOverHttps](INFO): SBD MOMSN=24149534 2025-03-10T17:51:29.680Z,1741629089.680 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T17:51:29.680Z,1741629089.680 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T17:51:29.680Z,1741629089.680 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T17:54:12.513Z,1741629252.513 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28 2025-03-10T17:54:12.513Z,1741629252.513 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:54:12.552Z,1741629252.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:54:12.910Z,1741629252.910 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:54:12.910Z,1741629252.910 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28 2025-03-10T17:56:30.281Z,1741629390.281 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T17:56:30.281Z,1741629390.281 [Default:CheckIn:C.Wait] Stopped 2025-03-10T17:56:30.281Z,1741629390.281 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T17:56:30.281Z,1741629390.281 [Default:CheckIn:D] Running Loop=1 2025-03-10T17:56:30.659Z,1741629390.659 [Default:CheckIn:D] Stopped 2025-03-10T17:56:30.659Z,1741629390.659 [Default:CheckIn:E] Running Loop=1 2025-03-10T17:56:31.072Z,1741629391.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.319637 min 2025-03-10T17:56:31.072Z,1741629391.072 [Default:CheckIn:E] Stopped 2025-03-10T17:56:31.072Z,1741629391.072 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T17:56:31.072Z,1741629391.072 [Default:CheckIn] Stopped 2025-03-10T17:56:31.073Z,1741629391.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T17:56:31.073Z,1741629391.073 [Default:CheckIn](INFO): Running loop #6 2025-03-10T17:56:31.073Z,1741629391.073 [Default:CheckIn] Running Loop=6 2025-03-10T17:56:31.073Z,1741629391.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T17:56:31.073Z,1741629391.073 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T17:57:00.553Z,1741629420.553 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T17:57:00.553Z,1741629420.553 [NAL9602] Data Fault, FailCount= 3 2025-03-10T17:57:00.553Z,1741629420.553 [NAL9602](ERROR): Data Fault 2025-03-10T17:57:00.570Z,1741629420.570 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T17:57:00.951Z,1741629420.951 [NAL9602](INFO): Powering down 2025-03-10T17:57:01.780Z,1741629421.780 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T17:57:01.781Z,1741629421.781 [NAL9602] No Fault, FailCount= 3 2025-03-10T17:57:13.483Z,1741629433.483 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29 2025-03-10T17:57:13.483Z,1741629433.483 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T17:57:13.494Z,1741629433.494 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T17:57:13.907Z,1741629433.907 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T17:57:13.907Z,1741629433.907 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29 2025-03-10T17:57:31.253Z,1741629451.253 [NAL9602](INFO): Powering up NAL9602 2025-03-10T17:57:42.165Z,1741629462.165 [NAL9602](INFO): NAL9602 initialized 2025-03-10T17:59:49.828Z,1741629589.828 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-10T17:59:49.828Z,1741629589.828 [DropWeight] Hardware Fault, FailCount= 1 2025-03-10T17:59:49.828Z,1741629589.828 [DropWeight](ERROR): Hardware Fault 2025-03-10T17:59:49.913Z,1741629589.913 [CBIT](INFO): Critical error at 20250310T175949 2025-03-10T17:59:49.963Z,1741629589.963 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-10T17:59:49.963Z,1741629589.963 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-10T17:59:50.281Z,1741629590.281 [CBIT](INFO): Critical error at 20250310T175949 2025-03-10T18:00:14.488Z,1741629614.488 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30 2025-03-10T18:00:14.488Z,1741629614.488 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:00:14.499Z,1741629614.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:00:14.905Z,1741629614.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:00:14.905Z,1741629614.905 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30 2025-03-10T18:01:31.239Z,1741629691.239 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T17:56:31.1Z 2025-03-10T18:01:31.239Z,1741629691.239 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T18:01:31.239Z,1741629691.239 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T18:01:37.058Z,1741629697.058 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20250310T162934/Courier0022.lzma 2025-03-10T18:01:37.893Z,1741629697.893 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0022.lzma.bak 2025-03-10T18:01:37.893Z,1741629697.893 [DataOverHttps](INFO): SBD MOMSN=24149556 2025-03-10T18:01:56.147Z,1741629716.147 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250310T162934/Express0023.lzma 2025-03-10T18:01:57.149Z,1741629717.149 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0023.lzma.bak 2025-03-10T18:01:57.149Z,1741629717.149 [DataOverHttps](INFO): SBD MOMSN=24149560 2025-03-10T18:01:58.791Z,1741629718.791 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T18:01:58.791Z,1741629718.791 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T18:01:58.791Z,1741629718.791 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T18:03:15.569Z,1741629795.569 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31 2025-03-10T18:03:15.569Z,1741629795.569 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:03:15.595Z,1741629795.595 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:03:15.990Z,1741629795.990 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:03:15.990Z,1741629795.990 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31 2025-03-10T18:06:16.542Z,1741629976.542 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32 2025-03-10T18:06:16.542Z,1741629976.542 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:06:16.553Z,1741629976.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:06:16.962Z,1741629976.962 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:06:16.962Z,1741629976.962 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32 2025-03-10T18:06:59.372Z,1741630019.372 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T18:06:59.372Z,1741630019.372 [Default:CheckIn:C.Wait] Stopped 2025-03-10T18:06:59.372Z,1741630019.372 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T18:06:59.373Z,1741630019.373 [Default:CheckIn:D] Running Loop=1 2025-03-10T18:06:59.776Z,1741630019.776 [Default:CheckIn:D] Stopped 2025-03-10T18:06:59.776Z,1741630019.776 [Default:CheckIn:E] Running Loop=1 2025-03-10T18:07:00.177Z,1741630020.177 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.804915 min 2025-03-10T18:07:00.177Z,1741630020.177 [Default:CheckIn:E] Stopped 2025-03-10T18:07:00.177Z,1741630020.177 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T18:07:00.177Z,1741630020.177 [Default:CheckIn] Stopped 2025-03-10T18:07:00.177Z,1741630020.177 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T18:07:00.177Z,1741630020.177 [Default:CheckIn](INFO): Running loop #7 2025-03-10T18:07:00.178Z,1741630020.178 [Default:CheckIn] Running Loop=7 2025-03-10T18:07:00.178Z,1741630020.178 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T18:07:00.178Z,1741630020.178 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T18:07:44.214Z,1741630064.214 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T18:07:44.214Z,1741630064.214 [NAL9602] Data Fault, FailCount= 4 2025-03-10T18:07:44.214Z,1741630064.214 [NAL9602](ERROR): Data Fault 2025-03-10T18:07:44.231Z,1741630064.231 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T18:07:44.610Z,1741630064.610 [NAL9602](INFO): Powering down 2025-03-10T18:07:45.439Z,1741630065.439 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T18:07:45.439Z,1741630065.439 [NAL9602] No Fault, FailCount= 4 2025-03-10T18:08:14.916Z,1741630094.916 [NAL9602](INFO): Powering up NAL9602 2025-03-10T18:08:25.820Z,1741630105.820 [NAL9602](INFO): NAL9602 initialized 2025-03-10T18:09:17.549Z,1741630157.549 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 33 2025-03-10T18:09:17.549Z,1741630157.549 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:09:17.560Z,1741630157.560 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:09:17.962Z,1741630157.962 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:09:17.962Z,1741630157.962 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 33 2025-03-10T18:12:00.421Z,1741630320.421 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T18:07:00.2Z 2025-03-10T18:12:00.421Z,1741630320.421 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T18:12:00.422Z,1741630320.422 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T18:12:06.026Z,1741630326.026 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T162934/Courier0025.lzma 2025-03-10T18:12:07.029Z,1741630327.029 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0025.lzma.bak 2025-03-10T18:12:07.029Z,1741630327.029 [DataOverHttps](INFO): SBD MOMSN=24149582 2025-03-10T18:12:18.621Z,1741630338.621 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 34 2025-03-10T18:12:18.622Z,1741630338.622 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:12:18.642Z,1741630338.642 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:12:19.005Z,1741630339.005 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:12:19.005Z,1741630339.005 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 34 2025-03-10T18:12:22.707Z,1741630342.707 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250310T162934/Express0026.lzma 2025-03-10T18:12:23.711Z,1741630343.711 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0026.lzma.bak 2025-03-10T18:12:23.712Z,1741630343.712 [DataOverHttps](INFO): SBD MOMSN=24149584 2025-03-10T18:12:25.049Z,1741630345.049 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T18:12:25.049Z,1741630345.049 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T18:12:25.049Z,1741630345.049 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T18:14:50.123Z,1741630490.123 [CBIT](INFO): Clearing failed state for component DropWeight 2025-03-10T18:14:50.123Z,1741630490.123 [DropWeight] No Fault, FailCount= 1 2025-03-10T18:15:19.588Z,1741630519.588 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 35 2025-03-10T18:15:19.588Z,1741630519.588 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:15:19.627Z,1741630519.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:15:19.988Z,1741630519.988 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:15:19.989Z,1741630519.989 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 35 2025-03-10T18:17:25.650Z,1741630645.650 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T18:17:25.651Z,1741630645.651 [Default:CheckIn:C.Wait] Stopped 2025-03-10T18:17:25.651Z,1741630645.651 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T18:17:25.651Z,1741630645.651 [Default:CheckIn:D] Running Loop=1 2025-03-10T18:17:26.041Z,1741630646.041 [Default:CheckIn:D] Stopped 2025-03-10T18:17:26.041Z,1741630646.041 [Default:CheckIn:E] Running Loop=1 2025-03-10T18:17:26.445Z,1741630646.445 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.242668 min 2025-03-10T18:17:26.445Z,1741630646.445 [Default:CheckIn:E] Stopped 2025-03-10T18:17:26.445Z,1741630646.445 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T18:17:26.445Z,1741630646.445 [Default:CheckIn] Stopped 2025-03-10T18:17:26.445Z,1741630646.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T18:17:26.445Z,1741630646.445 [Default:CheckIn](INFO): Running loop #8 2025-03-10T18:17:26.445Z,1741630646.445 [Default:CheckIn] Running Loop=8 2025-03-10T18:17:26.445Z,1741630646.445 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T18:17:26.445Z,1741630646.445 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T18:18:20.600Z,1741630700.600 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 36 2025-03-10T18:18:20.600Z,1741630700.600 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:18:20.621Z,1741630700.621 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:18:21.033Z,1741630701.033 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:18:21.033Z,1741630701.033 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 36 2025-03-10T18:18:29.057Z,1741630709.057 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T18:18:29.057Z,1741630709.057 [NAL9602] Data Fault, FailCount= 5 2025-03-10T18:18:29.057Z,1741630709.057 [NAL9602](ERROR): Data Fault 2025-03-10T18:18:29.074Z,1741630709.074 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T18:18:29.074Z,1741630709.074 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-03-10T18:18:29.455Z,1741630709.455 [NAL9602](INFO): Powering down 2025-03-10T18:18:29.471Z,1741630709.471 [CBIT](INFO): Critical error at 20250310T181829 2025-03-10T18:20:29.064Z,1741630829.064 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T18:20:29.064Z,1741630829.064 [NAL9602] No Fault, FailCount= 5 2025-03-10T18:20:29.443Z,1741630829.443 [NAL9602](INFO): Powering up NAL9602 2025-03-10T18:20:40.361Z,1741630840.361 [NAL9602](INFO): NAL9602 initialized 2025-03-10T18:21:21.568Z,1741630881.568 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 37 2025-03-10T18:21:21.568Z,1741630881.568 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:21:21.579Z,1741630881.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:21:21.995Z,1741630881.995 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:21:21.995Z,1741630881.995 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 37 2025-03-10T18:22:26.693Z,1741630946.693 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T18:17:26.4Z 2025-03-10T18:22:26.693Z,1741630946.693 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T18:22:26.694Z,1741630946.694 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T18:22:31.587Z,1741630951.587 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250310T162934/Courier0028.lzma 2025-03-10T18:22:32.589Z,1741630952.589 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0028.lzma.bak 2025-03-10T18:22:32.589Z,1741630952.589 [DataOverHttps](INFO): SBD MOMSN=24149612 2025-03-10T18:22:48.171Z,1741630968.171 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250310T162934/Express0029.lzma 2025-03-10T18:22:49.173Z,1741630969.173 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0029.lzma.bak 2025-03-10T18:22:49.173Z,1741630969.173 [DataOverHttps](INFO): SBD MOMSN=24149615 2025-03-10T18:22:50.544Z,1741630970.544 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T18:22:50.544Z,1741630970.544 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T18:22:50.544Z,1741630970.544 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T18:24:22.671Z,1741631062.671 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 38 2025-03-10T18:24:22.672Z,1741631062.672 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:24:22.709Z,1741631062.709 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:24:23.065Z,1741631063.065 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:24:23.065Z,1741631063.065 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 38 2025-03-10T18:27:23.631Z,1741631243.631 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 39 2025-03-10T18:27:23.631Z,1741631243.631 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:27:23.645Z,1741631243.645 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:27:24.057Z,1741631244.057 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:27:24.057Z,1741631244.057 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 39 2025-03-10T18:27:51.117Z,1741631271.117 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T18:27:51.117Z,1741631271.117 [Default:CheckIn:C.Wait] Stopped 2025-03-10T18:27:51.117Z,1741631271.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T18:27:51.118Z,1741631271.118 [Default:CheckIn:D] Running Loop=1 2025-03-10T18:27:51.497Z,1741631271.497 [Default:CheckIn:D] Stopped 2025-03-10T18:27:51.498Z,1741631271.498 [Default:CheckIn:E] Running Loop=1 2025-03-10T18:27:51.905Z,1741631271.905 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.666943 min 2025-03-10T18:27:51.905Z,1741631271.905 [Default:CheckIn:E] Stopped 2025-03-10T18:27:51.905Z,1741631271.905 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T18:27:51.905Z,1741631271.905 [Default:CheckIn] Stopped 2025-03-10T18:27:51.905Z,1741631271.905 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T18:27:51.906Z,1741631271.906 [Default:CheckIn](INFO): Running loop #9 2025-03-10T18:27:51.906Z,1741631271.906 [Default:CheckIn] Running Loop=9 2025-03-10T18:27:51.906Z,1741631271.906 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T18:27:51.906Z,1741631271.906 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T18:30:24.610Z,1741631424.610 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 40 2025-03-10T18:30:24.610Z,1741631424.610 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:30:24.621Z,1741631424.621 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:30:25.030Z,1741631425.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:30:25.030Z,1741631425.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 40 2025-03-10T18:30:41.575Z,1741631441.575 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T18:30:41.575Z,1741631441.575 [NAL9602] Data Fault, FailCount= 1 2025-03-10T18:30:41.575Z,1741631441.575 [NAL9602](ERROR): Data Fault 2025-03-10T18:30:41.593Z,1741631441.593 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T18:30:41.976Z,1741631441.976 [NAL9602](INFO): Powering down 2025-03-10T18:30:42.799Z,1741631442.799 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T18:30:42.799Z,1741631442.799 [NAL9602] No Fault, FailCount= 1 2025-03-10T18:31:12.281Z,1741631472.281 [NAL9602](INFO): Powering up NAL9602 2025-03-10T18:31:23.198Z,1741631483.198 [NAL9602](INFO): NAL9602 initialized 2025-03-10T18:32:52.129Z,1741631572.129 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T18:27:51.9Z 2025-03-10T18:32:52.129Z,1741631572.129 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T18:32:52.130Z,1741631572.130 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T18:32:56.922Z,1741631576.922 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T162934/Courier0031.lzma 2025-03-10T18:32:57.925Z,1741631577.925 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0031.lzma.bak 2025-03-10T18:32:57.925Z,1741631577.925 [DataOverHttps](INFO): SBD MOMSN=24149639 2025-03-10T18:33:13.315Z,1741631593.315 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250310T162934/Express0032.lzma 2025-03-10T18:33:14.317Z,1741631594.317 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0032.lzma.bak 2025-03-10T18:33:14.317Z,1741631594.317 [DataOverHttps](INFO): SBD MOMSN=24149641 2025-03-10T18:33:15.557Z,1741631595.557 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T18:33:15.557Z,1741631595.557 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T18:33:15.557Z,1741631595.557 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T18:33:25.636Z,1741631605.636 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 41 2025-03-10T18:33:25.636Z,1741631605.636 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:33:25.675Z,1741631605.675 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:33:26.052Z,1741631606.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:33:26.052Z,1741631606.052 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 41 2025-03-10T18:36:26.631Z,1741631786.631 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 42 2025-03-10T18:36:26.631Z,1741631786.631 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:36:26.642Z,1741631786.642 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:36:27.055Z,1741631787.055 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:36:27.059Z,1741631787.059 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 42 2025-03-10T18:38:16.133Z,1741631896.133 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T18:38:16.133Z,1741631896.133 [Default:CheckIn:C.Wait] Stopped 2025-03-10T18:38:16.133Z,1741631896.133 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T18:38:16.133Z,1741631896.133 [Default:CheckIn:D] Running Loop=1 2025-03-10T18:38:16.537Z,1741631896.537 [Default:CheckIn:D] Stopped 2025-03-10T18:38:16.537Z,1741631896.537 [Default:CheckIn:E] Running Loop=1 2025-03-10T18:38:16.957Z,1741631896.957 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.084269 min 2025-03-10T18:38:16.958Z,1741631896.958 [Default:CheckIn:E] Stopped 2025-03-10T18:38:16.958Z,1741631896.958 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T18:38:16.958Z,1741631896.958 [Default:CheckIn] Stopped 2025-03-10T18:38:16.958Z,1741631896.958 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T18:38:16.958Z,1741631896.958 [Default:CheckIn](INFO): Running loop #10 2025-03-10T18:38:16.958Z,1741631896.958 [Default:CheckIn] Running Loop=10 2025-03-10T18:38:16.958Z,1741631896.958 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T18:38:16.958Z,1741631896.958 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T18:39:27.624Z,1741631967.624 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 43 2025-03-10T18:39:27.624Z,1741631967.624 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:39:27.635Z,1741631967.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:39:28.040Z,1741631968.040 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:39:28.040Z,1741631968.040 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 43 2025-03-10T18:41:26.413Z,1741632086.413 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T18:41:26.413Z,1741632086.413 [NAL9602] Data Fault, FailCount= 2 2025-03-10T18:41:26.413Z,1741632086.413 [NAL9602](ERROR): Data Fault 2025-03-10T18:41:26.454Z,1741632086.454 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T18:41:26.815Z,1741632086.815 [NAL9602](INFO): Powering down 2025-03-10T18:41:27.641Z,1741632087.641 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T18:41:27.641Z,1741632087.641 [NAL9602] No Fault, FailCount= 2 2025-03-10T18:41:57.124Z,1741632117.124 [NAL9602](INFO): Powering up NAL9602 2025-03-10T18:42:08.027Z,1741632128.027 [NAL9602](INFO): NAL9602 initialized 2025-03-10T18:42:28.634Z,1741632148.634 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 44 2025-03-10T18:42:28.634Z,1741632148.634 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:42:28.644Z,1741632148.644 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:42:29.053Z,1741632149.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:42:29.053Z,1741632149.053 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 44 2025-03-10T18:43:17.154Z,1741632197.154 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T18:38:16.0Z 2025-03-10T18:43:17.154Z,1741632197.154 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T18:43:17.154Z,1741632197.154 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T18:43:20.840Z,1741632200.840 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T162934/Courier0034.lzma 2025-03-10T18:43:21.741Z,1741632201.741 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0034.lzma.bak 2025-03-10T18:43:21.741Z,1741632201.741 [DataOverHttps](INFO): SBD MOMSN=24149684 2025-03-10T18:43:37.028Z,1741632217.028 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250310T162934/Express0035.lzma 2025-03-10T18:43:38.029Z,1741632218.029 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0035.lzma.bak 2025-03-10T18:43:38.029Z,1741632218.029 [DataOverHttps](INFO): SBD MOMSN=24149686 2025-03-10T18:43:39.385Z,1741632219.385 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T18:43:39.385Z,1741632219.385 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T18:43:39.385Z,1741632219.385 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T18:44:51.664Z,1741632291.664 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-10T18:44:51.664Z,1741632291.664 [DropWeight] Hardware Fault, FailCount= 1 2025-03-10T18:44:51.664Z,1741632291.664 [DropWeight](ERROR): Hardware Fault 2025-03-10T18:44:51.748Z,1741632291.748 [CBIT](INFO): Critical error at 20250310T184451 2025-03-10T18:44:51.760Z,1741632291.760 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-10T18:44:51.760Z,1741632291.760 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-10T18:44:52.095Z,1741632292.095 [CBIT](INFO): Critical error at 20250310T184451 2025-03-10T18:45:29.658Z,1741632329.658 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 45 2025-03-10T18:45:29.658Z,1741632329.658 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:45:29.681Z,1741632329.681 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:45:30.089Z,1741632330.089 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:45:30.089Z,1741632330.089 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 45 2025-03-10T18:48:09.616Z,1741632489.616 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-03-10T18:48:30.627Z,1741632510.627 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 46 2025-03-10T18:48:30.627Z,1741632510.627 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:48:30.664Z,1741632510.664 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:48:31.073Z,1741632511.073 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:48:31.073Z,1741632511.073 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 46 2025-03-10T18:48:39.945Z,1741632519.945 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T18:48:39.945Z,1741632519.945 [Default:CheckIn:C.Wait] Stopped 2025-03-10T18:48:39.945Z,1741632519.945 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T18:48:39.945Z,1741632519.945 [Default:CheckIn:D] Running Loop=1 2025-03-10T18:48:40.337Z,1741632520.337 [Default:CheckIn:D] Stopped 2025-03-10T18:48:40.337Z,1741632520.337 [Default:CheckIn:E] Running Loop=1 2025-03-10T18:48:40.727Z,1741632520.727 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.480933 min 2025-03-10T18:48:40.727Z,1741632520.727 [Default:CheckIn:E] Stopped 2025-03-10T18:48:40.727Z,1741632520.727 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T18:48:40.727Z,1741632520.727 [Default:CheckIn] Stopped 2025-03-10T18:48:40.727Z,1741632520.727 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T18:48:40.728Z,1741632520.728 [Default:CheckIn](INFO): Running loop #11 2025-03-10T18:48:40.728Z,1741632520.728 [Default:CheckIn] Running Loop=11 2025-03-10T18:48:40.728Z,1741632520.728 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T18:48:40.728Z,1741632520.728 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T18:51:31.639Z,1741632691.639 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 47 2025-03-10T18:51:31.639Z,1741632691.639 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:51:31.650Z,1741632691.650 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:51:32.066Z,1741632692.066 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:51:32.066Z,1741632692.066 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 47 2025-03-10T18:51:57.884Z,1741632717.884 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-03-10T18:51:57.886Z,1741632717.886 [BPC1](INFO): Received data from all battery sticks. 2025-03-10T18:52:11.204Z,1741632731.204 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T18:52:11.204Z,1741632731.204 [NAL9602] Data Fault, FailCount= 3 2025-03-10T18:52:11.205Z,1741632731.205 [NAL9602](ERROR): Data Fault 2025-03-10T18:52:11.247Z,1741632731.247 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T18:52:11.612Z,1741632731.612 [NAL9602](INFO): Powering down 2025-03-10T18:52:12.437Z,1741632732.437 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T18:52:12.437Z,1741632732.437 [NAL9602] No Fault, FailCount= 3 2025-03-10T18:52:41.938Z,1741632761.938 [NAL9602](INFO): Powering up NAL9602 2025-03-10T18:52:52.853Z,1741632772.853 [NAL9602](INFO): NAL9602 initialized 2025-03-10T18:53:40.963Z,1741632820.963 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T18:48:40.7Z 2025-03-10T18:53:40.963Z,1741632820.963 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T18:53:40.963Z,1741632820.963 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T18:53:46.265Z,1741632826.265 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20250310T162934/Courier0037.lzma 2025-03-10T18:53:47.265Z,1741632827.265 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Courier0037.lzma.bak 2025-03-10T18:53:47.265Z,1741632827.265 [DataOverHttps](INFO): SBD MOMSN=24149893 2025-03-10T18:54:02.733Z,1741632842.733 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250310T162934/Express0038.lzma 2025-03-10T18:54:03.729Z,1741632843.729 [DataOverHttps](INFO): Moved sent file to Logs/20250310T162934/Express0038.lzma.bak 2025-03-10T18:54:03.729Z,1741632843.729 [DataOverHttps](INFO): SBD MOMSN=24149897 2025-03-10T18:54:05.177Z,1741632845.177 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T18:54:05.177Z,1741632845.177 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T18:54:05.177Z,1741632845.177 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T18:54:32.664Z,1741632872.664 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 48 2025-03-10T18:54:32.664Z,1741632872.664 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:54:32.728Z,1741632872.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:54:33.068Z,1741632873.068 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:54:33.068Z,1741632873.068 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 48 2025-03-10T18:57:33.643Z,1741633053.643 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 49 2025-03-10T18:57:33.644Z,1741633053.644 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:57:33.654Z,1741633053.654 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:57:34.066Z,1741633054.066 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:57:34.066Z,1741633054.066 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 49 2025-03-10T18:57:50.420Z,1741633070.420 [CommandExec](IMPORTANT): got command restart application 2025-03-10T18:57:51.423Z,1741633071.423 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:51.423Z,1741633071.423 [CommandExec](INFO): Uninitializing the command executive. 2025-03-10T18:57:51.424Z,1741633071.424 [CommandExec](INFO): Uninitializing the command scheduler. 2025-03-10T18:57:51.424Z,1741633071.424 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:51.603Z,1741633071.603 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-03-10T18:57:51.603Z,1741633071.603 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-03-10T18:57:51.603Z,1741633071.603 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:51.603Z,1741633071.603 [NavChartDb](INFO): Join timeout helper Thread ID is 1404 2025-03-10T18:57:51.979Z,1741633071.979 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:51.979Z,1741633071.979 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:51.991Z,1741633071.991 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-03-10T18:57:51.991Z,1741633071.991 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:51.991Z,1741633071.991 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1405 2025-03-10T18:57:52.083Z,1741633072.083 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:52.083Z,1741633072.083 [WetLabsBB2FL](INFO): Powering down 2025-03-10T18:57:52.084Z,1741633072.084 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:52.091Z,1741633072.091 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-03-10T18:57:52.091Z,1741633072.091 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:52.091Z,1741633072.091 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1406 2025-03-10T18:57:52.323Z,1741633072.323 [CTD_Seabird](INFO): Powering down 2025-03-10T18:57:52.339Z,1741633072.339 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:52.342Z,1741633072.342 [CTD_Seabird](INFO): Powering down 2025-03-10T18:57:52.355Z,1741633072.355 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:52.363Z,1741633072.363 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-03-10T18:57:52.363Z,1741633072.363 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:52.363Z,1741633072.363 [Radio_Surface](INFO): Join timeout helper Thread ID is 1407 2025-03-10T18:57:52.735Z,1741633072.735 [Radio_Surface](INFO): Powering down 2025-03-10T18:57:52.736Z,1741633072.736 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:52.736Z,1741633072.736 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:52.739Z,1741633072.739 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-03-10T18:57:52.739Z,1741633072.739 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:52.739Z,1741633072.739 [Onboard](INFO): Join timeout helper Thread ID is 1408 2025-03-10T18:57:52.843Z,1741633072.843 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:52.843Z,1741633072.843 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:52.864Z,1741633072.864 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-03-10T18:57:52.864Z,1741633072.864 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:52.864Z,1741633072.864 [DataOverHttps](INFO): Join timeout helper Thread ID is 1409 2025-03-10T18:57:53.087Z,1741633073.087 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:53.088Z,1741633073.088 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.092Z,1741633073.092 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-03-10T18:57:53.092Z,1741633073.092 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.092Z,1741633073.092 [BackseatComponent](INFO): Join timeout helper Thread ID is 1410 2025-03-10T18:57:53.235Z,1741633073.235 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:53.235Z,1741633073.235 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.248Z,1741633073.248 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-03-10T18:57:53.248Z,1741633073.248 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.248Z,1741633073.248 [logger](INFO): Join timeout helper Thread ID is 1411 2025-03-10T18:57:53.283Z,1741633073.283 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:53.283Z,1741633073.283 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.287Z,1741633073.287 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-03-10T18:57:53.288Z,1741633073.288 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.288Z,1741633073.288 [CommandLine](INFO): Join timeout helper Thread ID is 1412 2025-03-10T18:57:53.331Z,1741633073.331 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:53.331Z,1741633073.331 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.347Z,1741633073.347 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-03-10T18:57:53.348Z,1741633073.348 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.348Z,1741633073.348 [CommandExec](INFO): Join timeout helper Thread ID is 1413 2025-03-10T18:57:53.349Z,1741633073.349 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-03-10T18:57:53.349Z,1741633073.349 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.350Z,1741633073.350 [controlThread](INFO): Join timeout helper Thread ID is 1414 2025-03-10T18:57:53.411Z,1741633073.411 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:57:53.412Z,1741633073.412 [controlThread](DEBUG): Uninitializing ControlThread 2025-03-10T18:57:53.413Z,1741633073.413 [AHRS_M2](INFO): Powering down 2025-03-10T18:57:53.488Z,1741633073.488 [MultiRay](INFO): Powering down. 2025-03-10T18:57:53.489Z,1741633073.489 [NAL9602](INFO): Powering down 2025-03-10T18:57:53.490Z,1741633073.490 [Waterlinked](INFO): Powering down 2025-03-10T18:57:53.492Z,1741633073.492 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-03-10T18:57:53.492Z,1741633073.492 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-03-10T18:57:53.493Z,1741633073.493 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-03-10T18:57:53.493Z,1741633073.493 [MissionManager](INFO): Uninitializing Mission Default 2025-03-10T18:57:53.493Z,1741633073.493 [Default] Stopped 2025-03-10T18:57:53.493Z,1741633073.493 [Default](DEBUG): Aggregate::uninitialize Default 2025-03-10T18:57:53.493Z,1741633073.493 [Default:B.GoToSurface] Stopped 2025-03-10T18:57:53.494Z,1741633073.494 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-10T18:57:53.494Z,1741633073.494 [Default:CheckIn] Stopped 2025-03-10T18:57:53.494Z,1741633073.494 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T18:57:53.494Z,1741633073.494 [Default:CheckIn:C.Wait] Stopped 2025-03-10T18:57:53.494Z,1741633073.494 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T18:57:53.497Z,1741633073.497 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-03-10T18:57:53.497Z,1741633073.497 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-03-10T18:57:53.498Z,1741633073.498 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-03-10T18:57:53.498Z,1741633073.498 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-03-10T18:57:53.498Z,1741633073.498 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-03-10T18:57:53.498Z,1741633073.498 [BuoyancyServo](INFO): Powering down 2025-03-10T18:57:53.511Z,1741633073.511 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-03-10T18:57:53.511Z,1741633073.511 [ElevatorServo](INFO): Powering down 2025-03-10T18:57:53.512Z,1741633073.512 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-03-10T18:57:53.512Z,1741633073.512 [MassServo](INFO): Powering down 2025-03-10T18:57:53.513Z,1741633073.513 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-03-10T18:57:53.513Z,1741633073.513 [RudderServo](INFO): Powering down 2025-03-10T18:57:53.514Z,1741633073.514 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-03-10T18:57:53.514Z,1741633073.514 [ThrusterHE](INFO): Powering down 2025-03-10T18:57:53.515Z,1741633073.515 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-03-10T18:57:53.515Z,1741633073.515 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-03-10T18:57:53.515Z,1741633073.515 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-03-10T18:57:53.516Z,1741633073.516 [CBIT](DEBUG): Powering off loads. 2025-03-10T18:57:53.527Z,1741633073.527 [CBIT](DEBUG): Disabling WDT. 2025-03-10T18:57:53.539Z,1741633073.539 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-10T18:57:53.539Z,1741633073.539 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-10T18:57:53.540Z,1741633073.540 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.572Z,1741633073.572 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.577Z,1741633073.577 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.584Z,1741633073.584 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.629Z,1741633073.629 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.670Z,1741633073.670 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-10T18:57:53.670Z,1741633073.670 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-10T18:57:53.686Z,1741633073.686 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.690Z,1741633073.690 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.754Z,1741633073.754 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:57:53.803Z,1741633073.803 [logger ThreadHandler](INFO): Thread cancelled.