2023-10-31T21:44:44.122Z,1698788684.122 [Supervisor](DEBUG): Initializing supervisor. 2023-10-31T21:44:44.126Z,1698788684.126 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-10-31T21:44:44.127Z,1698788684.127 [SyncHandler](INFO): Protected caller Thread ID is 4867 2023-10-31T21:44:44.127Z,1698788684.127 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-10-31T21:44:44.128Z,1698788684.128 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-10-31T21:44:44.129Z,1698788684.129 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4868 2023-10-31T21:44:44.132Z,1698788684.132 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-10-31T21:44:44.150Z,1698788684.150 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-10-31T21:44:44.151Z,1698788684.151 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-10-31T21:44:44.151Z,1698788684.151 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4869 2023-10-31T21:44:44.155Z,1698788684.155 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-10-31T21:44:44.156Z,1698788684.156 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-10-31T21:44:44.156Z,1698788684.156 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4870 2023-10-31T21:44:44.159Z,1698788684.159 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-10-31T21:44:44.160Z,1698788684.160 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-10-31T21:44:44.160Z,1698788684.160 [logger ThreadHandler](INFO): Protected caller Thread ID is 4871 2023-10-31T21:44:44.164Z,1698788684.164 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-10-31T21:44:44.164Z,1698788684.164 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-10-31T21:44:44.168Z,1698788684.168 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-10-31T21:44:44.436Z,1698788684.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-10-31T21:44:44.438Z,1698788684.438 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-10-31T21:44:44.546Z,1698788684.546 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-10-31T21:44:44.548Z,1698788684.548 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-10-31T21:44:44.643Z,1698788684.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-10-31T21:44:44.645Z,1698788684.645 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-10-31T21:44:45.180Z,1698788685.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-10-31T21:44:45.182Z,1698788685.182 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-10-31T21:44:45.516Z,1698788685.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-10-31T21:44:45.516Z,1698788685.516 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-10-31T21:44:45.831Z,1698788685.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-10-31T21:44:45.832Z,1698788685.832 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-10-31T21:44:46.012Z,1698788686.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-10-31T21:44:46.013Z,1698788686.013 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-10-31T21:44:46.104Z,1698788686.104 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-10-31T21:44:46.188Z,1698788686.188 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-10-31T21:44:46.574Z,1698788686.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-10-31T21:44:46.574Z,1698788686.574 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-10-31T21:44:46.847Z,1698788686.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-10-31T21:44:46.848Z,1698788686.848 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-10-31T21:44:47.348Z,1698788687.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-10-31T21:44:47.350Z,1698788687.350 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-10-31T21:44:47.567Z,1698788687.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-10-31T21:44:47.567Z,1698788687.567 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-10-31T21:44:47.795Z,1698788687.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-10-31T21:44:47.797Z,1698788687.797 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-10-31T21:44:47.937Z,1698788687.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-10-31T21:44:47.937Z,1698788687.937 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-10-31T21:44:48.723Z,1698788688.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-10-31T21:44:48.725Z,1698788688.725 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2023-10-31T21:44:48.728Z,1698788688.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2023-10-31T21:44:48.815Z,1698788688.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2023-10-31T21:44:48.947Z,1698788688.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2023-10-31T21:44:49.432Z,1698788689.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-10-31T21:44:49.433Z,1698788689.433 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2023-10-31T21:44:49.868Z,1698788689.868 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2023-10-31T21:44:49.971Z,1698788689.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2023-10-31T21:44:50.065Z,1698788690.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2023-10-31T21:44:50.175Z,1698788690.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2023-10-31T21:44:50.272Z,1698788690.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2023-10-31T21:44:50.360Z,1698788690.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2023-10-31T21:44:50.461Z,1698788690.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2023-10-31T21:44:50.624Z,1698788690.624 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2023-10-31T21:44:50.624Z,1698788690.624 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-10-31T21:44:50.646Z,1698788690.646 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-10-31T21:44:50.712Z,1698788690.712 [DepthRateCalculator] Loaded 2023-10-31T21:44:50.712Z,1698788690.712 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-10-31T21:44:50.717Z,1698788690.717 [PitchRateCalculator] Loaded 2023-10-31T21:44:50.718Z,1698788690.718 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-10-31T21:44:50.728Z,1698788690.728 [SpeedCalculator] Loaded 2023-10-31T21:44:50.728Z,1698788690.728 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-10-31T21:44:50.733Z,1698788690.733 [YawRateCalculator] Loaded 2023-10-31T21:44:50.733Z,1698788690.733 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-10-31T21:44:50.754Z,1698788690.754 [ElevatorOffsetCalculator] Loaded 2023-10-31T21:44:50.754Z,1698788690.754 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-10-31T21:44:50.755Z,1698788690.755 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-10-31T21:44:50.755Z,1698788690.755 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-10-31T21:44:50.793Z,1698788690.793 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-10-31T21:44:50.793Z,1698788690.793 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-10-31T21:44:50.806Z,1698788690.806 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-10-31T21:44:50.807Z,1698788690.807 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-10-31T21:44:51.375Z,1698788691.375 [AHRS_M2] Loaded 2023-10-31T21:44:51.376Z,1698788691.376 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-10-31T21:44:51.410Z,1698788691.410 [BackseatComponent] Loaded 2023-10-31T21:44:51.411Z,1698788691.411 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-10-31T21:44:51.414Z,1698788691.414 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0 2023-10-31T21:44:51.415Z,1698788691.415 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4954 2023-10-31T21:44:51.418Z,1698788691.418 [LcmUniversalReporter] Loaded 2023-10-31T21:44:51.418Z,1698788691.418 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-10-31T21:44:52.204Z,1698788692.204 [BPC1] Loaded 2023-10-31T21:44:52.205Z,1698788692.205 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-10-31T21:44:52.276Z,1698788692.276 [DataOverHttps] Loaded 2023-10-31T21:44:52.276Z,1698788692.276 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-10-31T21:44:52.279Z,1698788692.279 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0 2023-10-31T21:44:52.279Z,1698788692.279 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4955 2023-10-31T21:44:52.299Z,1698788692.299 [Depth_Keller] Loaded 2023-10-31T21:44:52.299Z,1698788692.299 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-10-31T21:44:52.304Z,1698788692.304 [DropWeight] Loaded 2023-10-31T21:44:52.304Z,1698788692.304 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-10-31T21:44:52.323Z,1698788692.323 [MultiRay] Loaded 2023-10-31T21:44:52.323Z,1698788692.323 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2023-10-31T21:44:52.384Z,1698788692.384 [NAL9602] Loaded 2023-10-31T21:44:52.384Z,1698788692.384 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-10-31T21:44:52.414Z,1698788692.414 [Onboard] Loaded 2023-10-31T21:44:52.414Z,1698788692.414 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-10-31T21:44:52.415Z,1698788692.415 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0 2023-10-31T21:44:52.415Z,1698788692.415 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4956 2023-10-31T21:44:52.421Z,1698788692.421 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2023-10-31T21:44:52.432Z,1698788692.432 [PowerOnly] Loaded 2023-10-31T21:44:52.432Z,1698788692.432 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-10-31T21:44:52.444Z,1698788692.444 [Power24vConverter] Loaded 2023-10-31T21:44:52.444Z,1698788692.444 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-10-31T21:44:52.458Z,1698788692.458 [Radio_Surface] Loaded 2023-10-31T21:44:52.458Z,1698788692.458 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-10-31T21:44:52.459Z,1698788692.459 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0 2023-10-31T21:44:52.460Z,1698788692.460 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4957 2023-10-31T21:44:52.461Z,1698788692.461 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-10-31T21:44:52.461Z,1698788692.461 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-10-31T21:44:52.590Z,1698788692.590 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-10-31T21:44:52.591Z,1698788692.591 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-10-31T21:44:52.632Z,1698788692.632 [DeadReckonUsingSpeedCalculator] Loaded 2023-10-31T21:44:52.632Z,1698788692.632 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-10-31T21:44:52.645Z,1698788692.645 [NavChart] Loaded 2023-10-31T21:44:52.646Z,1698788692.646 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-10-31T21:44:52.651Z,1698788692.651 [UniversalFixResidualReporter] Loaded 2023-10-31T21:44:52.651Z,1698788692.651 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-10-31T21:44:52.652Z,1698788692.652 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-10-31T21:44:52.653Z,1698788692.653 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-10-31T21:44:52.806Z,1698788692.806 [SBIT](DEBUG): Construct Startup Built In Test. 2023-10-31T21:44:52.820Z,1698788692.820 [SBIT] Loaded 2023-10-31T21:44:52.821Z,1698788692.821 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-10-31T21:44:52.824Z,1698788692.824 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-10-31T21:44:52.836Z,1698788692.836 [IBIT] Loaded 2023-10-31T21:44:52.836Z,1698788692.836 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-10-31T21:44:52.842Z,1698788692.842 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-10-31T21:44:52.942Z,1698788692.942 [CBIT] Loaded 2023-10-31T21:44:52.943Z,1698788692.943 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-10-31T21:44:52.943Z,1698788692.943 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-10-31T21:44:52.944Z,1698788692.944 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-10-31T21:44:53.151Z,1698788693.151 [CTD_Seabird] Loaded 2023-10-31T21:44:53.152Z,1698788693.152 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-10-31T21:44:53.156Z,1698788693.156 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0 2023-10-31T21:44:53.156Z,1698788693.156 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4958 2023-10-31T21:44:53.177Z,1698788693.177 [PAR_Licor] Loaded 2023-10-31T21:44:53.177Z,1698788693.177 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-10-31T21:44:53.217Z,1698788693.217 [WetLabsBB2FL] Loaded 2023-10-31T21:44:53.217Z,1698788693.217 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-10-31T21:44:53.218Z,1698788693.218 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0 2023-10-31T21:44:53.218Z,1698788693.218 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4959 2023-10-31T21:44:53.219Z,1698788693.219 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-10-31T21:44:53.220Z,1698788693.220 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-10-31T21:44:53.372Z,1698788693.372 [BuoyancyServo] Loaded 2023-10-31T21:44:53.373Z,1698788693.373 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-10-31T21:44:53.394Z,1698788693.394 [ElevatorServo] Loaded 2023-10-31T21:44:53.394Z,1698788693.394 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-10-31T21:44:53.416Z,1698788693.416 [MassServo] Loaded 2023-10-31T21:44:53.416Z,1698788693.416 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-10-31T21:44:53.436Z,1698788693.436 [RudderServo] Loaded 2023-10-31T21:44:53.436Z,1698788693.436 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-10-31T21:44:53.452Z,1698788693.452 [ThrusterHE] Loaded 2023-10-31T21:44:53.452Z,1698788693.452 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-10-31T21:44:53.452Z,1698788693.452 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-10-31T21:44:53.453Z,1698788693.453 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-10-31T21:44:53.512Z,1698788693.512 [VerticalControl](DEBUG): Construct VerticalControl. 2023-10-31T21:44:53.561Z,1698788693.561 [VerticalControl] Loaded 2023-10-31T21:44:53.561Z,1698788693.561 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-10-31T21:44:53.564Z,1698788693.564 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-10-31T21:44:53.601Z,1698788693.601 [HorizontalControl] Loaded 2023-10-31T21:44:53.601Z,1698788693.601 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-10-31T21:44:53.604Z,1698788693.604 [SpeedControl](DEBUG): Construct SpeedControl. 2023-10-31T21:44:53.605Z,1698788693.605 [SpeedControl] Loaded 2023-10-31T21:44:53.605Z,1698788693.605 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-10-31T21:44:53.608Z,1698788693.608 [LoopControl](DEBUG): Construct LoopControl. 2023-10-31T21:44:53.609Z,1698788693.609 [LoopControl] Loaded 2023-10-31T21:44:53.609Z,1698788693.609 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-10-31T21:44:53.609Z,1698788693.609 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-10-31T21:44:53.610Z,1698788693.610 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-10-31T21:44:53.730Z,1698788693.730 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-10-31T21:44:53.730Z,1698788693.730 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-10-31T21:44:53.902Z,1698788693.902 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-10-31T21:44:53.902Z,1698788693.902 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-10-31T21:44:54.238Z,1698788694.238 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-10-31T21:44:54.239Z,1698788694.239 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-10-31T21:44:54.331Z,1698788694.331 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC 2023-10-31T21:44:54.331Z,1698788694.331 [StratificationFrontDetector](DEBUG): (re)initializing 2023-10-31T21:44:54.331Z,1698788694.331 [StratificationFrontDetector] Loaded 2023-10-31T21:44:54.331Z,1698788694.331 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2023-10-31T21:44:54.332Z,1698788694.332 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-10-31T21:44:54.338Z,1698788694.338 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-10-31T21:44:54.341Z,1698788694.341 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-10-31T21:44:54.352Z,1698788694.352 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-10-31T21:44:54.355Z,1698788694.355 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0 2023-10-31T21:44:54.355Z,1698788694.355 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4960 2023-10-31T21:44:54.360Z,1698788694.360 [Supervisor](INFO): Main Thread ID is 4866 2023-10-31T21:44:54.360Z,1698788694.360 [Supervisor](DEBUG): Running supervisor. 2023-10-31T21:44:54.361Z,1698788694.361 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4961 2023-10-31T21:44:54.361Z,1698788694.361 [CommandExec](INFO): Initializing the command executive. 2023-10-31T21:44:54.363Z,1698788694.363 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4962 2023-10-31T21:44:54.365Z,1698788694.365 [controlThread ThreadHandler](INFO): Handler Thread ID is 4963 2023-10-31T21:44:54.365Z,1698788694.365 [controlThread](DEBUG): Initializing ControlThread 2023-10-31T21:44:54.366Z,1698788694.366 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-10-31T21:44:54.367Z,1698788694.367 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-10-31T21:44:54.367Z,1698788694.367 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-10-31T21:44:54.367Z,1698788694.367 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-10-31T21:44:54.368Z,1698788694.368 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-10-31T21:44:54.373Z,1698788694.373 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T21:44:54.374Z,1698788694.374 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-10-31T21:44:54.374Z,1698788694.374 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-10-31T21:44:54.375Z,1698788694.375 [SBIT](INFO): Initialize SBIT Component. 2023-10-31T21:44:54.375Z,1698788694.375 [SBIT](IMPORTANT): git: 2023-09-18-3-g7ee55e0de 2023-10-31T21:44:54.375Z,1698788694.375 [SBIT](INFO): git hash: 7ee55e0ded82548f5c252ba0d9a41930617440e4 2023-10-31T21:44:54.376Z,1698788694.376 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-10-31T21:44:54.377Z,1698788694.377 [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 2023-10-31T21:44:54.378Z,1698788694.378 [SBIT](INFO): Beginning SBIT in 26.000000 seconds. 2023-10-31T21:44:54.378Z,1698788694.378 [IBIT](INFO): Initialize IBIT Component. 2023-10-31T21:44:54.379Z,1698788694.379 [CBIT](DEBUG): Initialize CBIT Component. 2023-10-31T21:44:54.380Z,1698788694.380 [logger ThreadHandler](INFO): Handler Thread ID is 4964 2023-10-31T21:44:54.390Z,1698788694.390 [CBIT](DEBUG): Initialized mux pins. 2023-10-31T21:44:54.390Z,1698788694.390 [CBIT](DEBUG): Initializing the watchdog timer. 2023-10-31T21:44:54.399Z,1698788694.399 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4965 2023-10-31T21:44:54.411Z,1698788694.411 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4966 2023-10-31T21:44:54.411Z,1698788694.411 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-10-31T21:44:54.414Z,1698788694.414 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-10-31T21:44:54.415Z,1698788694.415 [CBIT](DEBUG): Initializing heartbeat. 2023-10-31T21:44:54.423Z,1698788694.423 [Onboard ThreadHandler](INFO): Handler Thread ID is 4967 2023-10-31T21:44:54.446Z,1698788694.446 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4968 2023-10-31T21:44:54.463Z,1698788694.463 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4969 2023-10-31T21:44:54.464Z,1698788694.464 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-10-31T21:44:54.468Z,1698788694.468 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4971 2023-10-31T21:44:54.471Z,1698788694.471 [WetLabsBB2FL](INFO): Powering up 2023-10-31T21:44:54.479Z,1698788694.479 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4973 2023-10-31T21:44:54.486Z,1698788694.486 [CBIT](DEBUG): Deactivating GF circuits. 2023-10-31T21:44:54.486Z,1698788694.486 [CBIT](DEBUG): Deactivating emergency mode. 2023-10-31T21:44:54.487Z,1698788694.487 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-10-31T21:44:54.488Z,1698788694.488 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-10-31T21:44:54.488Z,1698788694.488 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-10-31T21:44:54.488Z,1698788694.488 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-10-31T21:44:54.488Z,1698788694.488 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-10-31T21:44:54.488Z,1698788694.488 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-10-31T21:44:54.488Z,1698788694.488 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-10-31T21:44:54.489Z,1698788694.489 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-10-31T21:44:54.526Z,1698788694.526 [CBIT](DEBUG): Backplane powered. 2023-10-31T21:44:54.532Z,1698788694.532 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-10-31T21:44:54.533Z,1698788694.533 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-10-31T21:44:54.534Z,1698788694.534 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-10-31T21:44:54.535Z,1698788694.535 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-10-31T21:44:54.536Z,1698788694.536 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-10-31T21:44:54.717Z,1698788694.717 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-31T21:44:54.729Z,1698788694.729 [MissionManager](DEBUG): 2023-10-31T21:44:54.730Z,1698788694.730 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-10-31T21:44:54.827Z,1698788694.827 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-10-31T21:44:54.829Z,1698788694.829 [Default:A.Wait](DEBUG): Construct Wait. 2023-10-31T21:44:54.831Z,1698788694.831 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-31T21:44:54.850Z,1698788694.850 [Radio_Surface](INFO): Powering up 2023-10-31T21:44:54.855Z,1698788694.855 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-10-31T21:44:54.862Z,1698788694.862 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-10-31T21:44:54.868Z,1698788694.868 [Default:E.Execute](DEBUG): Construct Execute. 2023-10-31T21:44:54.871Z,1698788694.871 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-10-31T21:44:54.893Z,1698788694.893 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-10-31T21:44:54.913Z,1698788694.913 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-10-31T21:44:54.976Z,1698788694.976 [MultiRay](INFO): Powering up MultiRay Lights 2023-10-31T21:44:55.008Z,1698788695.008 [Power24vConverter](INFO): Powering up. 2023-10-31T21:44:55.032Z,1698788695.032 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-10-31T21:44:55.038Z,1698788695.038 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-10-31T21:44:55.040Z,1698788695.040 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-10-31T21:44:55.046Z,1698788695.046 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-10-31T21:44:55.047Z,1698788695.047 [MassServo](DEBUG): Initializing EZServoServo. 2023-10-31T21:44:55.054Z,1698788695.054 [MassServo](DEBUG): Initializing MassServo. 2023-10-31T21:44:55.055Z,1698788695.055 [RudderServo](DEBUG): Initializing EZServoServo. 2023-10-31T21:44:55.062Z,1698788695.062 [RudderServo](DEBUG): Initializing RudderServo. 2023-10-31T21:44:55.063Z,1698788695.063 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-10-31T21:44:55.070Z,1698788695.070 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-10-31T21:44:55.332Z,1698788695.332 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-10-31T21:44:55.332Z,1698788695.332 [DropWeight] Hardware Fault, FailCount= 1 2023-10-31T21:44:55.332Z,1698788695.332 [DropWeight](ERROR): Hardware Fault 2023-10-31T21:44:55.388Z,1698788695.388 [CommandExec](FAULT): Scheduling is paused 2023-10-31T21:44:55.389Z,1698788695.389 [CBIT](INFO): Critical error at 20231031T214455 2023-10-31T21:44:55.389Z,1698788695.389 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-10-31T21:44:55.395Z,1698788695.395 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-10-31T21:44:55.396Z,1698788695.396 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-10-31T21:44:55.740Z,1698788695.740 [CBIT](INFO): Critical error at 20231031T214455 2023-10-31T21:44:57.082Z,1698788697.082 [WetLabsBB2FL](INFO): Powering down 2023-10-31T21:45:00.715Z,1698788700.715 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-10-31T21:45:01.495Z,1698788701.495 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-10-31T21:45:20.857Z,1698788720.857 [SBIT](IMPORTANT): Beginning Startup BIT 2023-10-31T21:45:20.878Z,1698788720.878 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-31T21:45:22.456Z,1698788722.456 [NAL9602](INFO): Powering up NAL9602 2023-10-31T21:45:32.219Z,1698788732.219 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.012621 CHAN A1 (24V): 0.002856 CHAN A2 (12V): -0.005114 CHAN A3 (5V): -0.002976 CHAN B0 (3.3V): -0.001051 CHAN B1 (3.15aV): -0.001699 CHAN B2 (3.15bV): -0.001640 CHAN B3 (GND): -0.000689 OPEN: 0.004493 Full Scale: +/- 1 mA 2023-10-31T21:45:33.368Z,1698788733.368 [NAL9602](INFO): NAL9602 initialized 2023-10-31T21:46:15.002Z,1698788775.002 [SBIT](IMPORTANT): SBIT PASSED 2023-10-31T21:46:15.002Z,1698788775.002 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-10-31T21:46:15.003Z,1698788775.003 [SBIT](IMPORTANT): BackseatComponent.missionCritical=1 bool; 2023-10-31T21:46:15.003Z,1698788775.003 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour; 2023-10-31T21:46:15.003Z,1698788775.003 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute; 2023-10-31T21:46:15.003Z,1698788775.003 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-10-31T21:46:15.003Z,1698788775.003 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature; 2023-10-31T21:46:15.003Z,1698788775.003 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-10-31T21:46:15.004Z,1698788775.004 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2023-10-31T21:46:15.004Z,1698788775.004 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-10-31T21:46:15.004Z,1698788775.004 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-10-31T21:46:15.004Z,1698788775.004 [SBIT](IMPORTANT): Express none _.ayeris_particle_shape; 2023-10-31T21:46:15.004Z,1698788775.004 [SBIT](IMPORTANT): Express none _.ayeris_particle_slope; 2023-10-31T21:46:15.004Z,1698788775.004 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2023-10-31T21:46:15.004Z,1698788775.004 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2023-10-31T21:46:15.004Z,1698788775.004 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2023-10-31T21:46:15.005Z,1698788775.005 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool; 2023-10-31T21:46:15.005Z,1698788775.005 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=312.5 cubic_centimeter; 2023-10-31T21:46:15.005Z,1698788775.005 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=0 second; 2023-10-31T21:46:15.005Z,1698788775.005 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=0.0015 reciprocal_second; 2023-10-31T21:46:15.005Z,1698788775.005 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=0.01 none; 2023-10-31T21:46:15.005Z,1698788775.005 [SBIT](IMPORTANT): VerticalControl.massDeadband=1 millimeter; 2023-10-31T21:46:15.005Z,1698788775.005 [SBIT](IMPORTANT): VerticalControl.massDefault=1.729565 millimeter; 2023-10-31T21:46:15.005Z,1698788775.005 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter; 2023-10-31T21:46:15.006Z,1698788775.006 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter; 2023-10-31T21:46:15.583Z,1698788775.583 [MissionManager](IMPORTANT): Started mission Startup 2023-10-31T21:46:15.583Z,1698788775.583 [Startup] Running Loop=1 2023-10-31T21:46:15.583Z,1698788775.583 [Startup](DEBUG): Aggregate::initialize Startup 2023-10-31T21:46:15.583Z,1698788775.583 [Startup:A.GoToSurface] Running Loop=1 2023-10-31T21:46:15.583Z,1698788775.583 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-31T21:46:15.584Z,1698788775.584 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-31T21:46:15.584Z,1698788775.584 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-31T21:46:15.585Z,1698788775.585 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-31T21:46:15.585Z,1698788775.585 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-31T21:46:15.585Z,1698788775.585 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-31T21:46:15.587Z,1698788775.587 [Startup:StartupSatComms] Running Loop=1 2023-10-31T21:46:15.587Z,1698788775.587 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-10-31T21:46:15.587Z,1698788775.587 [Startup:StartupSatComms:A] Running Loop=1 2023-10-31T21:46:15.809Z,1698788775.809 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-10-31T21:47:15.614Z,1698788835.614 [Startup:StartupSatComms:A](INFO): Timed out from 2023-10-31T21:46:15.6Z 2023-10-31T21:47:15.614Z,1698788835.614 [Startup:StartupSatComms:A] Stopped 2023-10-31T21:47:15.614Z,1698788835.614 [Startup:StartupSatComms:B] Running Loop=1 2023-10-31T21:47:16.030Z,1698788836.030 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-10-31T21:47:22.217Z,1698788842.217 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004948 2023-10-31T21:47:31.813Z,1698788851.813 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20231004T050433/Courier0114.lzma 2023-10-31T21:47:32.812Z,1698788852.812 [DataOverHttps](INFO): Moved sent file to Logs/20231004T050433/Courier0114.lzma.bak 2023-10-31T21:47:32.812Z,1698788852.812 [DataOverHttps](INFO): SBD MOMSN=19041362 2023-10-31T21:47:49.655Z,1698788869.655 [DataOverHttps](INFO): Sending 906 bytes from file Logs/20231004T050433/Courier0117.lzma 2023-10-31T21:47:50.656Z,1698788870.656 [DataOverHttps](INFO): Moved sent file to Logs/20231004T050433/Courier0117.lzma.bak 2023-10-31T21:47:50.656Z,1698788870.656 [DataOverHttps](INFO): SBD MOMSN=19041365 2023-10-31T21:47:54.776Z,1698788874.776 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-10-31T21:47:54.776Z,1698788874.776 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T21:47:54.777Z,1698788874.777 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-10-31T21:47:54.777Z,1698788874.777 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T21:47:54.787Z,1698788874.787 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T21:47:54.788Z,1698788874.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T21:47:55.209Z,1698788875.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T21:47:55.209Z,1698788875.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-10-31T21:47:55.209Z,1698788875.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T21:47:55.209Z,1698788875.209 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-10-31T21:47:55.605Z,1698788875.605 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T21:48:07.991Z,1698788887.991 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231004T050433/Courier0748.lzma 2023-10-31T21:48:08.992Z,1698788888.992 [DataOverHttps](INFO): Moved sent file to Logs/20231004T050433/Courier0748.lzma.bak 2023-10-31T21:48:08.992Z,1698788888.992 [DataOverHttps](INFO): SBD MOMSN=19041510 2023-10-31T21:48:15.797Z,1698788895.797 [Startup:StartupSatComms:B](INFO): Timed out from 2023-10-31T21:47:15.6Z 2023-10-31T21:48:15.797Z,1698788895.797 [Startup:StartupSatComms:B] Stopped 2023-10-31T21:48:15.797Z,1698788895.797 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-10-31T21:48:15.797Z,1698788895.797 [Startup:StartupSatComms] Stopped 2023-10-31T21:48:15.797Z,1698788895.797 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-10-31T21:48:15.798Z,1698788895.798 [Startup](INFO): Completed Startup 2023-10-31T21:48:15.818Z,1698788895.818 [MissionManager](INFO): Startup is completed. 2023-10-31T21:48:15.818Z,1698788895.818 [MissionManager](INFO): Uninitializing Mission Startup 2023-10-31T21:48:15.818Z,1698788895.818 [Startup] Stopped 2023-10-31T21:48:15.819Z,1698788895.819 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-10-31T21:48:15.819Z,1698788895.819 [Startup:A.GoToSurface] Stopped 2023-10-31T21:48:15.819Z,1698788895.819 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-31T21:48:16.323Z,1698788896.323 [MissionManager](IMPORTANT): Started mission Default 2023-10-31T21:48:16.324Z,1698788896.324 [Default] Running Loop=1 2023-10-31T21:48:16.324Z,1698788896.324 [Default](DEBUG): Aggregate::initialize Default 2023-10-31T21:48:16.324Z,1698788896.324 [Default:B.GoToSurface] Running Loop=1 2023-10-31T21:48:16.324Z,1698788896.324 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-31T21:48:16.324Z,1698788896.324 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-31T21:48:16.324Z,1698788896.324 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-31T21:48:16.325Z,1698788896.325 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-31T21:48:16.325Z,1698788896.325 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-31T21:48:16.325Z,1698788896.325 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-31T21:48:16.326Z,1698788896.326 [Default:A.Wait] Running Loop=1 2023-10-31T21:48:16.326Z,1698788896.326 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-10-31T21:48:26.078Z,1698788906.078 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20231031T214444/Courier0000.lzma 2023-10-31T21:48:27.080Z,1698788907.080 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Courier0000.lzma.bak 2023-10-31T21:48:27.080Z,1698788907.080 [DataOverHttps](INFO): SBD MOMSN=19041512 2023-10-31T21:48:29.550Z,1698788909.550 [Default:A.Wait](INFO): Done Waiting. 2023-10-31T21:48:29.550Z,1698788909.550 [Default:A.Wait] Stopped 2023-10-31T21:48:29.550Z,1698788909.550 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-10-31T21:48:30.098Z,1698788910.098 [Default:CheckIn] Running Loop=1 2023-10-31T21:48:30.099Z,1698788910.099 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-31T21:48:30.099Z,1698788910.099 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-31T21:48:30.381Z,1698788910.381 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-10-31T21:50:36.799Z,1698789036.799 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-31T21:50:55.773Z,1698789055.773 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-10-31T21:50:55.773Z,1698789055.773 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T21:50:55.774Z,1698789055.774 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-10-31T21:50:55.774Z,1698789055.774 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T21:50:55.785Z,1698789055.785 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T21:50:55.785Z,1698789055.785 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T21:50:56.196Z,1698789056.196 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T21:50:56.196Z,1698789056.196 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-10-31T21:50:56.196Z,1698789056.196 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T21:50:56.196Z,1698789056.196 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-10-31T21:50:56.630Z,1698789056.630 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T21:52:16.262Z,1698789136.262 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-10-31T21:52:22.630Z,1698789142.630 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-10-31T21:52:22.633Z,1698789142.633 [BPC1](INFO): Received data from all battery sticks. 2023-10-31T21:53:30.130Z,1698789210.130 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-31T21:48:30.1Z 2023-10-31T21:53:30.130Z,1698789210.130 [Default:CheckIn:Read_GPS] Stopped 2023-10-31T21:53:30.130Z,1698789210.130 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-31T21:53:30.519Z,1698789210.519 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-10-31T21:53:39.230Z,1698789219.230 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20231031T214444/Courier0004.lzma 2023-10-31T21:53:40.232Z,1698789220.232 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Courier0004.lzma.bak 2023-10-31T21:53:40.232Z,1698789220.232 [DataOverHttps](INFO): SBD MOMSN=19041517 2023-10-31T21:53:56.770Z,1698789236.770 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-10-31T21:53:56.771Z,1698789236.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T21:53:56.772Z,1698789236.772 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2023-10-31T21:53:56.772Z,1698789236.772 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T21:53:56.828Z,1698789236.828 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T21:53:56.828Z,1698789236.828 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T21:53:57.182Z,1698789237.182 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T21:53:57.182Z,1698789237.182 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-10-31T21:53:57.183Z,1698789237.183 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T21:53:57.183Z,1698789237.183 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2023-10-31T21:53:57.587Z,1698789237.587 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T21:54:00.675Z,1698789240.675 [DataOverHttps](INFO): Sending 1111 bytes from file Logs/20231031T214444/Express0001.lzma 2023-10-31T21:54:01.676Z,1698789241.676 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Express0001.lzma.bak 2023-10-31T21:54:01.676Z,1698789241.676 [DataOverHttps](INFO): SBD MOMSN=19041519 2023-10-31T21:54:19.938Z,1698789259.938 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20231031T214444/Express0005.lzma 2023-10-31T21:54:20.940Z,1698789260.940 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Express0005.lzma.bak 2023-10-31T21:54:20.940Z,1698789260.940 [DataOverHttps](INFO): SBD MOMSN=19041554 2023-10-31T21:54:25.060Z,1698789265.060 [Default:CheckIn:Read_Iridium] Stopped 2023-10-31T21:54:25.060Z,1698789265.060 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-31T21:54:25.060Z,1698789265.060 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-31T21:54:48.227Z,1698789288.227 [CommandExec](IMPORTANT): got command strobe off 2023-10-31T21:54:48.228Z,1698789288.228 [CommandExec](IMPORTANT): Deactivating strobe 2023-10-31T21:54:56.155Z,1698789296.155 [Power24vConverter](INFO): Powering down. 2023-10-31T21:55:34.924Z,1698789334.924 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-31T21:55:34.924Z,1698789334.924 [NAL9602] Data Fault, FailCount= 1 2023-10-31T21:55:34.924Z,1698789334.924 [NAL9602](ERROR): Data Fault 2023-10-31T21:55:34.972Z,1698789334.972 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-31T21:55:35.327Z,1698789335.327 [NAL9602](INFO): Powering down 2023-10-31T21:55:36.154Z,1698789336.154 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-31T21:55:36.154Z,1698789336.154 [NAL9602] No Fault, FailCount= 1 2023-10-31T21:56:05.624Z,1698789365.624 [NAL9602](INFO): Powering up NAL9602 2023-10-31T21:56:16.536Z,1698789376.536 [NAL9602](INFO): NAL9602 initialized 2023-10-31T21:56:57.752Z,1698789417.752 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-10-31T21:56:57.752Z,1698789417.752 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T21:56:57.753Z,1698789417.753 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2023-10-31T21:56:57.753Z,1698789417.753 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T21:56:57.801Z,1698789417.801 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T21:56:57.801Z,1698789417.801 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T21:56:58.187Z,1698789418.187 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T21:56:58.187Z,1698789418.187 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-10-31T21:56:58.187Z,1698789418.187 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T21:56:58.187Z,1698789418.187 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2023-10-31T21:56:58.567Z,1698789418.567 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T21:59:25.638Z,1698789565.638 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-31T21:59:25.638Z,1698789565.638 [Default:CheckIn:C.Wait] Stopped 2023-10-31T21:59:25.638Z,1698789565.638 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-31T21:59:25.638Z,1698789565.638 [Default:CheckIn:D] Running Loop=1 2023-10-31T21:59:26.038Z,1698789566.038 [Default:CheckIn:D] Stopped 2023-10-31T21:59:26.038Z,1698789566.038 [Default:CheckIn:E] Running Loop=1 2023-10-31T21:59:26.425Z,1698789566.425 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.161899 min 2023-10-31T21:59:26.425Z,1698789566.425 [Default:CheckIn:E] Stopped 2023-10-31T21:59:26.425Z,1698789566.425 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-31T21:59:26.425Z,1698789566.425 [Default:CheckIn] Stopped 2023-10-31T21:59:26.425Z,1698789566.425 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-31T21:59:26.425Z,1698789566.425 [Default:CheckIn](INFO): Running loop #2 2023-10-31T21:59:26.425Z,1698789566.425 [Default:CheckIn] Running Loop=2 2023-10-31T21:59:26.426Z,1698789566.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-31T21:59:26.426Z,1698789566.426 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-31T21:59:55.563Z,1698789595.563 [CBIT](INFO): Clearing failed state for component DropWeight 2023-10-31T21:59:55.563Z,1698789595.563 [DropWeight] No Fault, FailCount= 1 2023-10-31T21:59:58.754Z,1698789598.754 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-10-31T21:59:58.754Z,1698789598.754 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T21:59:58.756Z,1698789598.756 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2023-10-31T21:59:58.756Z,1698789598.756 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T21:59:58.767Z,1698789598.767 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T21:59:58.767Z,1698789598.767 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T21:59:59.181Z,1698789599.181 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T21:59:59.181Z,1698789599.181 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-10-31T21:59:59.182Z,1698789599.182 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T21:59:59.182Z,1698789599.182 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2023-10-31T21:59:59.567Z,1698789599.567 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:02:59.744Z,1698789779.744 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-10-31T22:02:59.744Z,1698789779.744 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:02:59.745Z,1698789779.745 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2023-10-31T22:02:59.745Z,1698789779.745 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:02:59.756Z,1698789779.756 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:02:59.756Z,1698789779.756 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:03:00.171Z,1698789780.171 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:03:00.171Z,1698789780.171 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-10-31T22:03:00.171Z,1698789780.171 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:03:00.171Z,1698789780.171 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2023-10-31T22:03:00.571Z,1698789780.571 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:04:26.626Z,1698789866.626 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-31T21:59:26.4Z 2023-10-31T22:04:26.626Z,1698789866.626 [Default:CheckIn:Read_GPS] Stopped 2023-10-31T22:04:26.626Z,1698789866.626 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-31T22:04:35.038Z,1698789875.038 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231031T214444/Courier0007.lzma 2023-10-31T22:04:36.040Z,1698789876.040 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Courier0007.lzma.bak 2023-10-31T22:04:36.040Z,1698789876.040 [DataOverHttps](INFO): SBD MOMSN=19041558 2023-10-31T22:04:54.194Z,1698789894.194 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20231031T214444/Express0008.lzma 2023-10-31T22:04:55.196Z,1698789895.196 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Express0008.lzma.bak 2023-10-31T22:04:55.196Z,1698789895.196 [DataOverHttps](INFO): SBD MOMSN=19041560 2023-10-31T22:04:58.944Z,1698789898.944 [Default:CheckIn:Read_Iridium] Stopped 2023-10-31T22:04:58.945Z,1698789898.945 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-31T22:04:58.945Z,1698789898.945 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-31T22:06:00.742Z,1698789960.742 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-10-31T22:06:00.742Z,1698789960.742 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:06:00.743Z,1698789960.743 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2023-10-31T22:06:00.743Z,1698789960.743 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:06:00.754Z,1698789960.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:06:00.754Z,1698789960.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:06:01.185Z,1698789961.185 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:06:01.185Z,1698789961.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-10-31T22:06:01.185Z,1698789961.185 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:06:01.185Z,1698789961.185 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2023-10-31T22:06:01.577Z,1698789961.577 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:06:18.093Z,1698789978.093 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-31T22:06:18.093Z,1698789978.093 [NAL9602] Data Fault, FailCount= 2 2023-10-31T22:06:18.093Z,1698789978.093 [NAL9602](ERROR): Data Fault 2023-10-31T22:06:18.112Z,1698789978.112 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-31T22:06:18.504Z,1698789978.504 [NAL9602](INFO): Powering down 2023-10-31T22:06:19.340Z,1698789979.340 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-31T22:06:19.340Z,1698789979.340 [NAL9602] No Fault, FailCount= 2 2023-10-31T22:06:48.804Z,1698790008.804 [NAL9602](INFO): Powering up NAL9602 2023-10-31T22:06:59.708Z,1698790019.708 [NAL9602](INFO): NAL9602 initialized 2023-10-31T22:09:01.754Z,1698790141.754 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2023-10-31T22:09:01.754Z,1698790141.754 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:09:01.755Z,1698790141.755 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2023-10-31T22:09:01.755Z,1698790141.755 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:09:01.766Z,1698790141.766 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:09:01.767Z,1698790141.767 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:09:02.174Z,1698790142.174 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:09:02.175Z,1698790142.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2023-10-31T22:09:02.175Z,1698790142.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:09:02.175Z,1698790142.175 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2023-10-31T22:09:02.539Z,1698790142.539 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:09:59.517Z,1698790199.517 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-31T22:09:59.517Z,1698790199.517 [Default:CheckIn:C.Wait] Stopped 2023-10-31T22:09:59.517Z,1698790199.517 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-31T22:09:59.517Z,1698790199.517 [Default:CheckIn:D] Running Loop=1 2023-10-31T22:09:59.934Z,1698790199.934 [Default:CheckIn:D] Stopped 2023-10-31T22:09:59.934Z,1698790199.934 [Default:CheckIn:E] Running Loop=1 2023-10-31T22:10:00.341Z,1698790200.341 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.726833 min 2023-10-31T22:10:00.341Z,1698790200.341 [Default:CheckIn:E] Stopped 2023-10-31T22:10:00.341Z,1698790200.341 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-31T22:10:00.341Z,1698790200.341 [Default:CheckIn] Stopped 2023-10-31T22:10:00.341Z,1698790200.341 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-31T22:10:00.341Z,1698790200.341 [Default:CheckIn](INFO): Running loop #3 2023-10-31T22:10:00.341Z,1698790200.341 [Default:CheckIn] Running Loop=3 2023-10-31T22:10:00.341Z,1698790200.341 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-31T22:10:00.342Z,1698790200.342 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-31T22:12:02.745Z,1698790322.745 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2023-10-31T22:12:02.745Z,1698790322.745 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:12:02.747Z,1698790322.747 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2023-10-31T22:12:02.747Z,1698790322.747 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:12:02.757Z,1698790322.757 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:12:02.758Z,1698790322.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:12:03.149Z,1698790323.149 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:12:03.149Z,1698790323.149 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2023-10-31T22:12:03.149Z,1698790323.149 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:12:03.149Z,1698790323.149 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2023-10-31T22:12:03.528Z,1698790323.528 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:15:00.481Z,1698790500.481 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-31T22:10:00.3Z 2023-10-31T22:15:00.481Z,1698790500.481 [Default:CheckIn:Read_GPS] Stopped 2023-10-31T22:15:00.482Z,1698790500.482 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-31T22:15:03.732Z,1698790503.732 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2023-10-31T22:15:03.732Z,1698790503.732 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:15:03.733Z,1698790503.733 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2023-10-31T22:15:03.733Z,1698790503.733 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:15:03.743Z,1698790503.743 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:15:03.743Z,1698790503.743 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:15:04.157Z,1698790504.157 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:15:04.157Z,1698790504.157 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2023-10-31T22:15:04.158Z,1698790504.158 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:15:04.158Z,1698790504.158 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2023-10-31T22:15:04.538Z,1698790504.538 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:15:08.653Z,1698790508.653 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231031T214444/Courier0010.lzma 2023-10-31T22:15:09.656Z,1698790509.656 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Courier0010.lzma.bak 2023-10-31T22:15:09.656Z,1698790509.656 [DataOverHttps](INFO): SBD MOMSN=19041573 2023-10-31T22:15:28.430Z,1698790528.430 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231031T214444/Express0011.lzma 2023-10-31T22:15:29.432Z,1698790529.432 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Express0011.lzma.bak 2023-10-31T22:15:29.432Z,1698790529.432 [DataOverHttps](INFO): SBD MOMSN=19041575 2023-10-31T22:15:33.216Z,1698790533.216 [Default:CheckIn:Read_Iridium] Stopped 2023-10-31T22:15:33.216Z,1698790533.216 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-31T22:15:33.216Z,1698790533.216 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-31T22:17:02.480Z,1698790622.480 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-31T22:17:02.480Z,1698790622.480 [NAL9602] Data Fault, FailCount= 3 2023-10-31T22:17:02.480Z,1698790622.480 [NAL9602](ERROR): Data Fault 2023-10-31T22:17:02.498Z,1698790622.498 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-31T22:17:02.885Z,1698790622.885 [NAL9602](INFO): Powering down 2023-10-31T22:17:03.723Z,1698790623.723 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-31T22:17:03.723Z,1698790623.723 [NAL9602] No Fault, FailCount= 3 2023-10-31T22:17:33.180Z,1698790653.180 [NAL9602](INFO): Powering up NAL9602 2023-10-31T22:17:44.096Z,1698790664.096 [NAL9602](INFO): NAL9602 initialized 2023-10-31T22:18:04.715Z,1698790684.715 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2023-10-31T22:18:04.715Z,1698790684.715 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:18:04.716Z,1698790684.716 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2023-10-31T22:18:04.716Z,1698790684.716 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:18:04.727Z,1698790684.727 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:18:04.727Z,1698790684.727 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:18:05.117Z,1698790685.117 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:18:05.117Z,1698790685.117 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2023-10-31T22:18:05.134Z,1698790685.134 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:18:05.134Z,1698790685.134 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2023-10-31T22:18:05.511Z,1698790685.511 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:20:33.781Z,1698790833.781 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-31T22:20:33.781Z,1698790833.781 [Default:CheckIn:C.Wait] Stopped 2023-10-31T22:20:33.781Z,1698790833.781 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-31T22:20:33.781Z,1698790833.781 [Default:CheckIn:D] Running Loop=1 2023-10-31T22:20:34.182Z,1698790834.182 [Default:CheckIn:D] Stopped 2023-10-31T22:20:34.182Z,1698790834.182 [Default:CheckIn:E] Running Loop=1 2023-10-31T22:20:34.625Z,1698790834.625 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.297632 min 2023-10-31T22:20:34.625Z,1698790834.625 [Default:CheckIn:E] Stopped 2023-10-31T22:20:34.625Z,1698790834.625 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-31T22:20:34.625Z,1698790834.625 [Default:CheckIn] Stopped 2023-10-31T22:20:34.625Z,1698790834.625 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-31T22:20:34.626Z,1698790834.626 [Default:CheckIn](INFO): Running loop #4 2023-10-31T22:20:34.626Z,1698790834.626 [Default:CheckIn] Running Loop=4 2023-10-31T22:20:34.670Z,1698790834.670 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-31T22:20:34.670Z,1698790834.670 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-31T22:21:05.764Z,1698790865.764 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2023-10-31T22:21:05.764Z,1698790865.764 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:21:05.765Z,1698790865.765 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2023-10-31T22:21:05.765Z,1698790865.765 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:21:05.832Z,1698790865.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:21:05.832Z,1698790865.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:21:06.154Z,1698790866.154 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:21:06.154Z,1698790866.154 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2023-10-31T22:21:06.155Z,1698790866.155 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:21:06.155Z,1698790866.155 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2023-10-31T22:21:06.550Z,1698790866.550 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:24:06.701Z,1698791046.701 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2023-10-31T22:24:06.701Z,1698791046.701 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:24:06.702Z,1698791046.702 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2023-10-31T22:24:06.702Z,1698791046.702 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:24:06.712Z,1698791046.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:24:06.713Z,1698791046.713 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:24:07.107Z,1698791047.107 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:24:07.107Z,1698791047.107 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2023-10-31T22:24:07.107Z,1698791047.107 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:24:07.107Z,1698791047.107 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2023-10-31T22:24:07.491Z,1698791047.491 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:25:34.785Z,1698791134.785 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-31T22:20:34.7Z 2023-10-31T22:25:34.785Z,1698791134.785 [Default:CheckIn:Read_GPS] Stopped 2023-10-31T22:25:34.786Z,1698791134.786 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-31T22:25:42.990Z,1698791142.990 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231031T214444/Courier0013.lzma 2023-10-31T22:25:43.992Z,1698791143.992 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Courier0013.lzma.bak 2023-10-31T22:25:43.992Z,1698791143.992 [DataOverHttps](INFO): SBD MOMSN=19041579 2023-10-31T22:26:01.990Z,1698791161.990 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231031T214444/Express0014.lzma 2023-10-31T22:26:02.992Z,1698791162.992 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Express0014.lzma.bak 2023-10-31T22:26:02.992Z,1698791162.992 [DataOverHttps](INFO): SBD MOMSN=19041581 2023-10-31T22:26:07.094Z,1698791167.094 [Default:CheckIn:Read_Iridium] Stopped 2023-10-31T22:26:07.094Z,1698791167.094 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-31T22:26:07.098Z,1698791167.098 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-31T22:27:07.692Z,1698791227.692 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2023-10-31T22:27:07.692Z,1698791227.692 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:27:07.693Z,1698791227.693 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2023-10-31T22:27:07.693Z,1698791227.693 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:27:07.728Z,1698791227.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:27:07.728Z,1698791227.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:27:08.100Z,1698791228.100 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:27:08.100Z,1698791228.100 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2023-10-31T22:27:08.100Z,1698791228.100 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:27:08.101Z,1698791228.101 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2023-10-31T22:27:08.497Z,1698791228.497 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:27:45.648Z,1698791265.648 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-31T22:27:45.648Z,1698791265.648 [NAL9602] Data Fault, FailCount= 4 2023-10-31T22:27:45.648Z,1698791265.648 [NAL9602](ERROR): Data Fault 2023-10-31T22:27:45.667Z,1698791265.667 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-31T22:27:46.048Z,1698791266.048 [NAL9602](INFO): Powering down 2023-10-31T22:27:46.877Z,1698791266.877 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-31T22:27:46.877Z,1698791266.877 [NAL9602] No Fault, FailCount= 4 2023-10-31T22:28:16.352Z,1698791296.352 [NAL9602](INFO): Powering up NAL9602 2023-10-31T22:28:27.256Z,1698791307.256 [NAL9602](INFO): NAL9602 initialized 2023-10-31T22:30:08.692Z,1698791408.692 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2023-10-31T22:30:08.692Z,1698791408.692 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:30:08.693Z,1698791408.693 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15 2023-10-31T22:30:08.693Z,1698791408.693 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:30:08.704Z,1698791408.704 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:30:08.704Z,1698791408.704 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:30:09.085Z,1698791409.085 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:30:09.085Z,1698791409.085 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2023-10-31T22:30:09.085Z,1698791409.085 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:30:09.086Z,1698791409.086 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15 2023-10-31T22:30:09.475Z,1698791409.475 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:31:07.669Z,1698791467.669 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-31T22:31:07.669Z,1698791467.669 [Default:CheckIn:C.Wait] Stopped 2023-10-31T22:31:07.669Z,1698791467.669 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-31T22:31:07.669Z,1698791467.669 [Default:CheckIn:D] Running Loop=1 2023-10-31T22:31:08.085Z,1698791468.085 [Default:CheckIn:D] Stopped 2023-10-31T22:31:08.085Z,1698791468.085 [Default:CheckIn:E] Running Loop=1 2023-10-31T22:31:08.479Z,1698791468.479 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.862691 min 2023-10-31T22:31:08.479Z,1698791468.479 [Default:CheckIn:E] Stopped 2023-10-31T22:31:08.479Z,1698791468.479 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-31T22:31:08.479Z,1698791468.479 [Default:CheckIn] Stopped 2023-10-31T22:31:08.479Z,1698791468.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-31T22:31:08.479Z,1698791468.479 [Default:CheckIn](INFO): Running loop #5 2023-10-31T22:31:08.479Z,1698791468.479 [Default:CheckIn] Running Loop=5 2023-10-31T22:31:08.479Z,1698791468.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-31T22:31:08.479Z,1698791468.479 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-31T22:33:09.677Z,1698791589.677 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2023-10-31T22:33:09.677Z,1698791589.677 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:33:09.678Z,1698791589.678 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16 2023-10-31T22:33:09.678Z,1698791589.678 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:33:09.688Z,1698791589.688 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:33:09.689Z,1698791589.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:33:10.080Z,1698791590.080 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:33:10.080Z,1698791590.080 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2023-10-31T22:33:10.081Z,1698791590.081 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:33:10.081Z,1698791590.081 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16 2023-10-31T22:33:10.471Z,1698791590.471 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:36:08.642Z,1698791768.642 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-31T22:31:08.5Z 2023-10-31T22:36:08.642Z,1698791768.642 [Default:CheckIn:Read_GPS] Stopped 2023-10-31T22:36:08.642Z,1698791768.642 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-31T22:36:10.668Z,1698791770.668 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2023-10-31T22:36:10.668Z,1698791770.668 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:36:10.669Z,1698791770.669 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17 2023-10-31T22:36:10.669Z,1698791770.669 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:36:10.688Z,1698791770.688 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:36:10.689Z,1698791770.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:36:11.086Z,1698791771.086 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:36:11.086Z,1698791771.086 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2023-10-31T22:36:11.086Z,1698791771.086 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:36:11.086Z,1698791771.086 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17 2023-10-31T22:36:11.459Z,1698791771.459 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:36:15.961Z,1698791775.961 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231031T214444/Courier0016.lzma 2023-10-31T22:36:16.964Z,1698791776.964 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Courier0016.lzma.bak 2023-10-31T22:36:16.964Z,1698791776.964 [DataOverHttps](INFO): SBD MOMSN=19041591 2023-10-31T22:36:34.998Z,1698791794.998 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20231031T214444/Express0017.lzma 2023-10-31T22:36:35.000Z,1698791796.000 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Express0017.lzma.bak 2023-10-31T22:36:36.000Z,1698791796.000 [DataOverHttps](INFO): SBD MOMSN=19041593 2023-10-31T22:36:40.161Z,1698791800.161 [Default:CheckIn:Read_Iridium] Stopped 2023-10-31T22:36:40.161Z,1698791800.161 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-31T22:36:40.161Z,1698791800.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-31T22:38:28.820Z,1698791908.820 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-31T22:38:28.820Z,1698791908.820 [NAL9602] Data Fault, FailCount= 5 2023-10-31T22:38:28.820Z,1698791908.820 [NAL9602](ERROR): Data Fault 2023-10-31T22:38:28.837Z,1698791908.837 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-31T22:38:28.838Z,1698791908.838 [CBIT](CRITICAL): Data Fault in component: NAL9602 2023-10-31T22:38:29.224Z,1698791909.224 [NAL9602](INFO): Powering down 2023-10-31T22:38:29.240Z,1698791909.240 [CBIT](INFO): Critical error at 20231031T223828 2023-10-31T22:39:11.651Z,1698791951.651 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2023-10-31T22:39:11.651Z,1698791951.651 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:39:11.652Z,1698791951.652 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18 2023-10-31T22:39:11.652Z,1698791951.652 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:39:11.695Z,1698791951.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:39:11.695Z,1698791951.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:39:12.060Z,1698791952.060 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:39:12.060Z,1698791952.060 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2023-10-31T22:39:12.061Z,1698791952.061 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:39:12.061Z,1698791952.061 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18 2023-10-31T22:39:12.456Z,1698791952.456 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:40:28.838Z,1698792028.838 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-31T22:40:28.838Z,1698792028.838 [NAL9602] No Fault, FailCount= 5 2023-10-31T22:40:29.212Z,1698792029.212 [NAL9602](INFO): Powering up NAL9602 2023-10-31T22:40:40.118Z,1698792040.118 [NAL9602](INFO): NAL9602 initialized 2023-10-31T22:41:40.749Z,1698792100.749 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-31T22:41:40.749Z,1698792100.749 [Default:CheckIn:C.Wait] Stopped 2023-10-31T22:41:40.750Z,1698792100.750 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-31T22:41:40.750Z,1698792100.750 [Default:CheckIn:D] Running Loop=1 2023-10-31T22:41:41.153Z,1698792101.153 [Default:CheckIn:D] Stopped 2023-10-31T22:41:41.153Z,1698792101.153 [Default:CheckIn:E] Running Loop=1 2023-10-31T22:41:41.547Z,1698792101.547 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.413831 min 2023-10-31T22:41:41.547Z,1698792101.547 [Default:CheckIn:E] Stopped 2023-10-31T22:41:41.547Z,1698792101.547 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-31T22:41:41.548Z,1698792101.548 [Default:CheckIn] Stopped 2023-10-31T22:41:41.548Z,1698792101.548 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-31T22:41:41.548Z,1698792101.548 [Default:CheckIn](INFO): Running loop #6 2023-10-31T22:41:41.548Z,1698792101.548 [Default:CheckIn] Running Loop=6 2023-10-31T22:41:41.548Z,1698792101.548 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-31T22:41:41.548Z,1698792101.548 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-31T22:42:12.661Z,1698792132.661 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2023-10-31T22:42:12.661Z,1698792132.661 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:42:12.662Z,1698792132.662 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19 2023-10-31T22:42:12.663Z,1698792132.663 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:42:12.681Z,1698792132.681 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:42:12.682Z,1698792132.682 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:42:13.081Z,1698792133.081 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:42:13.081Z,1698792133.081 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2023-10-31T22:42:13.082Z,1698792133.082 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:42:13.082Z,1698792133.082 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19 2023-10-31T22:42:13.447Z,1698792133.447 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:45:13.642Z,1698792313.642 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2023-10-31T22:45:13.642Z,1698792313.642 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:45:13.643Z,1698792313.643 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20 2023-10-31T22:45:13.643Z,1698792313.643 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:45:13.654Z,1698792313.654 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:45:13.654Z,1698792313.654 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:45:14.065Z,1698792314.065 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:45:14.065Z,1698792314.065 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2023-10-31T22:45:14.067Z,1698792314.067 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:45:14.067Z,1698792314.067 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20 2023-10-31T22:45:14.444Z,1698792314.444 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:45:32.235Z,1698792332.235 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-31T22:45:35.055Z,1698792335.055 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-10-31T22:45:43.178Z,1698792343.178 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.014154 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.005499 CHAN A3 (5V): -0.002702 CHAN B0 (3.3V): -0.000804 CHAN B1 (3.15aV): -0.001714 CHAN B2 (3.15bV): -0.002220 CHAN B3 (GND): -0.000650 OPEN: 0.004605 Full Scale: +/- 1 mA 2023-10-31T22:46:41.731Z,1698792401.731 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-31T22:41:41.5Z 2023-10-31T22:46:41.731Z,1698792401.731 [Default:CheckIn:Read_GPS] Stopped 2023-10-31T22:46:41.731Z,1698792401.731 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-31T22:46:50.509Z,1698792410.509 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20231031T214444/Courier0019.lzma 2023-10-31T22:46:51.513Z,1698792411.513 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Courier0019.lzma.bak 2023-10-31T22:46:51.513Z,1698792411.513 [DataOverHttps](INFO): SBD MOMSN=19041597 2023-10-31T22:47:10.482Z,1698792430.482 [DataOverHttps](INFO): Sending 335 bytes from file Logs/20231031T214444/Express0020.lzma 2023-10-31T22:47:11.484Z,1698792431.484 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Express0020.lzma.bak 2023-10-31T22:47:11.484Z,1698792431.484 [DataOverHttps](INFO): SBD MOMSN=19041600 2023-10-31T22:47:15.664Z,1698792435.664 [Default:CheckIn:Read_Iridium] Stopped 2023-10-31T22:47:15.664Z,1698792435.664 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-31T22:47:15.664Z,1698792435.664 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-31T22:48:14.632Z,1698792494.632 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2023-10-31T22:48:14.632Z,1698792494.632 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:48:14.633Z,1698792494.633 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 21 2023-10-31T22:48:14.633Z,1698792494.633 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:48:14.644Z,1698792494.644 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:48:14.644Z,1698792494.644 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:48:15.053Z,1698792495.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:48:15.053Z,1698792495.053 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2023-10-31T22:48:15.054Z,1698792495.054 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:48:15.054Z,1698792495.054 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 21 2023-10-31T22:48:15.450Z,1698792495.450 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:50:41.676Z,1698792641.676 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-31T22:50:41.676Z,1698792641.676 [NAL9602] Data Fault, FailCount= 1 2023-10-31T22:50:41.676Z,1698792641.676 [NAL9602](ERROR): Data Fault 2023-10-31T22:50:41.751Z,1698792641.751 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-31T22:50:42.079Z,1698792642.079 [NAL9602](INFO): Powering down 2023-10-31T22:50:42.906Z,1698792642.906 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-31T22:50:42.906Z,1698792642.906 [NAL9602] No Fault, FailCount= 1 2023-10-31T22:51:12.381Z,1698792672.381 [NAL9602](INFO): Powering up NAL9602 2023-10-31T22:51:15.625Z,1698792675.625 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2023-10-31T22:51:15.625Z,1698792675.625 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:51:15.626Z,1698792675.626 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 22 2023-10-31T22:51:15.627Z,1698792675.627 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:51:15.637Z,1698792675.637 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:51:15.637Z,1698792675.637 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:51:16.041Z,1698792676.041 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:51:16.041Z,1698792676.041 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2023-10-31T22:51:16.041Z,1698792676.041 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:51:16.041Z,1698792676.041 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 22 2023-10-31T22:51:16.428Z,1698792676.428 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:51:23.292Z,1698792683.292 [NAL9602](INFO): NAL9602 initialized 2023-10-31T22:52:16.225Z,1698792736.225 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-31T22:52:16.225Z,1698792736.225 [Default:CheckIn:C.Wait] Stopped 2023-10-31T22:52:16.225Z,1698792736.225 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-31T22:52:16.225Z,1698792736.225 [Default:CheckIn:D] Running Loop=1 2023-10-31T22:52:16.632Z,1698792736.632 [Default:CheckIn:D] Stopped 2023-10-31T22:52:16.632Z,1698792736.632 [Default:CheckIn:E] Running Loop=1 2023-10-31T22:52:17.058Z,1698792737.058 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.005143 min 2023-10-31T22:52:17.058Z,1698792737.058 [Default:CheckIn:E] Stopped 2023-10-31T22:52:17.058Z,1698792737.058 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-31T22:52:17.058Z,1698792737.058 [Default:CheckIn] Stopped 2023-10-31T22:52:17.058Z,1698792737.058 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-31T22:52:17.058Z,1698792737.058 [Default:CheckIn](INFO): Running loop #7 2023-10-31T22:52:17.059Z,1698792737.059 [Default:CheckIn] Running Loop=7 2023-10-31T22:52:17.059Z,1698792737.059 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-31T22:52:17.059Z,1698792737.059 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-31T22:54:16.615Z,1698792856.615 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23 2023-10-31T22:54:16.615Z,1698792856.615 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:54:16.616Z,1698792856.616 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 23 2023-10-31T22:54:16.616Z,1698792856.616 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:54:16.627Z,1698792856.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:54:16.627Z,1698792856.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:54:17.049Z,1698792857.049 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:54:17.049Z,1698792857.049 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23 2023-10-31T22:54:17.050Z,1698792857.050 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:54:17.050Z,1698792857.050 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 23 2023-10-31T22:54:17.440Z,1698792857.440 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:57:17.246Z,1698793037.246 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-31T22:52:17.1Z 2023-10-31T22:57:17.246Z,1698793037.246 [Default:CheckIn:Read_GPS] Stopped 2023-10-31T22:57:17.246Z,1698793037.246 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-31T22:57:17.645Z,1698793037.645 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24 2023-10-31T22:57:17.645Z,1698793037.645 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T22:57:17.647Z,1698793037.647 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 24 2023-10-31T22:57:17.647Z,1698793037.647 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T22:57:17.657Z,1698793037.657 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T22:57:17.658Z,1698793037.658 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T22:57:18.232Z,1698793038.232 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T22:57:18.232Z,1698793038.232 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24 2023-10-31T22:57:18.233Z,1698793038.233 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T22:57:18.233Z,1698793038.233 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 24 2023-10-31T22:57:18.559Z,1698793038.559 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T22:57:25.745Z,1698793045.745 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231031T214444/Courier0022.lzma 2023-10-31T22:57:26.748Z,1698793046.748 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Courier0022.lzma.bak 2023-10-31T22:57:26.748Z,1698793046.748 [DataOverHttps](INFO): SBD MOMSN=19041615 2023-10-31T22:57:44.946Z,1698793064.946 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231031T214444/Express0023.lzma 2023-10-31T22:57:45.948Z,1698793065.948 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Express0023.lzma.bak 2023-10-31T22:57:45.948Z,1698793065.948 [DataOverHttps](INFO): SBD MOMSN=19041617 2023-10-31T22:57:50.093Z,1698793070.093 [Default:CheckIn:Read_Iridium] Stopped 2023-10-31T22:57:50.093Z,1698793070.093 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-31T22:57:50.093Z,1698793070.093 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-31T22:59:51.258Z,1698793191.258 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-10-31T22:59:51.261Z,1698793191.261 [BPC1](INFO): Received data from all battery sticks. 2023-10-31T23:00:18.736Z,1698793218.736 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25 2023-10-31T23:00:18.736Z,1698793218.736 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T23:00:18.737Z,1698793218.737 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 25 2023-10-31T23:00:18.737Z,1698793218.737 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T23:00:18.751Z,1698793218.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T23:00:18.751Z,1698793218.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T23:00:19.162Z,1698793219.162 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T23:00:19.162Z,1698793219.162 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25 2023-10-31T23:00:19.163Z,1698793219.163 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T23:00:19.163Z,1698793219.163 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 25 2023-10-31T23:00:19.563Z,1698793219.563 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T23:01:24.176Z,1698793284.176 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-31T23:01:24.176Z,1698793284.176 [NAL9602] Data Fault, FailCount= 2 2023-10-31T23:01:24.176Z,1698793284.176 [NAL9602](ERROR): Data Fault 2023-10-31T23:01:24.228Z,1698793284.228 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-31T23:01:24.576Z,1698793284.576 [NAL9602](INFO): Powering down 2023-10-31T23:01:25.409Z,1698793285.409 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-31T23:01:25.409Z,1698793285.409 [NAL9602] No Fault, FailCount= 2 2023-10-31T23:01:54.884Z,1698793314.884 [NAL9602](INFO): Powering up NAL9602 2023-10-31T23:02:05.792Z,1698793325.792 [NAL9602](INFO): NAL9602 initialized 2023-10-31T23:02:50.650Z,1698793370.650 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-31T23:02:50.650Z,1698793370.650 [Default:CheckIn:C.Wait] Stopped 2023-10-31T23:02:50.650Z,1698793370.650 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-31T23:02:50.672Z,1698793370.672 [Default:CheckIn:D] Running Loop=1 2023-10-31T23:02:51.052Z,1698793371.052 [Default:CheckIn:D] Stopped 2023-10-31T23:02:51.053Z,1698793371.053 [Default:CheckIn:E] Running Loop=1 2023-10-31T23:02:51.473Z,1698793371.473 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.578817 min 2023-10-31T23:02:51.474Z,1698793371.474 [Default:CheckIn:E] Stopped 2023-10-31T23:02:51.474Z,1698793371.474 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-31T23:02:51.474Z,1698793371.474 [Default:CheckIn] Stopped 2023-10-31T23:02:51.474Z,1698793371.474 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-31T23:02:51.474Z,1698793371.474 [Default:CheckIn](INFO): Running loop #8 2023-10-31T23:02:51.474Z,1698793371.474 [Default:CheckIn] Running Loop=8 2023-10-31T23:02:51.475Z,1698793371.475 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-31T23:02:51.475Z,1698793371.475 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-31T23:03:19.728Z,1698793399.728 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26 2023-10-31T23:03:19.728Z,1698793399.728 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T23:03:19.729Z,1698793399.729 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 26 2023-10-31T23:03:19.729Z,1698793399.729 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T23:03:19.764Z,1698793399.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T23:03:19.764Z,1698793399.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T23:03:20.149Z,1698793400.149 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T23:03:20.149Z,1698793400.149 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26 2023-10-31T23:03:20.150Z,1698793400.150 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T23:03:20.150Z,1698793400.150 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 26 2023-10-31T23:03:20.546Z,1698793400.546 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T23:06:20.738Z,1698793580.738 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27 2023-10-31T23:06:20.738Z,1698793580.738 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T23:06:20.739Z,1698793580.739 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 27 2023-10-31T23:06:20.739Z,1698793580.739 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T23:06:20.762Z,1698793580.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T23:06:20.763Z,1698793580.763 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T23:06:21.155Z,1698793581.155 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T23:06:21.156Z,1698793581.156 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27 2023-10-31T23:06:21.156Z,1698793581.156 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T23:06:21.156Z,1698793581.156 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 27 2023-10-31T23:06:21.535Z,1698793581.535 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T23:07:51.629Z,1698793671.629 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-31T23:02:51.5Z 2023-10-31T23:07:51.629Z,1698793671.629 [Default:CheckIn:Read_GPS] Stopped 2023-10-31T23:07:51.629Z,1698793671.629 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-31T23:08:00.197Z,1698793680.197 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20231031T214444/Courier0025.lzma 2023-10-31T23:08:01.200Z,1698793681.200 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Courier0025.lzma.bak 2023-10-31T23:08:01.200Z,1698793681.200 [DataOverHttps](INFO): SBD MOMSN=19041621 2023-10-31T23:08:19.210Z,1698793699.210 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231031T214444/Express0026.lzma 2023-10-31T23:08:20.212Z,1698793700.212 [DataOverHttps](INFO): Moved sent file to Logs/20231031T214444/Express0026.lzma.bak 2023-10-31T23:08:20.212Z,1698793700.212 [DataOverHttps](INFO): SBD MOMSN=19041623 2023-10-31T23:08:24.797Z,1698793704.797 [Default:CheckIn:Read_Iridium] Stopped 2023-10-31T23:08:24.797Z,1698793704.797 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-31T23:08:24.797Z,1698793704.797 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-31T23:09:21.722Z,1698793761.722 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28 2023-10-31T23:09:21.722Z,1698793761.722 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T23:09:21.723Z,1698793761.723 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 28 2023-10-31T23:09:21.723Z,1698793761.723 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T23:09:21.734Z,1698793761.734 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T23:09:21.734Z,1698793761.734 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T23:09:22.134Z,1698793762.134 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T23:09:22.134Z,1698793762.134 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28 2023-10-31T23:09:22.134Z,1698793762.134 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T23:09:22.134Z,1698793762.134 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 28 2023-10-31T23:09:22.624Z,1698793762.624 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T23:12:08.561Z,1698793928.561 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-31T23:12:08.561Z,1698793928.561 [NAL9602] Data Fault, FailCount= 3 2023-10-31T23:12:08.561Z,1698793928.561 [NAL9602](ERROR): Data Fault 2023-10-31T23:12:08.624Z,1698793928.624 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-31T23:12:08.968Z,1698793928.968 [NAL9602](INFO): Powering down 2023-10-31T23:12:09.819Z,1698793929.819 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-31T23:12:09.819Z,1698793929.819 [NAL9602] No Fault, FailCount= 3 2023-10-31T23:12:22.714Z,1698793942.714 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29 2023-10-31T23:12:22.714Z,1698793942.714 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-31T23:12:22.716Z,1698793942.716 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 29 2023-10-31T23:12:22.716Z,1698793942.716 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-31T23:12:22.726Z,1698793942.726 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-31T23:12:22.727Z,1698793942.727 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-31T23:12:23.130Z,1698793943.130 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-31T23:12:23.130Z,1698793943.130 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29 2023-10-31T23:12:23.131Z,1698793943.131 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-31T23:12:23.131Z,1698793943.131 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 29 2023-10-31T23:12:23.511Z,1698793943.511 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-31T23:12:39.272Z,1698793959.272 [NAL9602](INFO): Powering up NAL9602 2023-10-31T23:12:44.319Z,1698793964.319 [CommandExec](IMPORTANT): got command quit 2023-10-31T23:12:45.326Z,1698793965.326 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:45.326Z,1698793965.326 [CommandExec](INFO): Uninitializing the command executive. 2023-10-31T23:12:45.326Z,1698793965.326 [CommandExec](INFO): Uninitializing the command scheduler. 2023-10-31T23:12:45.327Z,1698793965.327 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:45.526Z,1698793965.526 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-10-31T23:12:45.526Z,1698793965.526 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-10-31T23:12:45.526Z,1698793965.526 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:45.527Z,1698793965.527 [NavChartDb](INFO): Join timeout helper Thread ID is 5103 2023-10-31T23:12:45.846Z,1698793965.846 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:45.847Z,1698793965.847 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:45.850Z,1698793965.850 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-10-31T23:12:45.850Z,1698793965.850 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:45.851Z,1698793965.851 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5104 2023-10-31T23:12:45.966Z,1698793965.966 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:45.967Z,1698793965.967 [WetLabsBB2FL](INFO): Powering down 2023-10-31T23:12:45.967Z,1698793965.967 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:45.983Z,1698793965.983 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-10-31T23:12:45.983Z,1698793965.983 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:45.983Z,1698793965.983 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5105 2023-10-31T23:12:46.439Z,1698793966.439 [CTD_Seabird](INFO): Powering down 2023-10-31T23:12:46.450Z,1698793966.450 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:46.450Z,1698793966.450 [CTD_Seabird](INFO): Powering down 2023-10-31T23:12:46.462Z,1698793966.462 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:46.478Z,1698793966.478 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-10-31T23:12:46.478Z,1698793966.478 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:46.479Z,1698793966.479 [Radio_Surface](INFO): Join timeout helper Thread ID is 5106 2023-10-31T23:12:46.652Z,1698793966.652 [Radio_Surface](INFO): Powering down 2023-10-31T23:12:46.653Z,1698793966.653 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:46.654Z,1698793966.654 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:46.666Z,1698793966.666 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-10-31T23:12:46.666Z,1698793966.666 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:46.667Z,1698793966.667 [Onboard](INFO): Join timeout helper Thread ID is 5107 2023-10-31T23:12:48.818Z,1698793968.818 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:48.818Z,1698793968.818 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:48.823Z,1698793968.823 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-10-31T23:12:48.823Z,1698793968.823 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:48.823Z,1698793968.823 [DataOverHttps](INFO): Join timeout helper Thread ID is 5108 2023-10-31T23:12:49.266Z,1698793969.266 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:49.269Z,1698793969.269 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.279Z,1698793969.279 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-10-31T23:12:49.279Z,1698793969.279 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.279Z,1698793969.279 [BackseatComponent](INFO): Join timeout helper Thread ID is 5109 2023-10-31T23:12:49.318Z,1698793969.318 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:49.319Z,1698793969.319 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.339Z,1698793969.339 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-10-31T23:12:49.339Z,1698793969.339 [logger ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.339Z,1698793969.339 [logger](INFO): Join timeout helper Thread ID is 5110 2023-10-31T23:12:49.362Z,1698793969.362 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:49.362Z,1698793969.362 [logger ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.364Z,1698793969.364 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-10-31T23:12:49.364Z,1698793969.364 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.364Z,1698793969.364 [CommandLine](INFO): Join timeout helper Thread ID is 5111 2023-10-31T23:12:49.454Z,1698793969.454 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:49.455Z,1698793969.455 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.471Z,1698793969.471 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-10-31T23:12:49.471Z,1698793969.471 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.471Z,1698793969.471 [CommandExec](INFO): Join timeout helper Thread ID is 5112 2023-10-31T23:12:49.472Z,1698793969.472 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-10-31T23:12:49.473Z,1698793969.473 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.473Z,1698793969.473 [controlThread](INFO): Join timeout helper Thread ID is 5113 2023-10-31T23:12:49.766Z,1698793969.766 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-31T23:12:49.766Z,1698793969.766 [controlThread](DEBUG): Uninitializing ControlThread 2023-10-31T23:12:49.767Z,1698793969.767 [AHRS_M2](INFO): Powering down 2023-10-31T23:12:49.839Z,1698793969.839 [MultiRay](INFO): Powering down. 2023-10-31T23:12:49.840Z,1698793969.840 [NAL9602](INFO): Powering down 2023-10-31T23:12:49.842Z,1698793969.842 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-10-31T23:12:49.843Z,1698793969.843 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-10-31T23:12:49.843Z,1698793969.843 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-10-31T23:12:49.844Z,1698793969.844 [MissionManager](INFO): Uninitializing Mission Default 2023-10-31T23:12:49.844Z,1698793969.844 [Default] Stopped 2023-10-31T23:12:49.844Z,1698793969.844 [Default](DEBUG): Aggregate::uninitialize Default 2023-10-31T23:12:49.844Z,1698793969.844 [Default:B.GoToSurface] Stopped 2023-10-31T23:12:49.844Z,1698793969.844 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-31T23:12:49.844Z,1698793969.844 [Default:CheckIn] Stopped 2023-10-31T23:12:49.844Z,1698793969.844 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-31T23:12:49.845Z,1698793969.845 [Default:CheckIn:C.Wait] Stopped 2023-10-31T23:12:49.845Z,1698793969.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-31T23:12:49.847Z,1698793969.847 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-10-31T23:12:49.848Z,1698793969.848 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-10-31T23:12:49.848Z,1698793969.848 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-10-31T23:12:49.848Z,1698793969.848 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-10-31T23:12:49.848Z,1698793969.848 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-10-31T23:12:49.849Z,1698793969.849 [BuoyancyServo](INFO): Powering down 2023-10-31T23:12:49.862Z,1698793969.862 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-10-31T23:12:49.862Z,1698793969.862 [ElevatorServo](INFO): Powering down 2023-10-31T23:12:49.863Z,1698793969.863 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-10-31T23:12:49.863Z,1698793969.863 [MassServo](INFO): Powering down 2023-10-31T23:12:49.864Z,1698793969.864 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-10-31T23:12:49.864Z,1698793969.864 [RudderServo](INFO): Powering down 2023-10-31T23:12:49.865Z,1698793969.865 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-10-31T23:12:49.865Z,1698793969.865 [ThrusterHE](INFO): Powering down 2023-10-31T23:12:49.866Z,1698793969.866 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-10-31T23:12:49.866Z,1698793969.866 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-10-31T23:12:49.867Z,1698793969.867 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-10-31T23:12:49.867Z,1698793969.867 [CBIT](DEBUG): Powering off loads. 2023-10-31T23:12:49.878Z,1698793969.878 [CBIT](DEBUG): Disabling WDT. 2023-10-31T23:12:49.890Z,1698793969.890 [CBIT](DEBUG): Opening all GF detection circuits. 2023-10-31T23:12:49.891Z,1698793969.891 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.923Z,1698793969.923 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.927Z,1698793969.927 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.934Z,1698793969.934 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:49.977Z,1698793969.977 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:50.024Z,1698793970.024 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:50.029Z,1698793970.029 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:50.079Z,1698793970.079 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-31T23:12:50.126Z,1698793970.126 [logger ThreadHandler](INFO): Thread cancelled.