2024-02-14T17:36:22.371Z,1707932182.371 [Supervisor](DEBUG): Initializing supervisor. 2024-02-14T17:36:22.376Z,1707932182.376 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-02-14T17:36:22.377Z,1707932182.377 [SyncHandler](INFO): Protected caller Thread ID is 995 2024-02-14T17:36:22.377Z,1707932182.377 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-02-14T17:36:22.378Z,1707932182.378 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-02-14T17:36:22.379Z,1707932182.379 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 996 2024-02-14T17:36:22.383Z,1707932182.383 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-02-14T17:36:22.401Z,1707932182.401 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-02-14T17:36:22.401Z,1707932182.401 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-02-14T17:36:22.402Z,1707932182.402 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 997 2024-02-14T17:36:22.406Z,1707932182.406 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-02-14T17:36:22.407Z,1707932182.407 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-02-14T17:36:22.407Z,1707932182.407 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 998 2024-02-14T17:36:22.409Z,1707932182.409 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-02-14T17:36:22.410Z,1707932182.410 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-02-14T17:36:22.411Z,1707932182.411 [logger ThreadHandler](INFO): Protected caller Thread ID is 999 2024-02-14T17:36:22.414Z,1707932182.414 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-02-14T17:36:22.415Z,1707932182.415 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-02-14T17:36:22.416Z,1707932182.416 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-02-14T17:36:22.673Z,1707932182.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-02-14T17:36:22.674Z,1707932182.674 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-02-14T17:36:22.755Z,1707932182.755 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-02-14T17:36:23.317Z,1707932183.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-02-14T17:36:23.318Z,1707932183.318 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-02-14T17:36:23.643Z,1707932183.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-02-14T17:36:23.644Z,1707932183.644 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-02-14T17:36:23.741Z,1707932183.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-02-14T17:36:23.742Z,1707932183.741 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-02-14T17:36:24.138Z,1707932184.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-02-14T17:36:24.138Z,1707932184.138 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-02-14T17:36:24.354Z,1707932184.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-02-14T17:36:24.354Z,1707932184.354 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-02-14T17:36:24.867Z,1707932184.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-02-14T17:36:24.867Z,1707932184.867 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-02-14T17:36:25.005Z,1707932185.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-02-14T17:36:25.005Z,1707932185.005 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-02-14T17:36:25.085Z,1707932185.085 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-02-14T17:36:25.876Z,1707932185.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-02-14T17:36:25.877Z,1707932185.877 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-02-14T17:36:26.250Z,1707932186.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-02-14T17:36:26.251Z,1707932186.251 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-02-14T17:36:26.442Z,1707932186.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-02-14T17:36:26.442Z,1707932186.442 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-02-14T17:36:26.553Z,1707932186.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-02-14T17:36:26.553Z,1707932186.553 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-02-14T17:36:26.782Z,1707932186.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-02-14T17:36:26.783Z,1707932186.783 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-02-14T17:36:27.030Z,1707932187.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-02-14T17:36:27.032Z,1707932187.032 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-02-14T17:36:27.032Z,1707932187.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-02-14T17:36:27.123Z,1707932187.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-02-14T17:36:27.209Z,1707932187.209 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-02-14T17:36:27.310Z,1707932187.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-02-14T17:36:27.394Z,1707932187.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-02-14T17:36:27.495Z,1707932187.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-02-14T17:36:27.597Z,1707932187.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-02-14T17:36:27.710Z,1707932187.710 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-02-14T17:36:27.860Z,1707932187.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-02-14T17:36:27.998Z,1707932187.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-02-14T17:36:28.110Z,1707932188.110 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-02-14T17:36:28.444Z,1707932188.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-02-14T17:36:28.445Z,1707932188.445 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2024-02-14T17:36:28.446Z,1707932188.446 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-02-14T17:36:28.457Z,1707932188.457 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-02-14T17:36:28.678Z,1707932188.678 [VerticalControl](DEBUG): Construct VerticalControl. 2024-02-14T17:36:28.836Z,1707932188.836 [VerticalControl] Loaded 2024-02-14T17:36:28.836Z,1707932188.836 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-02-14T17:36:28.839Z,1707932188.839 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-02-14T17:36:28.880Z,1707932188.880 [HorizontalControl] Loaded 2024-02-14T17:36:28.881Z,1707932188.881 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-02-14T17:36:28.883Z,1707932188.883 [SpeedControl](DEBUG): Construct SpeedControl. 2024-02-14T17:36:28.887Z,1707932188.887 [SpeedControl] Loaded 2024-02-14T17:36:28.888Z,1707932188.888 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-02-14T17:36:28.890Z,1707932188.890 [LoopControl](DEBUG): Construct LoopControl. 2024-02-14T17:36:28.891Z,1707932188.891 [LoopControl] Loaded 2024-02-14T17:36:28.891Z,1707932188.891 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-02-14T17:36:28.891Z,1707932188.891 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-02-14T17:36:28.892Z,1707932188.892 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-02-14T17:36:28.902Z,1707932188.902 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-02-14T17:36:28.903Z,1707932188.903 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-02-14T17:36:29.190Z,1707932189.190 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-02-14T17:36:29.191Z,1707932189.191 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-02-14T17:36:29.588Z,1707932189.588 [BuoyancyServo] Loaded 2024-02-14T17:36:29.589Z,1707932189.589 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-02-14T17:36:29.666Z,1707932189.666 [ElevatorServo] Loaded 2024-02-14T17:36:29.666Z,1707932189.666 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-02-14T17:36:29.688Z,1707932189.688 [MassServo] Loaded 2024-02-14T17:36:29.688Z,1707932189.688 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-02-14T17:36:29.707Z,1707932189.707 [RudderServo] Loaded 2024-02-14T17:36:29.707Z,1707932189.707 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-02-14T17:36:29.722Z,1707932189.722 [ThrusterHE] Loaded 2024-02-14T17:36:29.722Z,1707932189.722 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-02-14T17:36:29.722Z,1707932189.722 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-02-14T17:36:29.723Z,1707932189.723 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-02-14T17:36:29.836Z,1707932189.836 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-02-14T17:36:29.837Z,1707932189.837 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-02-14T17:36:29.850Z,1707932189.850 [NavChart] Loaded 2024-02-14T17:36:29.851Z,1707932189.851 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-02-14T17:36:29.856Z,1707932189.856 [UniversalFixResidualReporter] Loaded 2024-02-14T17:36:29.857Z,1707932189.857 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-02-14T17:36:29.857Z,1707932189.857 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-02-14T17:36:29.858Z,1707932189.858 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-02-14T17:36:29.914Z,1707932189.914 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-02-14T17:36:29.914Z,1707932189.914 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-02-14T17:36:30.341Z,1707932190.341 [AHRS_M2] Loaded 2024-02-14T17:36:30.341Z,1707932190.341 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-02-14T17:36:31.172Z,1707932191.172 [BPC1] Loaded 2024-02-14T17:36:31.172Z,1707932191.172 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-02-14T17:36:31.248Z,1707932191.248 [DataOverHttps] Loaded 2024-02-14T17:36:31.249Z,1707932191.249 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-02-14T17:36:31.250Z,1707932191.250 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0 2024-02-14T17:36:31.250Z,1707932191.250 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1081 2024-02-14T17:36:31.271Z,1707932191.271 [Depth_Keller] Loaded 2024-02-14T17:36:31.271Z,1707932191.271 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-02-14T17:36:31.276Z,1707932191.276 [DropWeight] Loaded 2024-02-14T17:36:31.276Z,1707932191.276 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-02-14T17:36:31.338Z,1707932191.338 [NAL9602] Loaded 2024-02-14T17:36:31.339Z,1707932191.339 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-02-14T17:36:31.368Z,1707932191.368 [Onboard] Loaded 2024-02-14T17:36:31.369Z,1707932191.369 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-02-14T17:36:31.370Z,1707932191.370 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0 2024-02-14T17:36:31.370Z,1707932191.370 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1082 2024-02-14T17:36:31.383Z,1707932191.383 [Power24vConverter] Loaded 2024-02-14T17:36:31.384Z,1707932191.384 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-02-14T17:36:31.397Z,1707932191.397 [Radio_Surface] Loaded 2024-02-14T17:36:31.397Z,1707932191.397 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-02-14T17:36:31.398Z,1707932191.398 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0 2024-02-14T17:36:31.399Z,1707932191.399 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1083 2024-02-14T17:36:31.412Z,1707932191.412 [Sonardyne_Nano] Loaded 2024-02-14T17:36:31.413Z,1707932191.413 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-02-14T17:36:31.413Z,1707932191.413 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-02-14T17:36:31.414Z,1707932191.414 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-02-14T17:36:31.464Z,1707932191.464 [DepthRateCalculator] Loaded 2024-02-14T17:36:31.464Z,1707932191.464 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-02-14T17:36:31.469Z,1707932191.469 [PitchRateCalculator] Loaded 2024-02-14T17:36:31.469Z,1707932191.469 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-02-14T17:36:31.478Z,1707932191.478 [SpeedCalculator] Loaded 2024-02-14T17:36:31.479Z,1707932191.479 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-02-14T17:36:31.483Z,1707932191.483 [YawRateCalculator] Loaded 2024-02-14T17:36:31.483Z,1707932191.483 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-02-14T17:36:31.502Z,1707932191.502 [ElevatorOffsetCalculator] Loaded 2024-02-14T17:36:31.503Z,1707932191.503 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-02-14T17:36:31.503Z,1707932191.503 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-02-14T17:36:31.504Z,1707932191.504 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-02-14T17:36:31.636Z,1707932191.636 [CANONSampler] Loaded 2024-02-14T17:36:31.636Z,1707932191.636 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-02-14T17:36:31.697Z,1707932191.697 [CTD_Seabird] Loaded 2024-02-14T17:36:31.697Z,1707932191.697 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-02-14T17:36:31.698Z,1707932191.698 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0 2024-02-14T17:36:31.699Z,1707932191.699 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1084 2024-02-14T17:36:31.719Z,1707932191.719 [PAR_Licor] Loaded 2024-02-14T17:36:31.719Z,1707932191.719 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-02-14T17:36:31.760Z,1707932191.760 [WetLabsBB2FL] Loaded 2024-02-14T17:36:31.760Z,1707932191.760 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-02-14T17:36:31.761Z,1707932191.761 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0 2024-02-14T17:36:31.761Z,1707932191.761 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1085 2024-02-14T17:36:31.762Z,1707932191.762 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-02-14T17:36:31.763Z,1707932191.763 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-02-14T17:36:31.789Z,1707932191.789 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-02-14T17:36:31.790Z,1707932191.790 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-02-14T17:36:32.127Z,1707932192.127 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-02-14T17:36:32.128Z,1707932192.128 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-02-14T17:36:32.269Z,1707932192.269 [SBIT](DEBUG): Construct Startup Built In Test. 2024-02-14T17:36:32.279Z,1707932192.279 [SBIT] Loaded 2024-02-14T17:36:32.279Z,1707932192.279 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-02-14T17:36:32.282Z,1707932192.282 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-02-14T17:36:32.296Z,1707932192.296 [IBIT] Loaded 2024-02-14T17:36:32.296Z,1707932192.296 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-02-14T17:36:32.302Z,1707932192.302 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-02-14T17:36:32.407Z,1707932192.407 [CBIT] Loaded 2024-02-14T17:36:32.408Z,1707932192.408 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-02-14T17:36:32.408Z,1707932192.408 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-02-14T17:36:32.409Z,1707932192.409 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-02-14T17:36:32.521Z,1707932192.521 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-02-14T17:36:32.527Z,1707932192.527 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-02-14T17:36:32.530Z,1707932192.530 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-02-14T17:36:32.541Z,1707932192.541 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-02-14T17:36:32.542Z,1707932192.542 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BB14E0 2024-02-14T17:36:32.542Z,1707932192.542 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1086 2024-02-14T17:36:32.547Z,1707932192.547 [Supervisor](INFO): Main Thread ID is 831 2024-02-14T17:36:32.547Z,1707932192.547 [Supervisor](DEBUG): Running supervisor. 2024-02-14T17:36:32.547Z,1707932192.547 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1087 2024-02-14T17:36:32.548Z,1707932192.548 [CommandExec](INFO): Initializing the command executive. 2024-02-14T17:36:32.550Z,1707932192.550 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1088 2024-02-14T17:36:32.552Z,1707932192.552 [controlThread ThreadHandler](INFO): Handler Thread ID is 1089 2024-02-14T17:36:32.552Z,1707932192.552 [controlThread](DEBUG): Initializing ControlThread 2024-02-14T17:36:32.553Z,1707932192.553 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-02-14T17:36:32.555Z,1707932192.555 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-02-14T17:36:32.555Z,1707932192.555 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-02-14T17:36:32.556Z,1707932192.556 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-02-14T17:36:32.558Z,1707932192.558 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-02-14T17:36:32.558Z,1707932192.558 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-02-14T17:36:32.563Z,1707932192.563 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-02-14T17:36:32.563Z,1707932192.563 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-02-14T17:36:32.563Z,1707932192.563 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-02-14T17:36:32.563Z,1707932192.563 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-02-14T17:36:32.564Z,1707932192.564 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-02-14T17:36:32.569Z,1707932192.569 [SBIT](INFO): Initialize SBIT Component. 2024-02-14T17:36:32.569Z,1707932192.569 [SBIT](IMPORTANT): git: 2024-02-14 2024-02-14T17:36:32.569Z,1707932192.569 [SBIT](INFO): git hash: 8fc4e848ca047b5de9ae8a7c02f55958c6301ab2 2024-02-14T17:36:32.570Z,1707932192.570 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-02-14T17:36:32.571Z,1707932192.571 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-02-14T17:36:32.572Z,1707932192.572 [SBIT](INFO): Beginning SBIT in 64.000000 seconds. 2024-02-14T17:36:32.573Z,1707932192.573 [IBIT](INFO): Initialize IBIT Component. 2024-02-14T17:36:32.574Z,1707932192.574 [CBIT](DEBUG): Initialize CBIT Component. 2024-02-14T17:36:32.574Z,1707932192.574 [logger ThreadHandler](INFO): Handler Thread ID is 1090 2024-02-14T17:36:32.585Z,1707932192.585 [CBIT](DEBUG): Initialized mux pins. 2024-02-14T17:36:32.585Z,1707932192.585 [CBIT](DEBUG): Initializing the watchdog timer. 2024-02-14T17:36:32.593Z,1707932192.593 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1091 2024-02-14T17:36:32.595Z,1707932192.595 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-02-14T17:36:32.605Z,1707932192.605 [Onboard ThreadHandler](INFO): Handler Thread ID is 1092 2024-02-14T17:36:32.609Z,1707932192.609 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-02-14T17:36:32.610Z,1707932192.610 [CBIT](DEBUG): Initializing heartbeat. 2024-02-14T17:36:32.626Z,1707932192.626 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1093 2024-02-14T17:36:32.645Z,1707932192.645 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1094 2024-02-14T17:36:32.646Z,1707932192.646 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-02-14T17:36:32.651Z,1707932192.651 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1096 2024-02-14T17:36:32.657Z,1707932192.657 [WetLabsBB2FL](INFO): Powering up 2024-02-14T17:36:32.658Z,1707932192.658 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1098 2024-02-14T17:36:32.661Z,1707932192.661 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-02-14T17:36:32.662Z,1707932192.662 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-02-14T17:36:32.662Z,1707932192.662 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-02-14T17:36:32.662Z,1707932192.662 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-02-14T17:36:32.662Z,1707932192.662 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-02-14T17:36:32.662Z,1707932192.662 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-02-14T17:36:32.662Z,1707932192.662 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-02-14T17:36:32.663Z,1707932192.663 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-02-14T17:36:32.681Z,1707932192.681 [CBIT](DEBUG): Deactivating GF circuits. 2024-02-14T17:36:32.681Z,1707932192.681 [CBIT](DEBUG): Deactivating emergency mode. 2024-02-14T17:36:32.721Z,1707932192.721 [CBIT](DEBUG): Backplane powered. 2024-02-14T17:36:32.722Z,1707932192.722 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-02-14T17:36:32.722Z,1707932192.722 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-02-14T17:36:32.734Z,1707932192.734 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-14T17:36:32.761Z,1707932192.761 [MissionManager](DEBUG): mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-02-14T17:36:32.762Z,1707932192.762 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-02-14T17:36:32.762Z,1707932192.762 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-02-14T17:36:32.836Z,1707932192.836 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-02-14T17:36:32.837Z,1707932192.837 [Default:A.Wait](DEBUG): Construct Wait. 2024-02-14T17:36:32.839Z,1707932192.839 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-14T17:36:32.896Z,1707932192.896 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-02-14T17:36:32.922Z,1707932192.922 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-02-14T17:36:32.928Z,1707932192.928 [Default:E.Execute](DEBUG): Construct Execute. 2024-02-14T17:36:32.947Z,1707932192.947 [MissionManager](DEBUG): mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-02-14T17:36:32.952Z,1707932192.952 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-02-14T17:36:32.967Z,1707932192.967 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-02-14T17:36:33.029Z,1707932193.029 [Radio_Surface](INFO): Powering up 2024-02-14T17:36:33.034Z,1707932193.034 [Depth_Keller](ERROR): Pressure or depth reading out of range: 915.616455 decibar, 0.000000 m 2024-02-14T17:36:33.035Z,1707932193.035 [Power24vConverter](INFO): Powering up. 2024-02-14T17:36:33.036Z,1707932193.036 [Sonardyne_Nano](INFO): Initializing. 2024-02-14T17:36:33.056Z,1707932193.056 [CANONSampler](INFO): Powering down 2024-02-14T17:36:33.067Z,1707932193.067 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-02-14T17:36:33.110Z,1707932193.110 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-02-14T17:36:33.112Z,1707932193.112 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-02-14T17:36:33.113Z,1707932193.113 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-02-14T17:36:33.121Z,1707932193.121 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-02-14T17:36:33.122Z,1707932193.122 [MassServo](DEBUG): Initializing EZServoServo. 2024-02-14T17:36:33.129Z,1707932193.129 [MassServo](DEBUG): Initializing MassServo. 2024-02-14T17:36:33.130Z,1707932193.130 [RudderServo](DEBUG): Initializing EZServoServo. 2024-02-14T17:36:33.149Z,1707932193.149 [RudderServo](DEBUG): Initializing RudderServo. 2024-02-14T17:36:33.150Z,1707932193.150 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-02-14T17:36:33.157Z,1707932193.157 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-02-14T17:36:34.761Z,1707932194.761 [WetLabsBB2FL](INFO): Powering down 2024-02-14T17:36:34.979Z,1707932194.979 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-02-14T17:36:36.265Z,1707932196.265 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109 2024-02-14T17:36:38.722Z,1707932198.722 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-02-14T17:37:01.646Z,1707932221.646 [NAL9602](INFO): Powering up NAL9602 2024-02-14T17:37:12.558Z,1707932232.558 [NAL9602](INFO): NAL9602 initialized 2024-02-14T17:37:37.247Z,1707932257.247 [SBIT](IMPORTANT): Beginning Startup BIT 2024-02-14T17:37:37.251Z,1707932257.251 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-14T17:37:46.605Z,1707932266.605 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-14T17:37:48.129Z,1707932268.129 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-02-14T17:37:48.546Z,1707932268.546 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-14T17:37:59.533Z,1707932279.533 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-02-14T17:37:59.986Z,1707932279.986 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-14T17:38:10.805Z,1707932290.805 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-02-14T17:38:10.806Z,1707932290.806 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule. 2024-02-14T17:38:31.359Z,1707932311.359 [SBIT](IMPORTANT): SBIT PASSED 2024-02-14T17:38:31.359Z,1707932311.359 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-02-14T17:38:31.360Z,1707932311.360 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2024-02-14T17:38:31.360Z,1707932311.360 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-02-14T17:38:31.360Z,1707932311.360 [SBIT](IMPORTANT): BuoyancyServo.deviationVolume=6 cubic_centimeter; 2024-02-14T17:38:31.360Z,1707932311.360 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool; 2024-02-14T17:38:31.361Z,1707932311.361 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool; 2024-02-14T17:38:31.361Z,1707932311.361 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=1 bool; 2024-02-14T17:38:31.361Z,1707932311.361 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-02-14T17:38:31.361Z,1707932311.361 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count; 2024-02-14T17:38:31.361Z,1707932311.361 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool; 2024-02-14T17:38:31.361Z,1707932311.361 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent; 2024-02-14T17:38:31.361Z,1707932311.361 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=215.727710 cubic_centimeter; 2024-02-14T17:38:31.361Z,1707932311.361 [SBIT](IMPORTANT): VerticalControl.massDefault=-11.248903 millimeter; 2024-02-14T17:38:31.766Z,1707932311.766 [MissionManager](IMPORTANT): Started mission Startup 2024-02-14T17:38:31.767Z,1707932311.767 [Startup] Running Loop=1 2024-02-14T17:38:31.767Z,1707932311.767 [Startup](DEBUG): Aggregate::initialize Startup 2024-02-14T17:38:31.767Z,1707932311.767 [Startup:A.GoToSurface] Running Loop=1 2024-02-14T17:38:31.767Z,1707932311.767 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-14T17:38:31.767Z,1707932311.767 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-14T17:38:31.768Z,1707932311.768 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-14T17:38:31.768Z,1707932311.768 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-14T17:38:31.769Z,1707932311.769 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-14T17:38:31.769Z,1707932311.769 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-14T17:38:31.770Z,1707932311.770 [Startup:StartupSatComms] Running Loop=1 2024-02-14T17:38:31.770Z,1707932311.770 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-02-14T17:38:31.771Z,1707932311.771 [Startup:StartupSatComms:A] Running Loop=1 2024-02-14T17:38:32.159Z,1707932312.159 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-02-14T17:38:54.569Z,1707932334.569 [CommandExec](IMPORTANT): got command failComponent 2024-02-14T17:38:54.570Z,1707932334.570 [CommandExec](IMPORTANT): Failed components: 2024-02-14T17:38:54.570Z,1707932334.570 [CommandExec](IMPORTANT): No failed Components. 2024-02-14T17:38:58.562Z,1707932338.562 [CommandLine](INFO): End of History 2024-02-14T17:39:10.288Z,1707932350.288 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2024-02-14T17:39:10.293Z,1707932350.293 [CommandExec](IMPORTANT): Onboard.Humidity 13.276766 % 2024-02-14T17:39:16.079Z,1707932356.079 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004959 2024-02-14T17:39:20.175Z,1707932360.175 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-02-14T17:39:20.175Z,1707932360.175 [CommandExec](IMPORTANT): Onboard.Pressure 9.832232 psi 2024-02-14T17:39:20.290Z,1707932360.290 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-14T17:39:31.971Z,1707932371.971 [Startup:StartupSatComms:A](INFO): Timed out from 2024-02-14T17:38:31.8Z 2024-02-14T17:39:31.971Z,1707932371.971 [Startup:StartupSatComms:A] Stopped 2024-02-14T17:39:31.971Z,1707932371.971 [Startup:StartupSatComms:B] Running Loop=1 2024-02-14T17:39:32.351Z,1707932372.351 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-14T17:39:32.777Z,1707932372.777 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-02-14T17:39:32.777Z,1707932372.777 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T17:39:32.816Z,1707932372.816 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T17:39:33.175Z,1707932373.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T17:39:33.175Z,1707932373.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-02-14T17:39:39.573Z,1707932379.573 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20240214T172847/Courier0004.lzma 2024-02-14T17:39:40.575Z,1707932380.575 [DataOverHttps](INFO): Moved sent file to Logs/20240214T172847/Courier0004.lzma.bak 2024-02-14T17:39:40.575Z,1707932380.575 [DataOverHttps](INFO): SBD MOMSN=19278170 2024-02-14T17:39:50.369Z,1707932390.369 [CommandExec](IMPORTANT): got command load 2024-02-14T17:39:50.369Z,1707932390.369 [CommandExec](FAULT): Incomplete syntax. Try: help load 2024-02-14T17:39:56.076Z,1707932396.076 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240214T173622/Courier0000.lzma 2024-02-14T17:39:57.078Z,1707932397.078 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Courier0000.lzma.bak 2024-02-14T17:39:57.079Z,1707932397.079 [DataOverHttps](INFO): SBD MOMSN=19278172 2024-02-14T17:40:15.462Z,1707932415.462 [DataOverHttps](INFO): Sending 1019 bytes from file Logs/20240214T172847/Express0001.lzma 2024-02-14T17:40:16.462Z,1707932416.462 [DataOverHttps](INFO): Moved sent file to Logs/20240214T172847/Express0001.lzma.bak 2024-02-14T17:40:16.463Z,1707932416.463 [DataOverHttps](INFO): SBD MOMSN=19278175 2024-02-14T17:40:32.221Z,1707932432.221 [Startup:StartupSatComms:B](INFO): Timed out from 2024-02-14T17:39:31.0Z 2024-02-14T17:40:32.221Z,1707932432.221 [Startup:StartupSatComms:B] Stopped 2024-02-14T17:40:32.222Z,1707932432.222 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-02-14T17:40:32.222Z,1707932432.222 [Startup:StartupSatComms] Stopped 2024-02-14T17:40:32.222Z,1707932432.222 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-02-14T17:40:32.223Z,1707932432.223 [Startup](INFO): Completed Startup 2024-02-14T17:40:32.223Z,1707932432.223 [MissionManager](INFO): Startup is completed. 2024-02-14T17:40:32.223Z,1707932432.223 [MissionManager](INFO): Uninitializing Mission Startup 2024-02-14T17:40:32.223Z,1707932432.223 [Startup] Stopped 2024-02-14T17:40:32.223Z,1707932432.223 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-02-14T17:40:32.223Z,1707932432.223 [Startup:A.GoToSurface] Stopped 2024-02-14T17:40:32.223Z,1707932432.223 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-02-14T17:40:32.602Z,1707932432.602 [MissionManager](IMPORTANT): Started mission Default 2024-02-14T17:40:32.602Z,1707932432.602 [Default] Running Loop=1 2024-02-14T17:40:32.602Z,1707932432.602 [Default](DEBUG): Aggregate::initialize Default 2024-02-14T17:40:32.602Z,1707932432.602 [Default:B.GoToSurface] Running Loop=1 2024-02-14T17:40:32.602Z,1707932432.602 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-14T17:40:32.602Z,1707932432.602 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-14T17:40:32.603Z,1707932432.603 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-14T17:40:32.603Z,1707932432.603 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-14T17:40:32.603Z,1707932432.603 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-14T17:40:32.603Z,1707932432.603 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-14T17:40:32.604Z,1707932432.604 [Default:A.Wait] Running Loop=1 2024-02-14T17:40:32.604Z,1707932432.604 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-02-14T17:40:36.181Z,1707932436.181 [DataOverHttps](INFO): Sending 726 bytes from file Logs/20240214T172847/Express0005.lzma 2024-02-14T17:40:37.182Z,1707932437.182 [DataOverHttps](INFO): Moved sent file to Logs/20240214T172847/Express0005.lzma.bak 2024-02-14T17:40:37.182Z,1707932437.182 [DataOverHttps](INFO): SBD MOMSN=19278201 2024-02-14T17:40:45.948Z,1707932445.948 [Default:A.Wait](INFO): Done Waiting. 2024-02-14T17:40:45.949Z,1707932445.949 [Default:A.Wait] Stopped 2024-02-14T17:40:45.949Z,1707932445.949 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T17:40:46.338Z,1707932446.338 [Default:CheckIn] Running Loop=1 2024-02-14T17:40:46.339Z,1707932446.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T17:40:46.339Z,1707932446.339 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T17:40:46.736Z,1707932446.736 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-02-14T17:40:58.450Z,1707932458.450 [CommandExec](IMPORTANT): got command help 2024-02-14T17:41:26.366Z,1707932486.366 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-02-14T17:41:39.298Z,1707932499.298 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-02-14T17:42:16.008Z,1707932536.008 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-02-14T17:42:33.814Z,1707932553.814 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-02-14T17:42:33.814Z,1707932553.814 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T17:42:33.824Z,1707932553.824 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T17:42:34.279Z,1707932554.279 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T17:42:34.279Z,1707932554.279 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-02-14T17:43:14.989Z,1707932594.989 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2024-02-14T17:43:14.992Z,1707932594.992 [BPC1](INFO): Received data from all battery sticks. 2024-02-14T17:43:53.904Z,1707932633.904 [CommandLine](INFO): End of History 2024-02-14T17:43:53.907Z,1707932633.907 [CommandLine](INFO): End of History 2024-02-14T17:43:53.913Z,1707932633.913 [CommandLine](INFO): End of History 2024-02-14T17:43:54.153Z,1707932634.153 [CommandLine](INFO): End of History 2024-02-14T17:43:54.377Z,1707932634.377 [CommandLine](INFO): End of History 2024-02-14T17:43:54.609Z,1707932634.609 [CommandLine](INFO): End of History 2024-02-14T17:43:54.833Z,1707932634.833 [CommandLine](INFO): End of History 2024-02-14T17:43:55.051Z,1707932635.051 [CommandLine](INFO): End of History 2024-02-14T17:43:55.281Z,1707932635.281 [CommandLine](INFO): End of History 2024-02-14T17:43:55.786Z,1707932635.786 [CommandLine](INFO): End of History 2024-02-14T17:43:55.945Z,1707932635.945 [CommandLine](INFO): End of History 2024-02-14T17:43:56.209Z,1707932636.209 [CommandLine](INFO): End of History 2024-02-14T17:43:56.489Z,1707932636.489 [CommandLine](INFO): End of History 2024-02-14T17:43:57.082Z,1707932637.082 [CommandLine](INFO): End of History 2024-02-14T17:43:57.433Z,1707932637.433 [CommandLine](INFO): End of History 2024-02-14T17:43:57.713Z,1707932637.713 [CommandLine](INFO): End of History 2024-02-14T17:43:58.002Z,1707932638.002 [CommandLine](INFO): End of History 2024-02-14T17:43:58.273Z,1707932638.273 [CommandLine](INFO): End of History 2024-02-14T17:43:58.538Z,1707932638.538 [CommandLine](INFO): End of History 2024-02-14T17:43:58.777Z,1707932638.777 [CommandLine](INFO): End of History 2024-02-14T17:43:59.057Z,1707932639.057 [CommandLine](INFO): End of History 2024-02-14T17:44:07.884Z,1707932647.884 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-02-14T17:44:07.885Z,1707932647.885 [CommandExec](IMPORTANT): Onboard.Pressure 9.827613 psi 2024-02-14T17:45:34.813Z,1707932734.813 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-02-14T17:45:34.813Z,1707932734.813 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T17:45:34.823Z,1707932734.823 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T17:45:35.227Z,1707932735.227 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T17:45:35.227Z,1707932735.227 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-02-14T17:45:46.510Z,1707932746.510 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T17:40:46.3Z 2024-02-14T17:45:46.510Z,1707932746.510 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T17:45:46.510Z,1707932746.510 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T17:45:46.915Z,1707932746.915 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-14T17:45:47.849Z,1707932747.849 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240214T173622/Courier0004.lzma 2024-02-14T17:45:48.846Z,1707932748.846 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Courier0004.lzma.bak 2024-02-14T17:45:48.846Z,1707932748.846 [DataOverHttps](INFO): SBD MOMSN=19278213 2024-02-14T17:46:04.274Z,1707932764.274 [DataOverHttps](INFO): Sending 1098 bytes from file Logs/20240214T173622/Express0001.lzma 2024-02-14T17:46:05.275Z,1707932765.275 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0001.lzma.bak 2024-02-14T17:46:05.275Z,1707932765.275 [DataOverHttps](INFO): SBD MOMSN=19278215 2024-02-14T17:46:20.701Z,1707932780.701 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20240214T173622/Express0005.lzma 2024-02-14T17:46:21.702Z,1707932781.702 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0005.lzma.bak 2024-02-14T17:46:21.702Z,1707932781.702 [DataOverHttps](INFO): SBD MOMSN=19278250 2024-02-14T17:46:22.853Z,1707932782.853 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T17:46:22.853Z,1707932782.853 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T17:46:22.854Z,1707932782.854 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T17:46:34.163Z,1707932794.163 [Power24vConverter](INFO): Powering down. 2024-02-14T17:47:15.366Z,1707932835.366 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-02-14T17:47:15.366Z,1707932835.366 [NAL9602] Data Fault, FailCount= 1 2024-02-14T17:47:15.367Z,1707932835.367 [NAL9602](ERROR): Data Fault 2024-02-14T17:47:15.412Z,1707932835.412 [CBIT](ERROR): Data Fault in component: NAL9602 2024-02-14T17:47:15.770Z,1707932835.770 [NAL9602](INFO): Powering down 2024-02-14T17:47:16.603Z,1707932836.603 [CBIT](INFO): Clearing failed state for component NAL9602 2024-02-14T17:47:16.603Z,1707932836.603 [NAL9602] No Fault, FailCount= 1 2024-02-14T17:47:46.070Z,1707932866.070 [NAL9602](INFO): Powering up NAL9602 2024-02-14T17:47:56.982Z,1707932876.982 [NAL9602](INFO): NAL9602 initialized 2024-02-14T17:48:35.787Z,1707932915.787 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-02-14T17:48:35.787Z,1707932915.787 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T17:48:35.818Z,1707932915.818 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T17:48:36.230Z,1707932916.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T17:48:36.230Z,1707932916.230 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-02-14T17:49:41.109Z,1707932981.109 [CommandExec](IMPORTANT): got command failComponent 2024-02-14T17:49:41.109Z,1707932981.109 [CommandExec](IMPORTANT): Failed components: 2024-02-14T17:49:41.110Z,1707932981.110 [CommandExec](IMPORTANT): No failed Components. 2024-02-14T17:49:45.374Z,1707932985.374 [CommandExec](IMPORTANT): got command strobe off 2024-02-14T17:49:45.374Z,1707932985.374 [CommandExec](IMPORTANT): Deactivating strobe 2024-02-14T17:51:23.440Z,1707933083.440 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T17:51:23.440Z,1707933083.440 [Default:CheckIn:C.Wait] Stopped 2024-02-14T17:51:23.440Z,1707933083.440 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T17:51:23.440Z,1707933083.440 [Default:CheckIn:D] Running Loop=1 2024-02-14T17:51:23.859Z,1707933083.859 [Default:CheckIn:D] Stopped 2024-02-14T17:51:23.859Z,1707933083.859 [Default:CheckIn:E] Running Loop=1 2024-02-14T17:51:24.267Z,1707933084.267 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.854284 min 2024-02-14T17:51:24.267Z,1707933084.267 [Default:CheckIn:E] Stopped 2024-02-14T17:51:24.267Z,1707933084.267 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T17:51:24.267Z,1707933084.267 [Default:CheckIn] Stopped 2024-02-14T17:51:24.267Z,1707933084.267 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T17:51:24.267Z,1707933084.267 [Default:CheckIn](INFO): Running loop #2 2024-02-14T17:51:24.267Z,1707933084.267 [Default:CheckIn] Running Loop=2 2024-02-14T17:51:24.267Z,1707933084.267 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T17:51:24.267Z,1707933084.267 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T17:51:32.458Z,1707933092.458 [CommandExec](IMPORTANT): got command burn on 2024-02-14T17:51:32.458Z,1707933092.458 [CommandExec](IMPORTANT): Activating dropweight wire 2024-02-14T17:51:36.779Z,1707933096.779 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-02-14T17:51:36.779Z,1707933096.779 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T17:51:36.790Z,1707933096.790 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T17:51:37.197Z,1707933097.197 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T17:51:37.197Z,1707933097.197 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-02-14T17:52:15.454Z,1707933135.454 [CommandExec](IMPORTANT): got command burn off 2024-02-14T17:52:15.454Z,1707933135.454 [CommandExec](IMPORTANT): Deactivating dropweight wire 2024-02-14T17:54:37.757Z,1707933277.757 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-02-14T17:54:37.757Z,1707933277.757 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T17:54:37.796Z,1707933277.796 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T17:54:38.172Z,1707933278.172 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T17:54:38.172Z,1707933278.172 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-02-14T17:56:24.414Z,1707933384.414 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T17:51:24.3Z 2024-02-14T17:56:24.414Z,1707933384.414 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T17:56:24.415Z,1707933384.415 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T17:56:31.952Z,1707933391.952 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240214T173622/Courier0007.lzma 2024-02-14T17:56:32.954Z,1707933392.954 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Courier0007.lzma.bak 2024-02-14T17:56:32.954Z,1707933392.954 [DataOverHttps](INFO): SBD MOMSN=19278299 2024-02-14T17:56:48.553Z,1707933408.553 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20240214T173622/Express0008.lzma 2024-02-14T17:56:49.554Z,1707933409.554 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0008.lzma.bak 2024-02-14T17:56:49.555Z,1707933409.555 [DataOverHttps](INFO): SBD MOMSN=19278301 2024-02-14T17:56:50.674Z,1707933410.674 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T17:56:50.674Z,1707933410.674 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T17:56:50.674Z,1707933410.674 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T17:57:38.762Z,1707933458.762 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-02-14T17:57:38.762Z,1707933458.762 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T17:57:38.773Z,1707933458.773 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T17:57:39.163Z,1707933459.163 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T17:57:39.163Z,1707933459.163 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-02-14T17:58:00.558Z,1707933480.558 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-02-14T17:58:00.558Z,1707933480.558 [NAL9602] Data Fault, FailCount= 2 2024-02-14T17:58:00.558Z,1707933480.558 [NAL9602](ERROR): Data Fault 2024-02-14T17:58:00.600Z,1707933480.600 [CBIT](ERROR): Data Fault in component: NAL9602 2024-02-14T17:58:00.962Z,1707933480.962 [NAL9602](INFO): Powering down 2024-02-14T17:58:01.791Z,1707933481.791 [CBIT](INFO): Clearing failed state for component NAL9602 2024-02-14T17:58:01.791Z,1707933481.791 [NAL9602] No Fault, FailCount= 2 2024-02-14T17:58:31.262Z,1707933511.262 [NAL9602](INFO): Powering up NAL9602 2024-02-14T17:58:42.177Z,1707933522.177 [NAL9602](INFO): NAL9602 initialized 2024-02-14T18:00:39.746Z,1707933639.746 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2024-02-14T18:00:39.746Z,1707933639.746 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T18:00:39.761Z,1707933639.761 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T18:00:40.169Z,1707933640.169 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T18:00:40.169Z,1707933640.169 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2024-02-14T18:01:51.271Z,1707933711.271 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:01:51.271Z,1707933711.271 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:01:51.271Z,1707933711.271 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:01:51.271Z,1707933711.271 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:01:51.679Z,1707933711.679 [Default:CheckIn:D] Stopped 2024-02-14T18:01:51.679Z,1707933711.679 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:01:52.073Z,1707933712.073 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.317946 min 2024-02-14T18:01:52.073Z,1707933712.073 [Default:CheckIn:E] Stopped 2024-02-14T18:01:52.073Z,1707933712.073 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:01:52.073Z,1707933712.073 [Default:CheckIn] Stopped 2024-02-14T18:01:52.073Z,1707933712.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:01:52.073Z,1707933712.073 [Default:CheckIn](INFO): Running loop #3 2024-02-14T18:01:52.073Z,1707933712.073 [Default:CheckIn] Running Loop=3 2024-02-14T18:01:52.074Z,1707933712.074 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:01:52.074Z,1707933712.074 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:03:40.749Z,1707933820.749 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2024-02-14T18:03:40.750Z,1707933820.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T18:03:40.760Z,1707933820.760 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T18:03:41.159Z,1707933821.159 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T18:03:41.159Z,1707933821.159 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2024-02-14T18:06:41.780Z,1707934001.780 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2024-02-14T18:06:41.780Z,1707934001.780 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T18:06:41.791Z,1707934001.791 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T18:06:42.209Z,1707934002.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T18:06:42.209Z,1707934002.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2024-02-14T18:06:52.275Z,1707934012.275 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T18:01:52.1Z 2024-02-14T18:06:52.275Z,1707934012.275 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:06:52.275Z,1707934012.275 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:06:59.585Z,1707934019.585 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240214T173622/Courier0010.lzma 2024-02-14T18:07:00.586Z,1707934020.586 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Courier0010.lzma.bak 2024-02-14T18:07:00.587Z,1707934020.587 [DataOverHttps](INFO): SBD MOMSN=19278393 2024-02-14T18:07:15.996Z,1707934035.996 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240214T173622/Express0011.lzma 2024-02-14T18:07:16.998Z,1707934036.998 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0011.lzma.bak 2024-02-14T18:07:16.999Z,1707934036.999 [DataOverHttps](INFO): SBD MOMSN=19278395 2024-02-14T18:07:18.135Z,1707934038.135 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:07:18.135Z,1707934038.135 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:07:18.135Z,1707934038.135 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:08:45.442Z,1707934125.442 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-02-14T18:08:45.442Z,1707934125.442 [NAL9602] Data Fault, FailCount= 3 2024-02-14T18:08:45.442Z,1707934125.442 [NAL9602](ERROR): Data Fault 2024-02-14T18:08:45.460Z,1707934125.460 [CBIT](ERROR): Data Fault in component: NAL9602 2024-02-14T18:08:45.842Z,1707934125.842 [NAL9602](INFO): Powering down 2024-02-14T18:08:46.677Z,1707934126.677 [CBIT](INFO): Clearing failed state for component NAL9602 2024-02-14T18:08:46.677Z,1707934126.677 [NAL9602] No Fault, FailCount= 3 2024-02-14T18:09:16.198Z,1707934156.198 [NAL9602](INFO): Powering up NAL9602 2024-02-14T18:09:27.110Z,1707934167.110 [NAL9602](INFO): NAL9602 initialized 2024-02-14T18:09:42.875Z,1707934182.875 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2024-02-14T18:09:42.875Z,1707934182.875 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T18:09:42.886Z,1707934182.886 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T18:09:43.298Z,1707934183.298 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T18:09:43.298Z,1707934183.298 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2024-02-14T18:12:18.864Z,1707934338.864 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:12:18.864Z,1707934338.864 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:12:18.864Z,1707934338.864 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:12:18.864Z,1707934338.864 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:12:19.259Z,1707934339.259 [Default:CheckIn:D] Stopped 2024-02-14T18:12:19.259Z,1707934339.259 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:12:19.652Z,1707934339.652 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.777618 min 2024-02-14T18:12:19.652Z,1707934339.652 [Default:CheckIn:E] Stopped 2024-02-14T18:12:19.652Z,1707934339.652 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:12:19.652Z,1707934339.652 [Default:CheckIn] Stopped 2024-02-14T18:12:19.652Z,1707934339.652 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:12:19.652Z,1707934339.652 [Default:CheckIn](INFO): Running loop #4 2024-02-14T18:12:19.652Z,1707934339.652 [Default:CheckIn] Running Loop=4 2024-02-14T18:12:19.652Z,1707934339.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:12:19.653Z,1707934339.653 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:12:43.905Z,1707934363.905 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2024-02-14T18:12:43.905Z,1707934363.905 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T18:12:43.932Z,1707934363.932 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T18:12:44.319Z,1707934364.319 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T18:12:44.319Z,1707934364.319 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2024-02-14T18:13:33.992Z,1707934413.992 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181332.00,A,3648.16962,N,12147.26433,W,2.410,107.02,140224,,,A*76 2024-02-14T18:13:34.003Z,1707934414.003 [NAL9602](INFO): GPS fix at 20240214T181332: (36.802827, -121.787739) 2024-02-14T18:13:34.014Z,1707934414.014 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:13:34.014Z,1707934414.014 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:13:44.128Z,1707934424.128 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240214T173622/Courier0013.lzma 2024-02-14T18:13:45.130Z,1707934425.130 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Courier0013.lzma.bak 2024-02-14T18:13:45.130Z,1707934425.130 [DataOverHttps](INFO): SBD MOMSN=19278445 2024-02-14T18:14:01.949Z,1707934441.949 [DataOverHttps](INFO): Sending 292 bytes from file Logs/20240214T173622/Express0014.lzma 2024-02-14T18:14:02.951Z,1707934442.951 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0014.lzma.bak 2024-02-14T18:14:02.951Z,1707934442.951 [DataOverHttps](INFO): SBD MOMSN=19278450 2024-02-14T18:14:04.316Z,1707934444.316 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:14:04.317Z,1707934444.317 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:14:04.317Z,1707934444.317 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:14:06.314Z,1707934446.314 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:17:45.158Z,1707934665.158 [DataOverHttps](IMPORTANT): SBD MTMSN=20240214T181744 2024-02-14T18:17:52.465Z,1707934672.465 [DataOverHttps](INFO): Received command: ibit 2024-02-14T18:17:52.525Z,1707934672.525 [CommandExec](IMPORTANT): got command ibit 2024-02-14T18:17:52.604Z,1707934672.604 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-02-14T18:17:52.605Z,1707934672.605 [IBIT](IMPORTANT): Beginning control surface checks. 2024-02-14T18:17:52.608Z,1707934672.608 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-14T18:17:54.191Z,1707934674.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181752.00,A,3648.14453,N,12147.22980,W,0.039,80.99,140224,,,A*49 2024-02-14T18:17:54.204Z,1707934674.204 [NAL9602](INFO): GPS fix at 20240214T181752: (36.802409, -121.787163) 2024-02-14T18:17:55.497Z,1707934675.497 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2024-02-14T18:18:03.506Z,1707934683.506 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-02-14T18:18:03.914Z,1707934683.914 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-14T18:18:06.729Z,1707934686.729 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2024-02-14T18:18:14.865Z,1707934694.865 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-02-14T18:18:15.325Z,1707934695.325 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-14T18:18:18.137Z,1707934698.137 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2024-02-14T18:18:26.270Z,1707934706.270 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-02-14T18:18:26.270Z,1707934706.270 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule. 2024-02-14T18:18:34.680Z,1707934714.680 [NAL9602](INFO): SBD MO Status=0, MOMSN=46936, MT Status=0, MTMSN=0 2024-02-14T18:18:34.680Z,1707934714.680 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:18:35.875Z,1707934715.875 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181834.00,A,3648.14555,N,12147.22768,W,0.058,80.99,140224,,,A*4E 2024-02-14T18:18:35.878Z,1707934715.878 [NAL9602](INFO): GPS fix at 20240214T181834: (36.802426, -121.787128) 2024-02-14T18:18:38.736Z,1707934718.736 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802425 Longitude: -121.787125 2024-02-14T18:18:39.103Z,1707934719.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181837.00,A,3648.14534,N,12147.22768,W,0.078,80.99,140224,,,A*48 2024-02-14T18:18:39.106Z,1707934719.106 [NAL9602](INFO): GPS fix at 20240214T181837: (36.802422, -121.787128) 2024-02-14T18:18:39.142Z,1707934719.142 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.690001 2024-02-14T18:18:39.143Z,1707934719.143 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-02-14T18:18:39.143Z,1707934719.143 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-02-14T18:18:39.532Z,1707934719.532 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-02-14T18:18:39.532Z,1707934719.532 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-02-14T18:18:39.533Z,1707934719.533 [IBIT](IMPORTANT): Pressure:9.736602 PSI 2024-02-14T18:18:39.533Z,1707934719.533 [IBIT](IMPORTANT): Humidity:12.947136 % 2024-02-14T18:18:39.934Z,1707934719.934 [IBIT](IMPORTANT): Vehicle Pitch:-0.926392 degrees 2024-02-14T18:18:39.935Z,1707934719.935 [IBIT](IMPORTANT): Vehicle Roll:0.263867 degrees 2024-02-14T18:18:39.935Z,1707934719.935 [IBIT](IMPORTANT): Vehicle Heading:202.914658 degrees 2024-02-14T18:18:40.346Z,1707934720.346 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-02-14T18:18:40.346Z,1707934720.346 [IBIT](IMPORTANT): buoyancyNeutral: 215.727707 cc 2024-02-14T18:18:40.347Z,1707934720.347 [IBIT](IMPORTANT): massDefault: -1.124890 cm 2024-02-14T18:18:40.347Z,1707934720.347 [IBIT](IMPORTANT): stopDepth: 250.000000 m 2024-02-14T18:18:40.347Z,1707934720.347 [IBIT](IMPORTANT): abortDepth: 270.000000 m 2024-02-14T18:18:40.347Z,1707934720.347 [IBIT](IMPORTANT): IBIT PASSED 2024-02-14T18:19:05.036Z,1707934745.036 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:19:05.036Z,1707934745.036 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:19:05.036Z,1707934745.036 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:19:05.036Z,1707934745.036 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:19:05.431Z,1707934745.431 [Default:CheckIn:D] Stopped 2024-02-14T18:19:05.431Z,1707934745.431 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:19:05.843Z,1707934745.843 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.547156 min 2024-02-14T18:19:05.845Z,1707934745.845 [Default:CheckIn:E] Stopped 2024-02-14T18:19:05.845Z,1707934745.845 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:19:05.845Z,1707934745.845 [Default:CheckIn] Stopped 2024-02-14T18:19:05.845Z,1707934745.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:19:05.845Z,1707934745.845 [Default:CheckIn](INFO): Running loop #5 2024-02-14T18:19:05.845Z,1707934745.845 [Default:CheckIn] Running Loop=5 2024-02-14T18:19:05.845Z,1707934745.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:19:05.845Z,1707934745.845 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:19:07.843Z,1707934747.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181906.00,A,3648.14309,N,12147.22865,W,0.039,80.99,140224,,,A*44 2024-02-14T18:19:07.846Z,1707934747.846 [NAL9602](INFO): GPS fix at 20240214T181906: (36.802385, -121.787144) 2024-02-14T18:19:07.856Z,1707934747.856 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:19:07.856Z,1707934747.856 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:19:15.687Z,1707934755.687 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240214T173622/Courier0016.lzma 2024-02-14T18:19:16.630Z,1707934756.630 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Courier0016.lzma.bak 2024-02-14T18:19:16.631Z,1707934756.631 [DataOverHttps](INFO): SBD MOMSN=19278477 2024-02-14T18:19:32.441Z,1707934772.441 [DataOverHttps](INFO): Sending 805 bytes from file Logs/20240214T173622/Express0017.lzma 2024-02-14T18:19:33.442Z,1707934773.442 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0017.lzma.bak 2024-02-14T18:19:33.443Z,1707934773.443 [DataOverHttps](INFO): SBD MOMSN=19278483 2024-02-14T18:19:40.569Z,1707934780.569 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:19:49.280Z,1707934789.280 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240214T173622/Express0020.lzma 2024-02-14T18:19:50.282Z,1707934790.282 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0020.lzma.bak 2024-02-14T18:19:50.283Z,1707934790.283 [DataOverHttps](INFO): SBD MOMSN=19278513 2024-02-14T18:19:51.489Z,1707934791.489 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:19:51.489Z,1707934791.489 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:19:51.489Z,1707934791.489 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:22:00.674Z,1707934920.674 [DataOverHttps](IMPORTANT): SBD MTMSN=20240214T182159 2024-02-14T18:22:07.976Z,1707934927.976 [DataOverHttps](INFO): Received command: gfscan 2024-02-14T18:22:07.978Z,1707934927.978 [CommandExec](IMPORTANT): got command gfscan 2024-02-14T18:22:08.070Z,1707934928.070 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-14T18:22:10.915Z,1707934930.915 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2024-02-14T18:22:19.011Z,1707934939.011 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002553 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002509 CHAN A3 (5V): -0.001365 CHAN B0 (3.3V): -0.000471 CHAN B1 (3.15aV): -0.000064 CHAN B2 (3.15bV): -0.000361 CHAN B3 (GND): -0.000412 OPEN: -0.000177 Full Scale: +/- 1 mA 2024-02-14T18:24:52.108Z,1707935092.108 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:24:52.108Z,1707935092.108 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:24:52.108Z,1707935092.108 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:24:52.108Z,1707935092.108 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:24:52.531Z,1707935092.531 [Default:CheckIn:D] Stopped 2024-02-14T18:24:52.531Z,1707935092.531 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:24:52.937Z,1707935092.937 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.332157 min 2024-02-14T18:24:52.937Z,1707935092.937 [Default:CheckIn:E] Stopped 2024-02-14T18:24:52.937Z,1707935092.937 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:24:52.937Z,1707935092.937 [Default:CheckIn] Stopped 2024-02-14T18:24:52.937Z,1707935092.937 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:24:52.937Z,1707935092.937 [Default:CheckIn](INFO): Running loop #6 2024-02-14T18:24:52.937Z,1707935092.937 [Default:CheckIn] Running Loop=6 2024-02-14T18:24:52.937Z,1707935092.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:24:52.937Z,1707935092.937 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:24:54.928Z,1707935094.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182454.00,A,3648.14309,N,12147.22781,W,0.019,80.99,140224,,,A*4A 2024-02-14T18:24:54.935Z,1707935094.935 [NAL9602](INFO): GPS fix at 20240214T182454: (36.802385, -121.787130) 2024-02-14T18:24:54.950Z,1707935094.950 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:24:54.950Z,1707935094.950 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:25:01.920Z,1707935101.920 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240214T173622/Courier0022.lzma 2024-02-14T18:25:02.922Z,1707935102.922 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Courier0022.lzma.bak 2024-02-14T18:25:02.923Z,1707935102.923 [DataOverHttps](INFO): SBD MOMSN=19278568 2024-02-14T18:25:12.709Z,1707935112.709 [NAL9602](INFO): SBD MO Status=0, MOMSN=46937, MT Status=0, MTMSN=0 2024-02-14T18:25:12.709Z,1707935112.709 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:25:18.721Z,1707935118.721 [DataOverHttps](INFO): Sending 373 bytes from file Logs/20240214T173622/Express0023.lzma 2024-02-14T18:25:19.723Z,1707935119.723 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0023.lzma.bak 2024-02-14T18:25:19.723Z,1707935119.723 [DataOverHttps](IMPORTANT): SBD MOMSN=19278576, MTMSN=20240214T182518 2024-02-14T18:25:20.798Z,1707935120.798 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:25:20.798Z,1707935120.798 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:25:20.798Z,1707935120.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:25:27.016Z,1707935127.016 [DataOverHttps](INFO): Received command: failc 2024-02-14T18:25:27.089Z,1707935127.089 [CommandExec](IMPORTANT): got command failComponent 2024-02-14T18:25:27.089Z,1707935127.089 [CommandExec](IMPORTANT): Failed components: 2024-02-14T18:25:27.089Z,1707935127.089 [CommandExec](IMPORTANT): No failed Components. 2024-02-14T18:25:43.418Z,1707935143.418 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:28:31.810Z,1707935311.810 [DataOverHttps](IMPORTANT): SBD MTMSN=20240214T182830 2024-02-14T18:28:39.140Z,1707935319.140 [DataOverHttps](INFO): Received command: get platform_battery_charge 2024-02-14T18:28:39.857Z,1707935319.857 [CommandExec](IMPORTANT): got command get platform_battery_charge 2024-02-14T18:28:39.858Z,1707935319.858 [CommandExec](FAULT): Element has no value 2024-02-14T18:30:21.456Z,1707935421.456 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:30:21.456Z,1707935421.456 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:30:21.456Z,1707935421.456 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:30:21.456Z,1707935421.456 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:30:21.883Z,1707935421.883 [Default:CheckIn:D] Stopped 2024-02-14T18:30:21.883Z,1707935421.883 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:30:22.263Z,1707935422.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.821350 min 2024-02-14T18:30:22.263Z,1707935422.263 [Default:CheckIn:E] Stopped 2024-02-14T18:30:22.263Z,1707935422.263 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:30:22.263Z,1707935422.263 [Default:CheckIn] Stopped 2024-02-14T18:30:22.263Z,1707935422.263 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:30:22.263Z,1707935422.263 [Default:CheckIn](INFO): Running loop #7 2024-02-14T18:30:22.264Z,1707935422.264 [Default:CheckIn] Running Loop=7 2024-02-14T18:30:22.264Z,1707935422.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:30:22.264Z,1707935422.264 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:30:24.283Z,1707935424.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183023.00,A,3648.14348,N,12147.22863,W,0.000,80.99,140224,,,A*41 2024-02-14T18:30:24.286Z,1707935424.286 [NAL9602](INFO): GPS fix at 20240214T183023: (36.802391, -121.787144) 2024-02-14T18:30:24.304Z,1707935424.304 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:30:24.304Z,1707935424.304 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:30:32.341Z,1707935432.341 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240214T173622/Courier0025.lzma 2024-02-14T18:30:33.342Z,1707935433.342 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Courier0025.lzma.bak 2024-02-14T18:30:33.343Z,1707935433.343 [DataOverHttps](INFO): SBD MOMSN=19278646 2024-02-14T18:30:40.043Z,1707935440.043 [NAL9602](INFO): SBD MO Status=0, MOMSN=46938, MT Status=0, MTMSN=0 2024-02-14T18:30:40.043Z,1707935440.043 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:30:49.873Z,1707935449.873 [DataOverHttps](INFO): Sending 319 bytes from file Logs/20240214T173622/Express0027.lzma 2024-02-14T18:30:50.875Z,1707935450.875 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0027.lzma.bak 2024-02-14T18:30:50.875Z,1707935450.875 [DataOverHttps](INFO): SBD MOMSN=19278652 2024-02-14T18:30:52.191Z,1707935452.191 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:30:52.192Z,1707935452.192 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:30:52.192Z,1707935452.192 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:31:10.764Z,1707935470.764 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:33:01.634Z,1707935581.634 [DataOverHttps](IMPORTANT): SBD MTMSN=20240214T183300 2024-02-14T18:33:08.980Z,1707935588.980 [DataOverHttps](INFO): Received command: failc h bpc1; failc n bpc1 2024-02-14T18:33:09.042Z,1707935589.042 [CommandExec](IMPORTANT): got command failComponent hardware BPC1 2024-02-14T18:33:09.042Z,1707935589.042 [BPC1] Hardware Fault, FailCount= 1 2024-02-14T18:33:09.042Z,1707935589.042 [BPC1](ERROR): Hardware Fault 2024-02-14T18:33:09.043Z,1707935589.043 [CommandExec](IMPORTANT): BPC1 failureMode is Hardware Fault 2024-02-14T18:33:09.043Z,1707935589.043 [CommandExec](IMPORTANT): got command failComponent none BPC1 2024-02-14T18:33:09.043Z,1707935589.043 [BPC1] No Fault, FailCount= 1 2024-02-14T18:33:09.044Z,1707935589.044 [CommandExec](IMPORTANT): BPC1 failureMode is No Fault 2024-02-14T18:35:52.815Z,1707935752.815 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:35:52.815Z,1707935752.815 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:35:52.815Z,1707935752.815 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:35:52.815Z,1707935752.815 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:35:53.216Z,1707935753.216 [Default:CheckIn:D] Stopped 2024-02-14T18:35:53.216Z,1707935753.216 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:35:53.632Z,1707935753.632 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.343571 min 2024-02-14T18:35:53.632Z,1707935753.632 [Default:CheckIn:E] Stopped 2024-02-14T18:35:53.637Z,1707935753.637 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:35:53.637Z,1707935753.637 [Default:CheckIn] Stopped 2024-02-14T18:35:53.637Z,1707935753.637 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:35:53.637Z,1707935753.637 [Default:CheckIn](INFO): Running loop #8 2024-02-14T18:35:53.637Z,1707935753.637 [Default:CheckIn] Running Loop=8 2024-02-14T18:35:53.637Z,1707935753.637 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:35:53.637Z,1707935753.637 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:35:55.636Z,1707935755.636 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183554.00,A,3648.14401,N,12147.22799,W,0.039,80.99,140224,,,A*4E 2024-02-14T18:35:55.638Z,1707935755.638 [NAL9602](INFO): GPS fix at 20240214T183554: (36.802400, -121.787133) 2024-02-14T18:35:55.653Z,1707935755.653 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:35:55.653Z,1707935755.653 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:36:02.780Z,1707935762.780 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240214T173622/Courier0032.lzma 2024-02-14T18:36:03.782Z,1707935763.782 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Courier0032.lzma.bak 2024-02-14T18:36:03.783Z,1707935763.783 [DataOverHttps](INFO): SBD MOMSN=19278679 2024-02-14T18:36:21.197Z,1707935781.197 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20240214T173622/Express0034.lzma 2024-02-14T18:36:22.199Z,1707935782.199 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0034.lzma.bak 2024-02-14T18:36:22.199Z,1707935782.199 [DataOverHttps](INFO): SBD MOMSN=19278685 2024-02-14T18:36:39.784Z,1707935799.784 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20240214T173622/Express0035.lzma 2024-02-14T18:36:40.787Z,1707935800.787 [DataOverHttps](INFO): Moved sent file to Logs/20240214T173622/Express0035.lzma.bak 2024-02-14T18:36:40.787Z,1707935800.787 [DataOverHttps](INFO): SBD MOMSN=19278696 2024-02-14T18:36:42.129Z,1707935802.129 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:36:42.129Z,1707935802.129 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:36:42.129Z,1707935802.129 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:36:48.583Z,1707935808.583 [NAL9602](INFO): SBD MO Status=0, MOMSN=46939, MT Status=0, MTMSN=0 2024-02-14T18:36:48.583Z,1707935808.583 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:37:19.289Z,1707935839.289 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:39:55.422Z,1707935995.422 [CommandExec](IMPORTANT): got command restart application 2024-02-14T18:39:56.425Z,1707935996.425 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-02-14T18:39:56.425Z,1707935996.425 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:39:56.425Z,1707935996.425 [CommandExec](INFO): Uninitializing the command executive. 2024-02-14T18:39:56.425Z,1707935996.425 [CommandExec](INFO): Uninitializing the command scheduler. 2024-02-14T18:39:56.425Z,1707935996.425 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:56.497Z,1707935996.497 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-02-14T18:39:56.497Z,1707935996.497 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-02-14T18:39:56.497Z,1707935996.497 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:56.498Z,1707935996.498 [NavChartDb](INFO): Join timeout helper Thread ID is 1327 2024-02-14T18:39:56.737Z,1707935996.737 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:39:56.738Z,1707935996.738 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:56.757Z,1707935996.757 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-02-14T18:39:56.757Z,1707935996.757 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:56.757Z,1707935996.757 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1328 2024-02-14T18:39:56.906Z,1707935996.906 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:39:56.909Z,1707935996.909 [WetLabsBB2FL](INFO): Powering down 2024-02-14T18:39:56.910Z,1707935996.910 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:56.910Z,1707935996.910 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-02-14T18:39:56.910Z,1707935996.910 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:56.911Z,1707935996.911 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1329 2024-02-14T18:39:57.777Z,1707935997.777 [CTD_Seabird](INFO): Powering down 2024-02-14T18:39:57.789Z,1707935997.789 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:39:57.789Z,1707935997.789 [CTD_Seabird](INFO): Powering down 2024-02-14T18:39:57.801Z,1707935997.801 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:57.817Z,1707935997.817 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-02-14T18:39:57.817Z,1707935997.817 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:57.817Z,1707935997.817 [Radio_Surface](INFO): Join timeout helper Thread ID is 1330 2024-02-14T18:39:57.849Z,1707935997.849 [Radio_Surface](INFO): Powering down 2024-02-14T18:39:57.850Z,1707935997.850 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:39:57.850Z,1707935997.850 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:57.865Z,1707935997.865 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-02-14T18:39:57.865Z,1707935997.865 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:39:57.866Z,1707935997.866 [Onboard](INFO): Join timeout helper Thread ID is 1331 2024-02-14T18:39:58.837Z,1707935998.837 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-02-14T18:40:00.789Z,1707936000.789 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:40:00.789Z,1707936000.789 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:00.801Z,1707936000.801 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-02-14T18:40:00.802Z,1707936000.802 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:00.802Z,1707936000.802 [DataOverHttps](INFO): Join timeout helper Thread ID is 1332 2024-02-14T18:40:00.845Z,1707936000.845 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:40:00.845Z,1707936000.845 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:00.849Z,1707936000.849 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-02-14T18:40:00.850Z,1707936000.850 [logger ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:00.850Z,1707936000.850 [logger](INFO): Join timeout helper Thread ID is 1333 2024-02-14T18:40:00.859Z,1707936000.859 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:40:00.860Z,1707936000.860 [logger ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:00.869Z,1707936000.869 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-02-14T18:40:00.869Z,1707936000.869 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:00.869Z,1707936000.869 [CommandLine](INFO): Join timeout helper Thread ID is 1334 2024-02-14T18:40:00.929Z,1707936000.929 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:40:00.929Z,1707936000.929 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:00.930Z,1707936000.930 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-02-14T18:40:00.931Z,1707936000.931 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:00.931Z,1707936000.931 [CommandExec](INFO): Join timeout helper Thread ID is 1335 2024-02-14T18:40:00.936Z,1707936000.936 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-02-14T18:40:00.936Z,1707936000.936 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:00.937Z,1707936000.937 [controlThread](INFO): Join timeout helper Thread ID is 1336 2024-02-14T18:40:01.293Z,1707936001.293 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-14T18:40:01.293Z,1707936001.293 [controlThread](DEBUG): Uninitializing ControlThread 2024-02-14T18:40:01.293Z,1707936001.293 [AHRS_M2](INFO): Powering down 2024-02-14T18:40:01.365Z,1707936001.365 [NAL9602](INFO): Powering down 2024-02-14T18:40:01.367Z,1707936001.367 [Sonardyne_Nano](INFO): Powering down 2024-02-14T18:40:01.486Z,1707936001.486 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-02-14T18:40:01.487Z,1707936001.487 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-02-14T18:40:01.487Z,1707936001.487 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-02-14T18:40:01.488Z,1707936001.488 [MissionManager](INFO): Uninitializing Mission Default 2024-02-14T18:40:01.488Z,1707936001.488 [Default] Stopped 2024-02-14T18:40:01.488Z,1707936001.488 [Default](DEBUG): Aggregate::uninitialize Default 2024-02-14T18:40:01.488Z,1707936001.488 [Default:B.GoToSurface] Stopped 2024-02-14T18:40:01.488Z,1707936001.488 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-02-14T18:40:01.488Z,1707936001.488 [Default:CheckIn] Stopped 2024-02-14T18:40:01.489Z,1707936001.489 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:40:01.489Z,1707936001.489 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:40:01.489Z,1707936001.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:40:01.491Z,1707936001.491 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-02-14T18:40:01.492Z,1707936001.492 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-02-14T18:40:01.492Z,1707936001.492 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-02-14T18:40:01.492Z,1707936001.492 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-02-14T18:40:01.493Z,1707936001.493 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-02-14T18:40:01.493Z,1707936001.493 [BuoyancyServo](INFO): Powering down 2024-02-14T18:40:01.505Z,1707936001.505 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-02-14T18:40:01.505Z,1707936001.505 [ElevatorServo](INFO): Powering down 2024-02-14T18:40:01.506Z,1707936001.506 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-02-14T18:40:01.506Z,1707936001.506 [MassServo](INFO): Powering down 2024-02-14T18:40:01.507Z,1707936001.507 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-02-14T18:40:01.507Z,1707936001.507 [RudderServo](INFO): Powering down 2024-02-14T18:40:01.507Z,1707936001.507 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-02-14T18:40:01.508Z,1707936001.508 [ThrusterHE](INFO): Powering down 2024-02-14T18:40:01.508Z,1707936001.508 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-02-14T18:40:01.509Z,1707936001.509 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-02-14T18:40:01.509Z,1707936001.509 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-02-14T18:40:01.509Z,1707936001.509 [CBIT](DEBUG): Powering off loads. 2024-02-14T18:40:01.520Z,1707936001.520 [CBIT](DEBUG): Disabling WDT. 2024-02-14T18:40:01.532Z,1707936001.532 [CBIT](DEBUG): Opening all GF detection circuits. 2024-02-14T18:40:01.533Z,1707936001.533 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:01.575Z,1707936001.575 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:01.578Z,1707936001.578 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:01.583Z,1707936001.583 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:01.655Z,1707936001.655 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:01.659Z,1707936001.659 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:01.700Z,1707936001.700 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-02-14T18:40:01.776Z,1707936001.776 [logger ThreadHandler](INFO): Thread cancelled.