2025-04-21T17:22:02.121Z,1745256122.121 [Supervisor](DEBUG): Initializing supervisor. 2025-04-21T17:22:02.127Z,1745256122.127 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-04-21T17:22:02.127Z,1745256122.127 [SyncHandler](INFO): Protected caller Thread ID is 7938 2025-04-21T17:22:02.128Z,1745256122.128 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-04-21T17:22:02.129Z,1745256122.129 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-04-21T17:22:02.129Z,1745256122.129 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7939 2025-04-21T17:22:02.134Z,1745256122.134 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-04-21T17:22:02.155Z,1745256122.155 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-04-21T17:22:02.156Z,1745256122.156 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-04-21T17:22:02.156Z,1745256122.156 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7940 2025-04-21T17:22:02.160Z,1745256122.160 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-04-21T17:22:02.161Z,1745256122.161 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-04-21T17:22:02.162Z,1745256122.162 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7941 2025-04-21T17:22:02.164Z,1745256122.164 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-04-21T17:22:02.165Z,1745256122.165 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-04-21T17:22:02.165Z,1745256122.165 [logger ThreadHandler](INFO): Protected caller Thread ID is 7942 2025-04-21T17:22:02.169Z,1745256122.169 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-04-21T17:22:02.170Z,1745256122.170 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-04-21T17:22:02.171Z,1745256122.171 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-04-21T17:22:02.694Z,1745256122.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-04-21T17:22:02.695Z,1745256122.695 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-04-21T17:22:02.933Z,1745256122.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-04-21T17:22:02.934Z,1745256122.934 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-04-21T17:22:03.031Z,1745256123.031 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-04-21T17:22:03.298Z,1745256123.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-04-21T17:22:03.298Z,1745256123.298 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-04-21T17:22:03.391Z,1745256123.391 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-04-21T17:22:03.504Z,1745256123.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-04-21T17:22:03.504Z,1745256123.504 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-04-21T17:22:03.897Z,1745256123.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-04-21T17:22:03.898Z,1745256123.898 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-04-21T17:22:04.050Z,1745256124.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-04-21T17:22:04.051Z,1745256124.051 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-04-21T17:22:04.644Z,1745256124.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-04-21T17:22:04.645Z,1745256124.645 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-04-21T17:22:04.857Z,1745256124.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-04-21T17:22:04.858Z,1745256124.858 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-04-21T17:22:05.319Z,1745256125.319 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-04-21T17:22:05.320Z,1745256125.320 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-04-21T17:22:05.636Z,1745256125.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-04-21T17:22:05.637Z,1745256125.637 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-04-21T17:22:05.763Z,1745256125.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-04-21T17:22:05.764Z,1745256125.764 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-04-21T17:22:06.758Z,1745256126.758 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-04-21T17:22:06.758Z,1745256126.758 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-04-21T17:22:07.606Z,1745256127.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-04-21T17:22:07.607Z,1745256127.607 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-04-21T17:22:07.877Z,1745256127.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-04-21T17:22:07.879Z,1745256127.879 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2025-04-21T17:22:07.880Z,1745256127.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2025-04-21T17:22:08.020Z,1745256128.020 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2025-04-21T17:22:08.119Z,1745256128.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2025-04-21T17:22:08.282Z,1745256128.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2025-04-21T17:22:08.384Z,1745256128.384 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2025-04-21T17:22:08.674Z,1745256128.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-04-21T17:22:08.675Z,1745256128.675 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2025-04-21T17:22:08.800Z,1745256128.800 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2025-04-21T17:22:08.927Z,1745256128.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2025-04-21T17:22:09.046Z,1745256129.046 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2025-04-21T17:22:09.222Z,1745256129.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2025-04-21T17:22:09.339Z,1745256129.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2025-04-21T17:22:09.443Z,1745256129.443 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2025-04-21T17:22:09.443Z,1745256129.443 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-04-21T17:22:09.450Z,1745256129.450 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-04-21T17:22:09.462Z,1745256129.462 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-04-21T17:22:09.462Z,1745256129.462 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-04-21T17:22:09.602Z,1745256129.602 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-04-21T17:22:09.603Z,1745256129.603 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-04-21T17:22:09.659Z,1745256129.659 [VerticalControl](DEBUG): Construct VerticalControl. 2025-04-21T17:22:09.721Z,1745256129.721 [VerticalControl] Loaded 2025-04-21T17:22:09.721Z,1745256129.721 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-04-21T17:22:09.724Z,1745256129.724 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-04-21T17:22:09.772Z,1745256129.772 [HorizontalControl] Loaded 2025-04-21T17:22:09.772Z,1745256129.772 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-04-21T17:22:09.774Z,1745256129.774 [SpeedControl](DEBUG): Construct SpeedControl. 2025-04-21T17:22:09.778Z,1745256129.778 [SpeedControl] Loaded 2025-04-21T17:22:09.778Z,1745256129.778 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-04-21T17:22:09.780Z,1745256129.780 [LoopControl](DEBUG): Construct LoopControl. 2025-04-21T17:22:09.781Z,1745256129.781 [LoopControl] Loaded 2025-04-21T17:22:09.781Z,1745256129.781 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-04-21T17:22:09.782Z,1745256129.782 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-04-21T17:22:09.782Z,1745256129.782 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-04-21T17:22:09.949Z,1745256129.949 [BuoyancyServo] Loaded 2025-04-21T17:22:09.949Z,1745256129.949 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-04-21T17:22:09.971Z,1745256129.971 [ElevatorServo] Loaded 2025-04-21T17:22:09.972Z,1745256129.972 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-04-21T17:22:09.994Z,1745256129.994 [MassServo] Loaded 2025-04-21T17:22:09.994Z,1745256129.994 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-04-21T17:22:10.016Z,1745256130.016 [RudderServo] Loaded 2025-04-21T17:22:10.016Z,1745256130.016 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-04-21T17:22:10.033Z,1745256130.033 [ThrusterHE] Loaded 2025-04-21T17:22:10.033Z,1745256130.033 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-04-21T17:22:10.034Z,1745256130.034 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-04-21T17:22:10.034Z,1745256130.034 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-04-21T17:22:10.088Z,1745256130.088 [DepthRateCalculator] Loaded 2025-04-21T17:22:10.088Z,1745256130.088 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-04-21T17:22:10.094Z,1745256130.094 [PitchRateCalculator] Loaded 2025-04-21T17:22:10.094Z,1745256130.094 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-04-21T17:22:10.104Z,1745256130.104 [SpeedCalculator] Loaded 2025-04-21T17:22:10.104Z,1745256130.104 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-04-21T17:22:10.108Z,1745256130.108 [YawRateCalculator] Loaded 2025-04-21T17:22:10.109Z,1745256130.109 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-04-21T17:22:10.127Z,1745256130.127 [ElevatorOffsetCalculator] Loaded 2025-04-21T17:22:10.127Z,1745256130.127 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-04-21T17:22:10.128Z,1745256130.128 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-04-21T17:22:10.128Z,1745256130.128 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-04-21T17:22:10.246Z,1745256130.246 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-04-21T17:22:10.247Z,1745256130.247 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-04-21T17:22:10.261Z,1745256130.261 [NavChart] Loaded 2025-04-21T17:22:10.261Z,1745256130.261 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-04-21T17:22:10.267Z,1745256130.267 [UniversalFixResidualReporter] Loaded 2025-04-21T17:22:10.267Z,1745256130.267 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-04-21T17:22:10.267Z,1745256130.267 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-04-21T17:22:10.268Z,1745256130.268 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-04-21T17:22:10.770Z,1745256130.770 [AHRS_M2] Loaded 2025-04-21T17:22:10.771Z,1745256130.771 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-04-21T17:22:10.810Z,1745256130.810 [BackseatComponent] Loaded 2025-04-21T17:22:10.811Z,1745256130.811 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-04-21T17:22:10.812Z,1745256130.812 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409294E0 2025-04-21T17:22:10.812Z,1745256130.812 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8024 2025-04-21T17:22:10.815Z,1745256130.815 [LcmUniversalReporter] Loaded 2025-04-21T17:22:10.815Z,1745256130.815 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-04-21T17:22:10.822Z,1745256130.822 [BioacousticsDataBridge] Loaded 2025-04-21T17:22:10.822Z,1745256130.822 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2025-04-21T17:22:11.661Z,1745256131.661 [BPC1] Loaded 2025-04-21T17:22:11.661Z,1745256131.661 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-04-21T17:22:11.734Z,1745256131.734 [DataOverHttps] Loaded 2025-04-21T17:22:11.735Z,1745256131.735 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-04-21T17:22:11.736Z,1745256131.736 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409594E0 2025-04-21T17:22:11.736Z,1745256131.736 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8025 2025-04-21T17:22:11.760Z,1745256131.760 [Depth_Keller] Loaded 2025-04-21T17:22:11.760Z,1745256131.760 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-04-21T17:22:11.765Z,1745256131.765 [DropWeight] Loaded 2025-04-21T17:22:11.765Z,1745256131.765 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-04-21T17:22:11.831Z,1745256131.831 [NAL9602] Loaded 2025-04-21T17:22:11.832Z,1745256131.832 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-04-21T17:22:11.862Z,1745256131.862 [Onboard] Loaded 2025-04-21T17:22:11.862Z,1745256131.862 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-04-21T17:22:11.863Z,1745256131.863 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409894E0 2025-04-21T17:22:11.864Z,1745256131.864 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8026 2025-04-21T17:22:11.879Z,1745256131.879 [Power24vConverter] Loaded 2025-04-21T17:22:11.879Z,1745256131.879 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-04-21T17:22:11.895Z,1745256131.895 [Radio_Surface] Loaded 2025-04-21T17:22:11.896Z,1745256131.896 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-04-21T17:22:11.897Z,1745256131.897 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0 2025-04-21T17:22:11.897Z,1745256131.897 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8027 2025-04-21T17:22:11.913Z,1745256131.913 [Sonardyne_Nano] Loaded 2025-04-21T17:22:11.914Z,1745256131.914 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-04-21T17:22:11.914Z,1745256131.914 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-04-21T17:22:11.915Z,1745256131.915 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-04-21T17:22:12.100Z,1745256132.100 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-04-21T17:22:12.101Z,1745256132.101 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-04-21T17:22:12.567Z,1745256132.567 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-04-21T17:22:12.568Z,1745256132.568 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-04-21T17:22:12.598Z,1745256132.598 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-04-21T17:22:12.599Z,1745256132.599 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-04-21T17:22:12.772Z,1745256132.772 [CTD_Seabird] Loaded 2025-04-21T17:22:12.772Z,1745256132.772 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-04-21T17:22:12.773Z,1745256132.773 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AFE4E0 2025-04-21T17:22:12.773Z,1745256132.773 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8028 2025-04-21T17:22:12.797Z,1745256132.797 [PAR_Licor] Loaded 2025-04-21T17:22:12.798Z,1745256132.798 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-04-21T17:22:12.842Z,1745256132.842 [WetLabsBB2FL] Loaded 2025-04-21T17:22:12.842Z,1745256132.842 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-04-21T17:22:12.843Z,1745256132.843 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B2E4E0 2025-04-21T17:22:12.844Z,1745256132.844 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8029 2025-04-21T17:22:12.844Z,1745256132.844 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-04-21T17:22:12.845Z,1745256132.845 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-04-21T17:22:13.212Z,1745256133.212 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-04-21T17:22:13.213Z,1745256133.213 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-04-21T17:22:13.383Z,1745256133.383 [SBIT](DEBUG): Construct Startup Built In Test. 2025-04-21T17:22:13.392Z,1745256133.392 [SBIT] Loaded 2025-04-21T17:22:13.393Z,1745256133.393 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-04-21T17:22:13.396Z,1745256133.396 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-04-21T17:22:13.409Z,1745256133.409 [IBIT] Loaded 2025-04-21T17:22:13.409Z,1745256133.409 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-04-21T17:22:13.414Z,1745256133.414 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-04-21T17:22:13.496Z,1745256133.496 [CBIT] Loaded 2025-04-21T17:22:13.496Z,1745256133.496 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-04-21T17:22:13.529Z,1745256133.529 [GFScanner] Loaded 2025-04-21T17:22:13.529Z,1745256133.529 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-04-21T17:22:13.530Z,1745256133.530 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-04-21T17:22:13.536Z,1745256133.536 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-04-21T17:22:13.539Z,1745256133.539 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-04-21T17:22:13.550Z,1745256133.550 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-04-21T17:22:13.551Z,1745256133.551 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-04-21T17:22:13.552Z,1745256133.552 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8030 2025-04-21T17:22:13.556Z,1745256133.556 [Supervisor](INFO): Main Thread ID is 7110 2025-04-21T17:22:13.556Z,1745256133.556 [Supervisor](DEBUG): Running supervisor. 2025-04-21T17:22:13.557Z,1745256133.557 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8031 2025-04-21T17:22:13.557Z,1745256133.557 [CommandExec](INFO): Initializing the command executive. 2025-04-21T17:22:13.559Z,1745256133.559 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8032 2025-04-21T17:22:13.561Z,1745256133.561 [controlThread ThreadHandler](INFO): Handler Thread ID is 8033 2025-04-21T17:22:13.562Z,1745256133.562 [controlThread](DEBUG): Initializing ControlThread 2025-04-21T17:22:13.563Z,1745256133.563 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-04-21T17:22:13.564Z,1745256133.564 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-04-21T17:22:13.565Z,1745256133.565 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-04-21T17:22:13.566Z,1745256133.566 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-04-21T17:22:13.567Z,1745256133.567 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-04-21T17:22:13.568Z,1745256133.568 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-04-21T17:22:13.568Z,1745256133.568 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-04-21T17:22:13.568Z,1745256133.568 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-04-21T17:22:13.569Z,1745256133.569 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-04-21T17:22:13.570Z,1745256133.570 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-04-21T17:22:13.570Z,1745256133.570 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-04-21T17:22:13.577Z,1745256133.577 [SBIT](INFO): Initialize SBIT Component. 2025-04-21T17:22:13.578Z,1745256133.578 [SBIT](IMPORTANT): git: 2025-04-15 2025-04-21T17:22:13.578Z,1745256133.578 [SBIT](INFO): git hash: e5e9d680ffe788b716b2397065cfa67465e27e09 2025-04-21T17:22:13.578Z,1745256133.578 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-04-21T17:22:13.579Z,1745256133.579 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-04-21T17:22:13.580Z,1745256133.580 [SBIT](INFO): Beginning SBIT in 122.000000 seconds. 2025-04-21T17:22:13.581Z,1745256133.581 [IBIT](INFO): Initialize IBIT Component. 2025-04-21T17:22:13.582Z,1745256133.582 [CBIT](DEBUG): Initialize CBIT Component. 2025-04-21T17:22:13.583Z,1745256133.583 [logger ThreadHandler](INFO): Handler Thread ID is 8034 2025-04-21T17:22:13.593Z,1745256133.593 [CBIT](DEBUG): Initialized mux pins. 2025-04-21T17:22:13.593Z,1745256133.593 [CBIT](DEBUG): Initializing the watchdog timer. 2025-04-21T17:22:13.601Z,1745256133.601 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8035 2025-04-21T17:22:13.613Z,1745256133.613 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8036 2025-04-21T17:22:13.615Z,1745256133.615 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-04-21T17:22:13.617Z,1745256133.617 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-04-21T17:22:13.618Z,1745256133.618 [CBIT](DEBUG): Initializing heartbeat. 2025-04-21T17:22:13.625Z,1745256133.625 [Onboard ThreadHandler](INFO): Handler Thread ID is 8037 2025-04-21T17:22:13.643Z,1745256133.643 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8038 2025-04-21T17:22:13.662Z,1745256133.662 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8039 2025-04-21T17:22:13.663Z,1745256133.663 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-04-21T17:22:13.666Z,1745256133.666 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8041 2025-04-21T17:22:13.669Z,1745256133.669 [WetLabsBB2FL](INFO): Powering up 2025-04-21T17:22:13.671Z,1745256133.671 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8043 2025-04-21T17:22:13.674Z,1745256133.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000 2025-04-21T17:22:13.674Z,1745256133.674 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000 2025-04-21T17:22:13.674Z,1745256133.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000 2025-04-21T17:22:13.674Z,1745256133.674 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000 2025-04-21T17:22:13.675Z,1745256133.675 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000 2025-04-21T17:22:13.675Z,1745256133.675 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000 2025-04-21T17:22:13.675Z,1745256133.675 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000 2025-04-21T17:22:13.675Z,1745256133.675 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000 2025-04-21T17:22:13.675Z,1745256133.675 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000 2025-04-21T17:22:13.675Z,1745256133.675 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000 2025-04-21T17:22:13.676Z,1745256133.676 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000 2025-04-21T17:22:13.676Z,1745256133.676 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000 2025-04-21T17:22:13.676Z,1745256133.676 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000 2025-04-21T17:22:13.676Z,1745256133.676 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000 2025-04-21T17:22:13.676Z,1745256133.676 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000 2025-04-21T17:22:13.676Z,1745256133.676 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000 2025-04-21T17:22:13.677Z,1745256133.677 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000 2025-04-21T17:22:13.677Z,1745256133.677 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000 2025-04-21T17:22:13.678Z,1745256133.678 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000 2025-04-21T17:22:13.678Z,1745256133.678 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000 2025-04-21T17:22:13.689Z,1745256133.689 [CBIT](DEBUG): Deactivating emergency mode. 2025-04-21T17:22:13.729Z,1745256133.729 [CBIT](DEBUG): Backplane powered. 2025-04-21T17:22:13.729Z,1745256133.729 [GFScanner](DEBUG): Initializing GFScanner 2025-04-21T17:22:13.729Z,1745256133.729 [GFScanner](DEBUG): Deactivating GF circuits. 2025-04-21T17:22:13.734Z,1745256133.734 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-04-21T17:22:13.735Z,1745256133.735 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-04-21T17:22:13.735Z,1745256133.735 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-04-21T17:22:13.736Z,1745256133.736 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-04-21T17:22:13.794Z,1745256133.794 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-04-21T17:22:13.814Z,1745256133.814 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-21T17:22:13.838Z,1745256133.838 [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-04-21T17:22:13.839Z,1745256133.839 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-04-21T17:22:13.839Z,1745256133.839 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-04-21T17:22:13.840Z,1745256133.840 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-04-21T17:22:14.049Z,1745256134.049 [Radio_Surface](INFO): Powering up 2025-04-21T17:22:14.191Z,1745256134.191 [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-04-21T17:22:14.219Z,1745256134.219 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-04-21T17:22:14.220Z,1745256134.220 [Default:A.Wait](DEBUG): Construct Wait. 2025-04-21T17:22:14.222Z,1745256134.222 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-21T17:22:14.265Z,1745256134.265 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-04-21T17:22:14.268Z,1745256134.268 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-04-21T17:22:14.283Z,1745256134.283 [Default:E.Execute](DEBUG): Construct Execute. 2025-04-21T17:22:14.308Z,1745256134.308 [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-04-21T17:22:14.325Z,1745256134.325 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,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-04-21T17:22:14.337Z,1745256134.337 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-04-21T17:22:14.397Z,1745256134.397 [Depth_Keller](INFO): Initializing. 2025-04-21T17:22:14.399Z,1745256134.399 [Power24vConverter](INFO): Powering up. 2025-04-21T17:22:14.399Z,1745256134.399 [Sonardyne_Nano](INFO): Initializing. 2025-04-21T17:22:14.437Z,1745256134.437 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-21T17:22:14.464Z,1745256134.464 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-21T17:22:14.469Z,1745256134.469 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-21T17:22:14.470Z,1745256134.470 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-04-21T17:22:14.477Z,1745256134.477 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-04-21T17:22:14.478Z,1745256134.478 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-21T17:22:14.485Z,1745256134.485 [MassServo](DEBUG): Initializing MassServo. 2025-04-21T17:22:14.486Z,1745256134.486 [RudderServo](DEBUG): Initializing EZServoServo. 2025-04-21T17:22:14.494Z,1745256134.494 [RudderServo](DEBUG): Initializing RudderServo. 2025-04-21T17:22:14.495Z,1745256134.495 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-04-21T17:22:14.501Z,1745256134.501 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-04-21T17:22:14.800Z,1745256134.800 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-21T17:22:14.800Z,1745256134.800 [DropWeight] Hardware Fault, FailCount= 1 2025-04-21T17:22:14.800Z,1745256134.800 [DropWeight](ERROR): Hardware Fault 2025-04-21T17:22:14.828Z,1745256134.828 [CommandExec](FAULT): Scheduling is paused 2025-04-21T17:22:14.828Z,1745256134.828 [CBIT](INFO): Critical error at 20250421T172214 2025-04-21T17:22:14.828Z,1745256134.828 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-04-21T17:22:14.839Z,1745256134.839 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-21T17:22:14.840Z,1745256134.840 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-21T17:22:15.162Z,1745256135.162 [CBIT](INFO): Critical error at 20250421T172214 2025-04-21T17:22:16.281Z,1745256136.281 [WetLabsBB2FL](INFO): Powering down 2025-04-21T17:22:16.355Z,1745256136.355 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-04-21T17:22:17.641Z,1745256137.641 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-21T17:22:20.693Z,1745256140.693 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-04-21T17:22:42.215Z,1745256162.215 [NAL9602](INFO): Powering up NAL9602 2025-04-21T17:22:53.127Z,1745256173.127 [NAL9602](INFO): NAL9602 initialized 2025-04-21T17:23:09.486Z,1745256189.486 [CommandExec](IMPORTANT): got command failComponent 2025-04-21T17:23:09.486Z,1745256189.486 [CommandExec](IMPORTANT): Failed components: 2025-04-21T17:23:09.486Z,1745256189.486 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2025-04-21T17:23:15.102Z,1745256195.102 [CommandExec](IMPORTANT): got command show stack 2025-04-21T17:23:15.102Z,1745256195.102 [CommandExec](IMPORTANT): Behavior Stack: 2025-04-21T17:23:15.103Z,1745256195.103 [MissionManager](IMPORTANT): Mission loaded, but not running. 2025-04-21T17:23:44.831Z,1745256224.831 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T17:24:02.607Z,1745256242.607 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T17:24:16.363Z,1745256256.363 [SBIT](IMPORTANT): Beginning Startup BIT 2025-04-21T17:24:16.368Z,1745256256.368 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-21T17:24:20.921Z,1745256260.921 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004566 CHAN A1 (24V): -0.007920 CHAN A2 (12V): -0.005146 CHAN A3 (5V): -0.003293 CHAN B0 (3.3V): -0.001697 CHAN B1 (3.15aV): -0.001020 CHAN B2 (3.15bV): -0.001064 CHAN B3 (GND): 0.000006 OPEN: 0.005094 Full Scale: +/- 1 mA 2025-04-21T17:24:21.191Z,1745256261.191 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T17:24:39.435Z,1745256279.435 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T17:24:41.699Z,1745256281.699 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004541 2025-04-21T17:24:57.211Z,1745256297.211 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T17:25:10.564Z,1745256310.564 [SBIT](IMPORTANT): SBIT PASSED 2025-04-21T17:25:10.564Z,1745256310.564 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-04-21T17:25:10.565Z,1745256310.565 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count; 2025-04-21T17:25:10.565Z,1745256310.565 [SBIT](IMPORTANT): BackseatComponent.missionCritical=0 bool; 2025-04-21T17:25:10.565Z,1745256310.565 [SBIT](IMPORTANT): ElevatorServo.loadAtStartup=1 bool; 2025-04-21T17:25:10.565Z,1745256310.565 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.750000 m/s; 2025-04-21T17:25:10.565Z,1745256310.565 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=161.274998 cubic_centimeter; 2025-04-21T17:25:10.565Z,1745256310.565 [SBIT](IMPORTANT): VerticalControl.massDefault=25.748476 millimeter; 2025-04-21T17:25:10.974Z,1745256310.974 [MissionManager](IMPORTANT): Started mission Startup 2025-04-21T17:25:10.974Z,1745256310.974 [Startup] Running Loop=1 2025-04-21T17:25:10.975Z,1745256310.975 [Startup](DEBUG): Aggregate::initialize Startup 2025-04-21T17:25:10.975Z,1745256310.975 [Startup:A.GoToSurface] Running Loop=1 2025-04-21T17:25:10.975Z,1745256310.975 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-21T17:25:10.975Z,1745256310.975 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-21T17:25:10.976Z,1745256310.976 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-21T17:25:10.976Z,1745256310.976 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-21T17:25:10.976Z,1745256310.976 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-21T17:25:10.977Z,1745256310.977 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-21T17:25:10.977Z,1745256310.977 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-21T17:25:10.979Z,1745256310.979 [Startup:StartupSatComms] Running Loop=1 2025-04-21T17:25:10.979Z,1745256310.979 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-04-21T17:25:10.979Z,1745256310.979 [Startup:StartupSatComms:A] Running Loop=1 2025-04-21T17:25:11.368Z,1745256311.368 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-04-21T17:25:13.840Z,1745256313.840 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-04-21T17:25:13.840Z,1745256313.840 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:25:13.973Z,1745256313.973 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:25:14.229Z,1745256314.229 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:25:14.229Z,1745256314.229 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-04-21T17:25:14.987Z,1745256314.987 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T17:25:32.763Z,1745256332.763 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T17:25:32.765Z,1745256332.765 [BPC1](ERROR): BPC1B: No match for serial number 0000 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-04-21T17:25:40.895Z,1745256340.895 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-04-21T17:25:50.944Z,1745256350.944 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T17:25:53.018Z,1745256353.018 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-04-21T17:26:11.183Z,1745256371.183 [Startup:StartupSatComms:A](INFO): Timed out from 2025-04-21T17:25:10.0Z 2025-04-21T17:26:11.183Z,1745256371.183 [Startup:StartupSatComms:A] Stopped 2025-04-21T17:26:11.183Z,1745256371.183 [Startup:StartupSatComms:B] Running Loop=1 2025-04-21T17:26:11.576Z,1745256371.576 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-21T17:26:17.952Z,1745256377.952 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250421T165946/Courier0007.lzma 2025-04-21T17:26:18.955Z,1745256378.955 [DataOverHttps](INFO): Moved sent file to Logs/20250421T165946/Courier0007.lzma.bak 2025-04-21T17:26:18.955Z,1745256378.955 [DataOverHttps](INFO): SBD MOMSN=24698723 2025-04-21T17:26:34.621Z,1745256394.621 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20250421T172202/Courier0000.lzma 2025-04-21T17:26:35.623Z,1745256395.623 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Courier0000.lzma.bak 2025-04-21T17:26:35.623Z,1745256395.623 [DataOverHttps](INFO): SBD MOMSN=24698725 2025-04-21T17:26:39.510Z,1745256399.510 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-04-21T17:26:47.813Z,1745256407.813 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.tl 2025-04-21T17:26:47.814Z,1745256407.814 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.tl 2025-04-21T17:26:47.814Z,1745256407.814 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Maintenance/run_backseat_on_surface.tl 2025-04-21T17:26:47.815Z,1745256407.815 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Maintenance/run_backseat_on_surface.tl 2025-04-21T17:26:48.348Z,1745256408.348 [MissionManager](DEBUG): TethyslAPI: ./Missions/Maintenance/run_backseat_on_surface.tl translated into: 0 Burn on Dropped weight due to communications timeout. 5 run_backseat_on_surface mission has been running for Restarting logs and run_backseat_on_surface mission. restart logs 2025-04-21T17:26:48.360Z,1745256408.360 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min 2025-04-21T17:26:48.360Z,1745256408.360 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-04-21T17:26:48.361Z,1745256408.361 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-04-21T17:26:48.394Z,1745256408.394 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-04-21T17:26:48.458Z,1745256408.458 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-04-21T17:26:48.474Z,1745256408.474 [MissionManager](INFO): DefineArg run_backseat_on_surface:BackseatDriver.EnableBackseat = 0 bool 2025-04-21T17:26:48.481Z,1745256408.481 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-04-21T17:26:48.490Z,1745256408.490 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-04-21T17:26:48.490Z,1745256408.490 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-04-21T17:26:48.491Z,1745256408.491 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-04-21T17:26:48.775Z,1745256408.775 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-04-21T17:26:48.796Z,1745256408.796 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleAll = 0 bool 2025-04-21T17:26:48.803Z,1745256408.803 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleLoad1 = 0 bool 2025-04-21T17:26:48.810Z,1745256408.810 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleLoad2 = 0 bool 2025-04-21T17:26:48.812Z,1745256408.812 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleLoad3 = 0 bool 2025-04-21T17:26:48.820Z,1745256408.820 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.EnabledPowerOnly = 0 bool 2025-04-21T17:26:48.895Z,1745256408.895 [run_backseat_on_surface:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-04-21T17:26:48.905Z,1745256408.905 [run_backseat_on_surface:D.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-21T17:26:48.950Z,1745256408.950 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-04-21T17:26:48.961Z,1745256408.961 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-04-21T17:26:48.985Z,1745256408.985 [run_backseat_on_surface:G.Execute](DEBUG): Construct Execute. 2025-04-21T17:26:48.998Z,1745256408.998 [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 run_backseat_on_surface { arguments { ElapsedSinceMissionStarted = 0 minute } # Power the science payload. # insert id="Science" Insert/Science.tl # Power the Backseat payload. insert Insert/BackseatDriver.tl assign in sequence BackseatDriver:EnableBackseat = true insert Insert/PowerOnly.tl 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=P2H { 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 ElapsedSinceMissionStarted = elapsed ( Universal:mission_started ) syslog important "run_backseat_on_surface mission has been running for " + ElapsedSinceMissionStarted~minute } syslog important "Restarting logs and run_backseat_on_surface mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2025-04-21T17:26:49.000Z,1745256409.000 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.tl 2025-04-21T17:26:52.165Z,1745256412.165 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20250421T165946/Express0008.lzma 2025-04-21T17:26:53.167Z,1745256413.167 [DataOverHttps](INFO): Moved sent file to Logs/20250421T165946/Express0008.lzma.bak 2025-04-21T17:26:53.167Z,1745256413.167 [DataOverHttps](INFO): SBD MOMSN=24698730 2025-04-21T17:27:09.920Z,1745256429.920 [CommandExec](IMPORTANT): got command report touch BackseatComponent.handled_message 2025-04-21T17:27:11.227Z,1745256431.227 [DataOverHttps](INFO): Sending 995 bytes from file Logs/20250421T172202/Express0001.lzma 2025-04-21T17:27:11.362Z,1745256431.362 [Startup:StartupSatComms:B](INFO): Timed out from 2025-04-21T17:26:11.2Z 2025-04-21T17:27:11.362Z,1745256431.362 [Startup:StartupSatComms:B] Stopped 2025-04-21T17:27:11.362Z,1745256431.362 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-04-21T17:27:11.362Z,1745256431.362 [Startup:StartupSatComms] Stopped 2025-04-21T17:27:11.362Z,1745256431.362 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-04-21T17:27:11.363Z,1745256431.363 [Startup](INFO): Completed Startup 2025-04-21T17:27:11.363Z,1745256431.363 [MissionManager](INFO): Startup is completed. 2025-04-21T17:27:11.363Z,1745256431.363 [MissionManager](INFO): Uninitializing Mission Startup 2025-04-21T17:27:11.363Z,1745256431.363 [Startup] Stopped 2025-04-21T17:27:11.364Z,1745256431.364 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-04-21T17:27:11.364Z,1745256431.364 [Startup:A.GoToSurface] Stopped 2025-04-21T17:27:11.364Z,1745256431.364 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-21T17:27:11.759Z,1745256431.759 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface 2025-04-21T17:27:11.759Z,1745256431.759 [run_backseat_on_surface] Running Loop=1 2025-04-21T17:27:11.759Z,1745256431.759 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface 2025-04-21T17:27:11.759Z,1745256431.759 [run_backseat_on_surface:BackseatDriver] Running Loop=1 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::initialize run_backseat_on_surface:BackseatDriver 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:PowerOnly] Running Loop=1 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:PowerOnly](DEBUG): Aggregate::initialize run_backseat_on_surface:PowerOnly 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:PowerOnly:A] Running Loop=1 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:PowerOnly:B] Running Loop=1 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:PowerOnly:C] Running Loop=1 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:PowerOnly:D] Running Loop=1 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:PowerOnly:E.Wait] Running Loop=1 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:D.GoToSurface] Running Loop=1 2025-04-21T17:27:11.760Z,1745256431.760 [run_backseat_on_surface:D.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-21T17:27:11.781Z,1745256431.781 [run_backseat_on_surface:D.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-21T17:27:11.781Z,1745256431.781 [run_backseat_on_surface:D.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-21T17:27:11.782Z,1745256431.782 [run_backseat_on_surface:D.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-21T17:27:11.782Z,1745256431.782 [run_backseat_on_surface:D.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-21T17:27:11.782Z,1745256431.782 [run_backseat_on_surface:D.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-21T17:27:11.783Z,1745256431.783 [run_backseat_on_surface:D.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-21T17:27:11.783Z,1745256431.783 [run_backseat_on_surface:B] Running Loop=1 2025-04-21T17:27:11.784Z,1745256431.784 [run_backseat_on_surface:PowerOnly] Running Loop=1 2025-04-21T17:27:11.784Z,1745256431.784 [run_backseat_on_surface:PowerOnly:D] Stopped 2025-04-21T17:27:11.784Z,1745256431.784 [run_backseat_on_surface:PowerOnly:C] Stopped 2025-04-21T17:27:11.784Z,1745256431.784 [run_backseat_on_surface:PowerOnly:B] Stopped 2025-04-21T17:27:11.785Z,1745256431.785 [run_backseat_on_surface:PowerOnly:A] Stopped 2025-04-21T17:27:11.790Z,1745256431.790 [run_backseat_on_surface:B] Stopped 2025-04-21T17:27:11.791Z,1745256431.791 [run_backseat_on_surface:BackseatDriver] Running Loop=1 2025-04-21T17:27:11.793Z,1745256431.793 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat 2025-04-21T17:27:11.845Z,1745256431.845 [BackseatComponent](INFO): Powering up 2025-04-21T17:27:11.846Z,1745256431.846 [BackseatComponent](INFO): Subscribing to LCM channels. 2025-04-21T17:27:12.164Z,1745256432.164 [run_backseat_on_surface:CheckIn] Running Loop=1 2025-04-21T17:27:12.164Z,1745256432.164 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn 2025-04-21T17:27:12.164Z,1745256432.164 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1 2025-04-21T17:27:12.227Z,1745256432.227 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Express0001.lzma.bak 2025-04-21T17:27:12.227Z,1745256432.227 [DataOverHttps](INFO): SBD MOMSN=24698735 2025-04-21T17:27:12.570Z,1745256432.570 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-21T17:27:23.520Z,1745256443.520 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-04-21T17:27:30.367Z,1745256450.367 [Reporter](INFO): BackseatComponent.handled_message 0 bool 2025-04-21T17:27:35.232Z,1745256455.232 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-04-21T17:27:38.469Z,1745256458.469 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-04-21T17:27:56.594Z,1745256476.594 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-21T17:28:14.796Z,1745256494.796 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-04-21T17:28:14.796Z,1745256494.796 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:28:14.827Z,1745256494.827 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:28:15.201Z,1745256495.201 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:28:15.201Z,1745256495.201 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-04-21T17:28:16.035Z,1745256496.035 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-04-21T17:28:31.339Z,1745256511.339 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 4. 2025-04-21T17:28:31.343Z,1745256511.343 [BPC1](INFO): Received data from all battery sticks. 2025-04-21T17:28:54.234Z,1745256534.234 [BackseatComponent](IMPORTANT): calanus: Running AyeRIS backseat app. Logging to: 20250421T172821 2025-04-21T17:28:54.260Z,1745256534.260 [BackseatComponent](IMPORTANT): 2025-04-21 17:28:54.144 | INFO | __main__::125 - $ELC,2025-04-21 17:28:52.927,25.040,106.990,0.00,23.57,16.74,23.57,0.16,0.90,12.12,1,0,0,500,1 2025-04-21T17:28:54.391Z,1745256534.391 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:12.160Z,1745256552.160 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.ayeris_disk_space with code: 1916 2025-04-21T17:29:12.163Z,1745256552.163 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.ayeris_particle_counts with code: 1917 2025-04-21T17:29:12.166Z,1745256552.166 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.ayeris_particle_shape with code: 1918 2025-04-21T17:29:12.168Z,1745256552.168 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.ayeris_particle_size with code: 1919 2025-04-21T17:29:12.171Z,1745256552.171 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.ayeris_particle_slope with code: 1920 2025-04-21T17:29:12.198Z,1745256552.198 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:15.811Z,1745256555.811 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:18.772Z,1745256558.772 [BackseatComponent](INFO): Created data reader for Depth_Keller.enableBroadcast with code: 1753 2025-04-21T17:29:18.773Z,1745256558.773 [BackseatComponent](INFO): Requesting data from Depth_Keller.enableBroadcast (1753). Requested size is: 1 2025-04-21T17:29:18.774Z,1745256558.774 [BackseatComponent](INFO): Created universal data reader for depth with code: 6 2025-04-21T17:29:18.774Z,1745256558.774 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 2 2025-04-21T17:29:19.008Z,1745256559.008 [Depth_Keller](ERROR): Failed to copy Depth_Keller.enableBroadcast's data value to LCM message. 2025-04-21T17:29:19.008Z,1745256559.008 [Depth_Keller](INFO): Activating messaging. 2025-04-21T17:29:19.084Z,1745256559.084 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:19.870Z,1745256559.870 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:23.926Z,1745256563.926 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:27.942Z,1745256567.942 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:29.148Z,1745256569.148 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:32.002Z,1745256572.002 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:34.402Z,1745256574.402 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:36.042Z,1745256576.042 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:39.676Z,1745256579.676 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:40.082Z,1745256580.082 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:44.111Z,1745256584.111 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:44.896Z,1745256584.896 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:48.163Z,1745256588.163 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:50.156Z,1745256590.156 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:52.182Z,1745256592.182 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:55.412Z,1745256595.412 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:56.243Z,1745256596.243 [Reporter](INFO): BackseatComponent.handled_message 1 bool 2025-04-21T17:29:56.642Z,1745256596.642 [CommandExec](IMPORTANT): got command stop 2025-04-21T17:29:56.642Z,1745256596.642 [CommandExec](IMPORTANT): Scheduling is paused 2025-04-21T17:29:56.642Z,1745256596.642 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-04-21T17:29:57.013Z,1745256597.013 [MissionManager](INFO): MissionManager is completed. 2025-04-21T17:29:57.013Z,1745256597.013 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface 2025-04-21T17:29:57.013Z,1745256597.013 [run_backseat_on_surface] Stopped 2025-04-21T17:29:57.013Z,1745256597.013 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface 2025-04-21T17:29:57.013Z,1745256597.013 [run_backseat_on_surface:BackseatDriver] Stopped 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::uninitialize run_backseat_on_surface:BackseatDriver 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Stopped 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:PowerOnly] Stopped 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:PowerOnly](DEBUG): Aggregate::uninitialize run_backseat_on_surface:PowerOnly 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:PowerOnly:E.Wait] Stopped 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:D.GoToSurface] Stopped 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:D.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:CheckIn] Stopped 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn 2025-04-21T17:29:57.014Z,1745256597.014 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped 2025-04-21T17:29:57.399Z,1745256597.399 [MissionManager](IMPORTANT): Started mission Default 2025-04-21T17:29:57.399Z,1745256597.399 [Default] Running Loop=1 2025-04-21T17:29:57.400Z,1745256597.400 [Default](DEBUG): Aggregate::initialize Default 2025-04-21T17:29:57.400Z,1745256597.400 [Default:B.GoToSurface] Running Loop=1 2025-04-21T17:29:57.400Z,1745256597.400 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-21T17:29:57.400Z,1745256597.400 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-21T17:29:57.400Z,1745256597.400 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-21T17:29:57.401Z,1745256597.401 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-21T17:29:57.401Z,1745256597.401 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-21T17:29:57.402Z,1745256597.402 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-21T17:29:57.402Z,1745256597.402 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-21T17:29:57.402Z,1745256597.402 [Default:A.Wait] Running Loop=1 2025-04-21T17:29:57.402Z,1745256597.402 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-21T17:29:57.876Z,1745256597.876 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-04-21T17:29:57.877Z,1745256597.877 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2025-04-21T17:30:10.739Z,1745256610.739 [Default:A.Wait](INFO): Done Waiting. 2025-04-21T17:30:10.739Z,1745256610.739 [Default:A.Wait] Stopped 2025-04-21T17:30:10.739Z,1745256610.739 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-21T17:30:11.134Z,1745256611.134 [Default:CheckIn] Running Loop=1 2025-04-21T17:30:11.134Z,1745256611.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-21T17:30:11.134Z,1745256611.134 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-21T17:30:11.540Z,1745256611.540 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-21T17:30:16.045Z,1745256616.045 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-04-21T17:30:16.046Z,1745256616.046 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-04-21T17:30:16.046Z,1745256616.046 [LcmUniversalReporter](INFO): Deactivating messaging. 2025-04-21T17:30:16.371Z,1745256616.371 [Depth_Keller](INFO): Deactivating messaging. 2025-04-21T17:31:15.785Z,1745256675.785 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-04-21T17:31:15.785Z,1745256675.785 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:31:15.804Z,1745256675.804 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:31:16.215Z,1745256676.215 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:31:16.215Z,1745256676.215 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-04-21T17:32:15.559Z,1745256735.559 [Power24vConverter](INFO): Powering down. 2025-04-21T17:32:55.962Z,1745256775.962 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-21T17:32:55.962Z,1745256775.962 [NAL9602] Data Fault, FailCount= 1 2025-04-21T17:32:55.962Z,1745256775.962 [NAL9602](ERROR): Data Fault 2025-04-21T17:32:55.980Z,1745256775.980 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-21T17:32:56.358Z,1745256776.358 [NAL9602](INFO): Powering down 2025-04-21T17:32:57.186Z,1745256777.186 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-21T17:32:57.186Z,1745256777.186 [NAL9602] No Fault, FailCount= 1 2025-04-21T17:33:26.666Z,1745256806.666 [NAL9602](INFO): Powering up NAL9602 2025-04-21T17:33:37.570Z,1745256817.570 [NAL9602](INFO): NAL9602 initialized 2025-04-21T17:34:16.786Z,1745256856.786 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-04-21T17:34:16.786Z,1745256856.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:34:16.797Z,1745256856.797 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:34:17.189Z,1745256857.189 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:34:17.189Z,1745256857.189 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-04-21T17:35:11.314Z,1745256911.314 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-21T17:30:11.1Z 2025-04-21T17:35:11.315Z,1745256911.315 [Default:CheckIn:Read_GPS] Stopped 2025-04-21T17:35:11.315Z,1745256911.315 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-21T17:35:11.711Z,1745256911.711 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-21T17:35:18.697Z,1745256918.697 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250421T172202/Courier0004.lzma 2025-04-21T17:35:19.700Z,1745256919.700 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Courier0004.lzma.bak 2025-04-21T17:35:19.700Z,1745256919.700 [DataOverHttps](INFO): SBD MOMSN=24698839 2025-04-21T17:35:35.194Z,1745256935.194 [DataOverHttps](INFO): Sending 528 bytes from file Logs/20250421T172202/Express0005.lzma 2025-04-21T17:35:36.195Z,1745256936.195 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Express0005.lzma.bak 2025-04-21T17:35:36.195Z,1745256936.195 [DataOverHttps](INFO): SBD MOMSN=24698846 2025-04-21T17:35:37.589Z,1745256937.589 [Default:CheckIn:Read_Iridium] Stopped 2025-04-21T17:35:37.590Z,1745256937.590 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-21T17:35:37.590Z,1745256937.590 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-21T17:37:14.942Z,1745257034.942 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-21T17:37:14.942Z,1745257034.942 [DropWeight] No Fault, FailCount= 1 2025-04-21T17:37:17.757Z,1745257037.757 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-04-21T17:37:17.757Z,1745257037.757 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:37:17.768Z,1745257037.768 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:37:18.171Z,1745257038.171 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:37:18.172Z,1745257038.172 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-04-21T17:40:18.753Z,1745257218.753 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2025-04-21T17:40:18.753Z,1745257218.753 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:40:18.764Z,1745257218.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:40:19.173Z,1745257219.173 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:40:19.173Z,1745257219.173 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2025-04-21T17:40:38.163Z,1745257238.163 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-21T17:40:38.164Z,1745257238.164 [Default:CheckIn:C.Wait] Stopped 2025-04-21T17:40:38.164Z,1745257238.164 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-21T17:40:38.164Z,1745257238.164 [Default:CheckIn:D] Running Loop=1 2025-04-21T17:40:38.553Z,1745257238.553 [Default:CheckIn:D] Stopped 2025-04-21T17:40:38.554Z,1745257238.554 [Default:CheckIn:E] Running Loop=1 2025-04-21T17:40:38.946Z,1745257238.946 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.685898 min 2025-04-21T17:40:38.947Z,1745257238.947 [Default:CheckIn:E] Stopped 2025-04-21T17:40:38.947Z,1745257238.947 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-21T17:40:38.947Z,1745257238.947 [Default:CheckIn] Stopped 2025-04-21T17:40:38.947Z,1745257238.947 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-21T17:40:38.947Z,1745257238.947 [Default:CheckIn](INFO): Running loop #2 2025-04-21T17:40:38.947Z,1745257238.947 [Default:CheckIn] Running Loop=2 2025-04-21T17:40:38.947Z,1745257238.947 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-21T17:40:38.947Z,1745257238.947 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-21T17:43:19.660Z,1745257399.660 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2025-04-21T17:43:19.660Z,1745257399.660 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:43:19.675Z,1745257399.675 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:43:20.062Z,1745257400.062 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:43:20.062Z,1745257400.062 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2025-04-21T17:43:38.616Z,1745257418.616 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-21T17:43:38.616Z,1745257418.616 [NAL9602] Data Fault, FailCount= 2 2025-04-21T17:43:38.616Z,1745257418.616 [NAL9602](ERROR): Data Fault 2025-04-21T17:43:38.653Z,1745257418.653 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-21T17:43:39.025Z,1745257419.025 [NAL9602](INFO): Powering down 2025-04-21T17:43:39.846Z,1745257419.846 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-21T17:43:39.847Z,1745257419.847 [NAL9602] No Fault, FailCount= 2 2025-04-21T17:44:09.328Z,1745257449.328 [NAL9602](INFO): Powering up NAL9602 2025-04-21T17:44:20.233Z,1745257460.233 [NAL9602](INFO): NAL9602 initialized 2025-04-21T17:45:39.021Z,1745257539.021 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-21T17:40:38.9Z 2025-04-21T17:45:39.021Z,1745257539.021 [Default:CheckIn:Read_GPS] Stopped 2025-04-21T17:45:39.021Z,1745257539.021 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-21T17:45:43.973Z,1745257543.973 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250421T172202/Courier0007.lzma 2025-04-21T17:45:44.974Z,1745257544.974 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Courier0007.lzma.bak 2025-04-21T17:45:44.974Z,1745257544.974 [DataOverHttps](INFO): SBD MOMSN=24699092 2025-04-21T17:46:00.484Z,1745257560.484 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20250421T172202/Express0008.lzma 2025-04-21T17:46:01.486Z,1745257561.486 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Express0008.lzma.bak 2025-04-21T17:46:01.486Z,1745257561.486 [DataOverHttps](INFO): SBD MOMSN=24699099 2025-04-21T17:46:02.861Z,1745257562.861 [Default:CheckIn:Read_Iridium] Stopped 2025-04-21T17:46:02.861Z,1745257562.861 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-21T17:46:02.861Z,1745257562.861 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-21T17:46:20.652Z,1745257580.652 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2025-04-21T17:46:20.652Z,1745257580.652 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:46:20.663Z,1745257580.663 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:46:21.123Z,1745257581.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:46:21.123Z,1745257581.123 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2025-04-21T17:49:21.618Z,1745257761.618 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2025-04-21T17:49:21.619Z,1745257761.619 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:49:21.629Z,1745257761.629 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:49:22.038Z,1745257762.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:49:22.038Z,1745257762.038 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2025-04-21T17:51:03.441Z,1745257863.441 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-21T17:51:03.441Z,1745257863.441 [Default:CheckIn:C.Wait] Stopped 2025-04-21T17:51:03.441Z,1745257863.441 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-21T17:51:03.442Z,1745257863.442 [Default:CheckIn:D] Running Loop=1 2025-04-21T17:51:03.837Z,1745257863.837 [Default:CheckIn:D] Stopped 2025-04-21T17:51:03.837Z,1745257863.837 [Default:CheckIn:E] Running Loop=1 2025-04-21T17:51:04.258Z,1745257864.258 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.107296 min 2025-04-21T17:51:04.258Z,1745257864.258 [Default:CheckIn:E] Stopped 2025-04-21T17:51:04.259Z,1745257864.259 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-21T17:51:04.259Z,1745257864.259 [Default:CheckIn] Stopped 2025-04-21T17:51:04.259Z,1745257864.259 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-21T17:51:04.259Z,1745257864.259 [Default:CheckIn](INFO): Running loop #3 2025-04-21T17:51:04.259Z,1745257864.259 [Default:CheckIn] Running Loop=3 2025-04-21T17:51:04.259Z,1745257864.259 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-21T17:51:04.259Z,1745257864.259 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-21T17:52:22.630Z,1745257942.630 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2025-04-21T17:52:22.630Z,1745257942.630 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:52:22.641Z,1745257942.641 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:52:23.053Z,1745257943.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:52:23.053Z,1745257943.053 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2025-04-21T17:52:48.617Z,1745257968.617 [CommandExec](IMPORTANT): got command gfscan 2025-04-21T17:52:48.904Z,1745257968.904 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-21T17:52:50.112Z,1745257970.112 [GFScanner](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2025-04-21T17:52:53.334Z,1745257973.334 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004245 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.005013 CHAN A3 (5V): -0.002976 CHAN B0 (3.3V): -0.000854 CHAN B1 (3.15aV): -0.001494 CHAN B2 (3.15bV): -0.000875 CHAN B3 (GND): -0.000147 OPEN: 0.004988 Full Scale: +/- 1 mA 2025-04-21T17:53:13.264Z,1745257993.264 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree 2025-04-21T17:53:13.268Z,1745257993.268 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread. 2025-04-21T17:53:13.557Z,1745257993.557 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-04-21T17:53:22.960Z,1745258002.960 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree 2025-04-21T17:53:31.637Z,1745258011.637 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0 degree 2025-04-21T17:53:58.108Z,1745258038.108 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree 2025-04-21T17:53:58.112Z,1745258038.112 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread. 2025-04-21T17:53:58.421Z,1745258038.421 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-04-21T17:54:07.795Z,1745258047.795 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree 2025-04-21T17:54:22.189Z,1745258062.189 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-21T17:54:22.189Z,1745258062.189 [NAL9602] Data Fault, FailCount= 3 2025-04-21T17:54:22.189Z,1745258062.189 [NAL9602](ERROR): Data Fault 2025-04-21T17:54:22.271Z,1745258062.271 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-21T17:54:22.593Z,1745258062.593 [NAL9602](INFO): Powering down 2025-04-21T17:54:23.463Z,1745258063.463 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-21T17:54:23.463Z,1745258063.463 [NAL9602] No Fault, FailCount= 3 2025-04-21T17:54:23.565Z,1745258063.565 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0 degree 2025-04-21T17:54:28.972Z,1745258068.972 [CommandExec](IMPORTANT): got command failComponent 2025-04-21T17:54:28.972Z,1745258068.972 [CommandExec](IMPORTANT): Failed components: 2025-04-21T17:54:28.973Z,1745258068.973 [CommandExec](IMPORTANT): No failed Components. 2025-04-21T17:54:33.033Z,1745258073.033 [CommandExec](IMPORTANT): got command gfscan 2025-04-21T17:54:33.119Z,1745258073.119 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-21T17:54:34.343Z,1745258074.343 [GFScanner](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2025-04-21T17:54:37.573Z,1745258077.573 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.003184 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.004599 CHAN A3 (5V): -0.002521 CHAN B0 (3.3V): -0.001195 CHAN B1 (3.15aV): -0.001123 CHAN B2 (3.15bV): -0.001229 CHAN B3 (GND): -0.000480 OPEN: 0.004803 Full Scale: +/- 1 mA 2025-04-21T17:54:47.965Z,1745258087.965 [CommandExec](IMPORTANT): got command configSet list 2025-04-21T17:54:47.965Z,1745258087.965 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-04-21T17:54:47.967Z,1745258087.967 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count; 2025-04-21T17:54:47.969Z,1745258087.969 [CommandExec](IMPORTANT): SpeedControl.rollOptimum=0.750000 m/s; 2025-04-21T17:54:47.970Z,1745258087.970 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=161.274998 cubic_centimeter; 2025-04-21T17:54:47.971Z,1745258087.971 [CommandExec](IMPORTANT): VerticalControl.massDefault=25.748476 millimeter; 2025-04-21T17:54:52.893Z,1745258092.893 [NAL9602](INFO): Powering up NAL9602 2025-04-21T17:55:03.805Z,1745258103.805 [NAL9602](INFO): NAL9602 initialized 2025-04-21T17:55:23.723Z,1745258123.723 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2025-04-21T17:55:23.723Z,1745258123.723 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:55:23.735Z,1745258123.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:55:24.146Z,1745258124.146 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:55:24.146Z,1745258124.146 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2025-04-21T17:56:04.531Z,1745258164.531 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-21T17:51:04.3Z 2025-04-21T17:56:04.531Z,1745258164.531 [Default:CheckIn:Read_GPS] Stopped 2025-04-21T17:56:04.532Z,1745258164.532 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-21T17:56:10.485Z,1745258170.485 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250421T172202/Courier0010.lzma 2025-04-21T17:56:11.487Z,1745258171.487 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Courier0010.lzma.bak 2025-04-21T17:56:11.488Z,1745258171.488 [DataOverHttps](INFO): SBD MOMSN=24699242 2025-04-21T17:56:26.967Z,1745258186.967 [DataOverHttps](INFO): Sending 733 bytes from file Logs/20250421T172202/Express0011.lzma 2025-04-21T17:56:27.968Z,1745258187.968 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Express0011.lzma.bak 2025-04-21T17:56:27.968Z,1745258187.968 [DataOverHttps](INFO): SBD MOMSN=24699251 2025-04-21T17:56:29.179Z,1745258189.179 [Default:CheckIn:Read_Iridium] Stopped 2025-04-21T17:56:29.179Z,1745258189.179 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-21T17:56:29.179Z,1745258189.179 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-21T17:58:24.710Z,1745258304.710 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2025-04-21T17:58:24.710Z,1745258304.710 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T17:58:24.722Z,1745258304.722 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T17:58:25.148Z,1745258305.148 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T17:58:25.149Z,1745258305.149 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2025-04-21T17:59:20.530Z,1745258360.530 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T17:59:21.534Z,1745258361.534 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251289 2025-04-21T17:59:55.670Z,1745258395.670 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T18:00:30.814Z,1745258430.814 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T18:01:05.954Z,1745258465.954 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T18:01:25.706Z,1745258485.706 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2025-04-21T18:01:25.706Z,1745258485.706 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:01:25.717Z,1745258485.717 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:01:26.129Z,1745258486.129 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:01:26.129Z,1745258486.129 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2025-04-21T18:01:29.742Z,1745258489.742 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-21T18:01:29.742Z,1745258489.742 [Default:CheckIn:C.Wait] Stopped 2025-04-21T18:01:29.742Z,1745258489.742 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-21T18:01:29.742Z,1745258489.742 [Default:CheckIn:D] Running Loop=1 2025-04-21T18:01:30.168Z,1745258490.168 [Default:CheckIn:D] Stopped 2025-04-21T18:01:30.168Z,1745258490.168 [Default:CheckIn:E] Running Loop=1 2025-04-21T18:01:30.580Z,1745258490.580 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.546136 min 2025-04-21T18:01:30.580Z,1745258490.580 [Default:CheckIn:E] Stopped 2025-04-21T18:01:30.580Z,1745258490.580 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-21T18:01:30.580Z,1745258490.580 [Default:CheckIn] Stopped 2025-04-21T18:01:30.580Z,1745258490.580 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-21T18:01:30.581Z,1745258490.581 [Default:CheckIn](INFO): Running loop #4 2025-04-21T18:01:30.581Z,1745258490.581 [Default:CheckIn] Running Loop=4 2025-04-21T18:01:30.581Z,1745258490.581 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-21T18:01:30.581Z,1745258490.581 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-21T18:01:41.102Z,1745258501.102 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T18:02:16.242Z,1745258536.242 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T18:02:28.534Z,1745258548.534 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002858 2025-04-21T18:04:26.723Z,1745258666.723 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2025-04-21T18:04:26.723Z,1745258666.723 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:04:26.734Z,1745258666.734 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:04:27.140Z,1745258667.140 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:04:27.140Z,1745258667.140 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2025-04-21T18:05:06.283Z,1745258706.283 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-21T18:05:06.283Z,1745258706.283 [NAL9602] Data Fault, FailCount= 4 2025-04-21T18:05:06.283Z,1745258706.283 [NAL9602](ERROR): Data Fault 2025-04-21T18:05:06.338Z,1745258706.338 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-21T18:05:06.687Z,1745258706.687 [NAL9602](INFO): Powering down 2025-04-21T18:05:07.516Z,1745258707.516 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-21T18:05:07.517Z,1745258707.517 [NAL9602] No Fault, FailCount= 4 2025-04-21T18:05:36.987Z,1745258736.987 [NAL9602](INFO): Powering up NAL9602 2025-04-21T18:05:47.895Z,1745258747.895 [NAL9602](INFO): NAL9602 initialized 2025-04-21T18:06:30.735Z,1745258790.735 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-21T18:01:30.6Z 2025-04-21T18:06:30.735Z,1745258790.735 [Default:CheckIn:Read_GPS] Stopped 2025-04-21T18:06:30.736Z,1745258790.736 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-21T18:06:36.789Z,1745258796.789 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250421T172202/Courier0013.lzma 2025-04-21T18:06:37.791Z,1745258797.791 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Courier0013.lzma.bak 2025-04-21T18:06:37.791Z,1745258797.791 [DataOverHttps](INFO): SBD MOMSN=24699412 2025-04-21T18:06:53.557Z,1745258813.557 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250421T172202/Express0014.lzma 2025-04-21T18:06:54.559Z,1745258814.559 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Express0014.lzma.bak 2025-04-21T18:06:54.559Z,1745258814.559 [DataOverHttps](INFO): SBD MOMSN=24699427 2025-04-21T18:06:55.702Z,1745258815.702 [Default:CheckIn:Read_Iridium] Stopped 2025-04-21T18:06:55.702Z,1745258815.702 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-21T18:06:55.702Z,1745258815.702 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-21T18:07:16.239Z,1745258836.239 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-21T18:07:16.239Z,1745258836.239 [DropWeight] Hardware Fault, FailCount= 1 2025-04-21T18:07:16.239Z,1745258836.239 [DropWeight](ERROR): Hardware Fault 2025-04-21T18:07:16.291Z,1745258836.291 [CommandExec](FAULT): Scheduling is paused 2025-04-21T18:07:16.291Z,1745258836.291 [CBIT](INFO): Critical error at 20250421T180716 2025-04-21T18:07:16.298Z,1745258836.298 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-21T18:07:16.298Z,1745258836.298 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-21T18:07:16.690Z,1745258836.690 [CBIT](INFO): Critical error at 20250421T180716 2025-04-21T18:07:27.558Z,1745258847.558 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2025-04-21T18:07:27.558Z,1745258847.558 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:07:27.600Z,1745258847.600 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:07:27.972Z,1745258847.972 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:07:27.972Z,1745258847.972 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2025-04-21T18:10:28.385Z,1745259028.385 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2025-04-21T18:10:28.385Z,1745259028.385 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:10:28.396Z,1745259028.396 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:10:28.811Z,1745259028.811 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:10:28.811Z,1745259028.811 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2025-04-21T18:11:26.707Z,1745259086.707 [CommandExec](IMPORTANT): got command strobe off 2025-04-21T18:11:26.707Z,1745259086.707 [CommandExec](IMPORTANT): Deactivating strobe 2025-04-21T18:11:56.074Z,1745259116.074 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-21T18:11:56.074Z,1745259116.074 [Default:CheckIn:C.Wait] Stopped 2025-04-21T18:11:56.074Z,1745259116.074 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-21T18:11:56.074Z,1745259116.074 [Default:CheckIn:D] Running Loop=1 2025-04-21T18:11:56.475Z,1745259116.475 [Default:CheckIn:D] Stopped 2025-04-21T18:11:56.475Z,1745259116.475 [Default:CheckIn:E] Running Loop=1 2025-04-21T18:11:56.890Z,1745259116.890 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.984595 min 2025-04-21T18:11:56.890Z,1745259116.890 [Default:CheckIn:E] Stopped 2025-04-21T18:11:56.890Z,1745259116.890 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-21T18:11:56.890Z,1745259116.890 [Default:CheckIn] Stopped 2025-04-21T18:11:56.890Z,1745259116.890 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-21T18:11:56.890Z,1745259116.890 [Default:CheckIn](INFO): Running loop #5 2025-04-21T18:11:56.890Z,1745259116.890 [Default:CheckIn] Running Loop=5 2025-04-21T18:11:56.891Z,1745259116.891 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-21T18:11:56.891Z,1745259116.891 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-21T18:13:29.421Z,1745259209.421 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2025-04-21T18:13:29.421Z,1745259209.421 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:13:29.440Z,1745259209.440 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:13:29.847Z,1745259209.847 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:13:29.847Z,1745259209.847 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2025-04-21T18:15:50.786Z,1745259350.786 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-21T18:15:50.787Z,1745259350.787 [NAL9602] Data Fault, FailCount= 5 2025-04-21T18:15:50.787Z,1745259350.787 [NAL9602](ERROR): Data Fault 2025-04-21T18:15:50.866Z,1745259350.866 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-21T18:15:50.866Z,1745259350.866 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-04-21T18:15:51.190Z,1745259351.190 [NAL9602](INFO): Powering down 2025-04-21T18:15:51.250Z,1745259351.250 [CBIT](INFO): Critical error at 20250421T181550 2025-04-21T18:16:30.388Z,1745259390.388 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2025-04-21T18:16:30.388Z,1745259390.388 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:16:30.400Z,1745259390.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:16:30.802Z,1745259390.802 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:16:30.803Z,1745259390.803 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2025-04-21T18:16:57.057Z,1745259417.057 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-21T18:11:56.9Z 2025-04-21T18:16:57.057Z,1745259417.057 [Default:CheckIn:Read_GPS] Stopped 2025-04-21T18:16:57.058Z,1745259417.058 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-21T18:17:03.565Z,1745259423.565 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20250421T172202/Courier0016.lzma 2025-04-21T18:17:04.567Z,1745259424.567 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Courier0016.lzma.bak 2025-04-21T18:17:04.567Z,1745259424.567 [DataOverHttps](INFO): SBD MOMSN=24699528 2025-04-21T18:17:20.001Z,1745259440.001 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20250421T172202/Express0017.lzma 2025-04-21T18:17:21.003Z,1745259441.003 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Express0017.lzma.bak 2025-04-21T18:17:21.003Z,1745259441.003 [DataOverHttps](INFO): SBD MOMSN=24699533 2025-04-21T18:17:22.141Z,1745259442.141 [Default:CheckIn:Read_Iridium] Stopped 2025-04-21T18:17:22.141Z,1745259442.141 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-21T18:17:22.141Z,1745259442.141 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-21T18:17:50.795Z,1745259470.795 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-21T18:17:50.795Z,1745259470.795 [NAL9602] No Fault, FailCount= 5 2025-04-21T18:17:51.178Z,1745259471.178 [NAL9602](INFO): Powering up NAL9602 2025-04-21T18:18:02.090Z,1745259482.090 [NAL9602](INFO): NAL9602 initialized 2025-04-21T18:19:31.415Z,1745259571.415 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2025-04-21T18:19:31.415Z,1745259571.415 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:19:31.426Z,1745259571.426 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:19:31.808Z,1745259571.808 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:19:31.808Z,1745259571.808 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2025-04-21T18:22:16.627Z,1745259736.627 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-21T18:22:16.627Z,1745259736.627 [DropWeight] No Fault, FailCount= 1 2025-04-21T18:22:22.674Z,1745259742.674 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-21T18:22:22.674Z,1745259742.674 [Default:CheckIn:C.Wait] Stopped 2025-04-21T18:22:22.674Z,1745259742.674 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-21T18:22:22.675Z,1745259742.675 [Default:CheckIn:D] Running Loop=1 2025-04-21T18:22:23.093Z,1745259743.093 [Default:CheckIn:D] Stopped 2025-04-21T18:22:23.093Z,1745259743.093 [Default:CheckIn:E] Running Loop=1 2025-04-21T18:22:23.482Z,1745259743.482 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.428227 min 2025-04-21T18:22:23.483Z,1745259743.483 [Default:CheckIn:E] Stopped 2025-04-21T18:22:23.483Z,1745259743.483 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-21T18:22:23.483Z,1745259743.483 [Default:CheckIn] Stopped 2025-04-21T18:22:23.483Z,1745259743.483 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-21T18:22:23.483Z,1745259743.483 [Default:CheckIn](INFO): Running loop #6 2025-04-21T18:22:23.483Z,1745259743.483 [Default:CheckIn] Running Loop=6 2025-04-21T18:22:23.483Z,1745259743.483 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-21T18:22:23.484Z,1745259743.484 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-21T18:22:32.359Z,1745259752.359 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2025-04-21T18:22:32.359Z,1745259752.359 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:22:32.370Z,1745259752.370 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:22:32.775Z,1745259752.775 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:22:32.775Z,1745259752.775 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2025-04-21T18:25:33.371Z,1745259933.371 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2025-04-21T18:25:33.371Z,1745259933.371 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:25:33.382Z,1745259933.382 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:25:33.793Z,1745259933.793 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:25:33.793Z,1745259933.793 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2025-04-21T18:27:23.660Z,1745260043.660 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-21T18:22:23.5Z 2025-04-21T18:27:23.660Z,1745260043.660 [Default:CheckIn:Read_GPS] Stopped 2025-04-21T18:27:23.660Z,1745260043.660 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-21T18:27:30.526Z,1745260050.526 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250421T172202/Courier0019.lzma 2025-04-21T18:27:31.529Z,1745260051.529 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Courier0019.lzma.bak 2025-04-21T18:27:31.529Z,1745260051.529 [DataOverHttps](INFO): SBD MOMSN=24699778 2025-04-21T18:27:47.022Z,1745260067.022 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20250421T172202/Express0020.lzma 2025-04-21T18:27:48.024Z,1745260068.024 [DataOverHttps](INFO): Moved sent file to Logs/20250421T172202/Express0020.lzma.bak 2025-04-21T18:27:48.024Z,1745260068.024 [DataOverHttps](INFO): SBD MOMSN=24699786 2025-04-21T18:27:49.318Z,1745260069.318 [Default:CheckIn:Read_Iridium] Stopped 2025-04-21T18:27:49.318Z,1745260069.318 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-21T18:27:49.318Z,1745260069.318 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-21T18:28:04.243Z,1745260084.243 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-21T18:28:04.243Z,1745260084.243 [NAL9602] Data Fault, FailCount= 1 2025-04-21T18:28:04.243Z,1745260084.243 [NAL9602](ERROR): Data Fault 2025-04-21T18:28:04.261Z,1745260084.261 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-21T18:28:04.651Z,1745260084.651 [NAL9602](INFO): Powering down 2025-04-21T18:28:05.494Z,1745260085.494 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-21T18:28:05.494Z,1745260085.494 [NAL9602] No Fault, FailCount= 1 2025-04-21T18:28:34.553Z,1745260114.553 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2025-04-21T18:28:34.553Z,1745260114.553 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:28:34.601Z,1745260114.601 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:28:34.951Z,1745260114.951 [NAL9602](INFO): Powering up NAL9602 2025-04-21T18:28:34.969Z,1745260114.969 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:28:34.969Z,1745260114.969 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2025-04-21T18:28:45.859Z,1745260125.859 [NAL9602](INFO): NAL9602 initialized 2025-04-21T18:31:35.557Z,1745260295.557 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23 2025-04-21T18:31:35.557Z,1745260295.557 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-21T18:31:35.568Z,1745260295.568 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-21T18:31:35.980Z,1745260295.980 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-21T18:31:35.980Z,1745260295.980 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23 2025-04-21T18:32:49.955Z,1745260369.955 [Default:CheckIn:C.Wait](INFO): Done W