2024-02-14T16:22:06.765Z,1707927726.765 [Supervisor](DEBUG): Initializing supervisor.
2024-02-14T16:22:06.770Z,1707927726.770 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-02-14T16:22:06.771Z,1707927726.771 [SyncHandler](INFO): Protected caller Thread ID is 5172
2024-02-14T16:22:06.771Z,1707927726.771 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-02-14T16:22:06.773Z,1707927726.773 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-02-14T16:22:06.773Z,1707927726.773 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5173
2024-02-14T16:22:06.777Z,1707927726.777 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-02-14T16:22:06.794Z,1707927726.794 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-02-14T16:22:06.795Z,1707927726.795 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-02-14T16:22:06.796Z,1707927726.796 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5174
2024-02-14T16:22:06.800Z,1707927726.800 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-02-14T16:22:06.801Z,1707927726.801 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-02-14T16:22:06.801Z,1707927726.801 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5175
2024-02-14T16:22:06.803Z,1707927726.803 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-02-14T16:22:06.804Z,1707927726.804 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-02-14T16:22:06.804Z,1707927726.804 [logger ThreadHandler](INFO): Protected caller Thread ID is 5176
2024-02-14T16:22:06.808Z,1707927726.808 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-02-14T16:22:06.808Z,1707927726.808 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-02-14T16:22:06.810Z,1707927726.810 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-02-14T16:22:07.019Z,1707927727.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-02-14T16:22:07.019Z,1707927727.019 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-02-14T16:22:07.128Z,1707927727.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-02-14T16:22:07.129Z,1707927727.129 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-02-14T16:22:07.226Z,1707927727.226 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-02-14T16:22:07.226Z,1707927727.226 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-02-14T16:22:07.698Z,1707927727.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-02-14T16:22:07.699Z,1707927727.699 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-02-14T16:22:08.045Z,1707927728.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-02-14T16:22:08.046Z,1707927728.046 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-02-14T16:22:08.376Z,1707927728.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-02-14T16:22:08.377Z,1707927728.377 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-02-14T16:22:08.567Z,1707927728.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-02-14T16:22:08.568Z,1707927728.568 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-02-14T16:22:08.648Z,1707927728.648 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-02-14T16:22:08.730Z,1707927728.730 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-02-14T16:22:09.653Z,1707927729.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-02-14T16:22:09.653Z,1707927729.653 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-02-14T16:22:09.933Z,1707927729.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-02-14T16:22:09.934Z,1707927729.934 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-02-14T16:22:10.440Z,1707927730.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-02-14T16:22:10.441Z,1707927730.441 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-02-14T16:22:10.663Z,1707927730.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-02-14T16:22:10.663Z,1707927730.663 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-02-14T16:22:11.028Z,1707927731.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-02-14T16:22:11.029Z,1707927731.029 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-02-14T16:22:11.170Z,1707927731.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-02-14T16:22:11.170Z,1707927731.170 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-02-14T16:22:11.990Z,1707927731.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-02-14T16:22:11.992Z,1707927731.992 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2024-02-14T16:22:11.993Z,1707927731.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2024-02-14T16:22:12.079Z,1707927732.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2024-02-14T16:22:12.210Z,1707927732.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2024-02-14T16:22:12.477Z,1707927732.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-02-14T16:22:12.478Z,1707927732.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2024-02-14T16:22:12.601Z,1707927732.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2024-02-14T16:22:12.702Z,1707927732.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2024-02-14T16:22:12.797Z,1707927732.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2024-02-14T16:22:12.905Z,1707927732.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2024-02-14T16:22:13.001Z,1707927733.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2024-02-14T16:22:13.088Z,1707927733.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2024-02-14T16:22:13.189Z,1707927733.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/workSite.cfg
2024-02-14T16:22:13.272Z,1707927733.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2024-02-14T16:22:13.436Z,1707927733.436 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2024-02-14T16:22:13.436Z,1707927733.436 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2024-02-14T16:22:13.442Z,1707927733.442 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-02-14T16:22:13.492Z,1707927733.492 [DepthRateCalculator] Loaded
2024-02-14T16:22:13.492Z,1707927733.492 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-02-14T16:22:13.497Z,1707927733.497 [PitchRateCalculator] Loaded
2024-02-14T16:22:13.498Z,1707927733.498 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-02-14T16:22:13.508Z,1707927733.508 [SpeedCalculator] Loaded
2024-02-14T16:22:13.508Z,1707927733.508 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-02-14T16:22:13.513Z,1707927733.513 [YawRateCalculator] Loaded
2024-02-14T16:22:13.513Z,1707927733.513 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-02-14T16:22:13.535Z,1707927733.535 [ElevatorOffsetCalculator] Loaded
2024-02-14T16:22:13.535Z,1707927733.535 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-02-14T16:22:13.535Z,1707927733.535 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-02-14T16:22:13.536Z,1707927733.536 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-02-14T16:22:13.562Z,1707927733.562 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-02-14T16:22:13.562Z,1707927733.562 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-02-14T16:22:13.572Z,1707927733.572 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-02-14T16:22:13.573Z,1707927733.573 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-02-14T16:22:14.018Z,1707927734.018 [AHRS_M2] Loaded
2024-02-14T16:22:14.019Z,1707927734.019 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-02-14T16:22:14.054Z,1707927734.054 [BackseatComponent] Loaded
2024-02-14T16:22:14.054Z,1707927734.054 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-02-14T16:22:14.055Z,1707927734.055 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084F4E0
2024-02-14T16:22:14.055Z,1707927734.055 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5261
2024-02-14T16:22:14.058Z,1707927734.058 [LcmUniversalReporter] Loaded
2024-02-14T16:22:14.058Z,1707927734.058 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-02-14T16:22:14.860Z,1707927734.860 [BPC1] Loaded
2024-02-14T16:22:14.860Z,1707927734.860 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-02-14T16:22:14.935Z,1707927734.935 [DataOverHttps] Loaded
2024-02-14T16:22:14.935Z,1707927734.935 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-02-14T16:22:14.936Z,1707927734.936 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087F4E0
2024-02-14T16:22:14.936Z,1707927734.936 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5262
2024-02-14T16:22:14.957Z,1707927734.957 [Depth_Keller] Loaded
2024-02-14T16:22:14.957Z,1707927734.957 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-02-14T16:22:14.962Z,1707927734.962 [DropWeight] Loaded
2024-02-14T16:22:14.962Z,1707927734.962 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-02-14T16:22:14.980Z,1707927734.980 [MultiRay] Loaded
2024-02-14T16:22:14.981Z,1707927734.981 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2024-02-14T16:22:15.042Z,1707927735.042 [NAL9602] Loaded
2024-02-14T16:22:15.042Z,1707927735.042 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-02-14T16:22:15.130Z,1707927735.130 [Onboard] Loaded
2024-02-14T16:22:15.130Z,1707927735.130 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-02-14T16:22:15.131Z,1707927735.131 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AF4E0
2024-02-14T16:22:15.131Z,1707927735.131 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5263
2024-02-14T16:22:15.145Z,1707927735.145 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2024-02-14T16:22:15.165Z,1707927735.165 [PowerOnly] Loaded
2024-02-14T16:22:15.165Z,1707927735.165 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-02-14T16:22:15.220Z,1707927735.220 [Power24vConverter] Loaded
2024-02-14T16:22:15.252Z,1707927735.252 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-02-14T16:22:15.274Z,1707927735.274 [Radio_Surface] Loaded
2024-02-14T16:22:15.275Z,1707927735.275 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-02-14T16:22:15.284Z,1707927735.284 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DF4E0
2024-02-14T16:22:15.285Z,1707927735.285 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5264
2024-02-14T16:22:15.615Z,1707927735.615 [DAT] Loaded
2024-02-14T16:22:15.615Z,1707927735.615 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-02-14T16:22:15.616Z,1707927735.616 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0
2024-02-14T16:22:15.616Z,1707927735.616 [DAT ThreadHandler](INFO): Protected caller Thread ID is 5265
2024-02-14T16:22:15.617Z,1707927735.617 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-02-14T16:22:15.617Z,1707927735.617 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-02-14T16:22:15.943Z,1707927735.943 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-02-14T16:22:15.944Z,1707927735.944 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-02-14T16:22:15.985Z,1707927735.985 [DeadReckonUsingSpeedCalculator] Loaded
2024-02-14T16:22:15.985Z,1707927735.985 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-02-14T16:22:16.002Z,1707927736.002 [NavChart] Loaded
2024-02-14T16:22:16.002Z,1707927736.002 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-02-14T16:22:16.008Z,1707927736.008 [UniversalFixResidualReporter] Loaded
2024-02-14T16:22:16.008Z,1707927736.008 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-02-14T16:22:16.009Z,1707927736.009 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-02-14T16:22:16.009Z,1707927736.009 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-02-14T16:22:16.236Z,1707927736.236 [SBIT](DEBUG): Construct Startup Built In Test.
2024-02-14T16:22:16.250Z,1707927736.250 [SBIT] Loaded
2024-02-14T16:22:16.250Z,1707927736.250 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-02-14T16:22:16.253Z,1707927736.253 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-02-14T16:22:16.266Z,1707927736.266 [IBIT] Loaded
2024-02-14T16:22:16.266Z,1707927736.266 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-02-14T16:22:16.272Z,1707927736.272 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-02-14T16:22:16.376Z,1707927736.376 [CBIT] Loaded
2024-02-14T16:22:16.376Z,1707927736.376 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-02-14T16:22:16.376Z,1707927736.376 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-02-14T16:22:16.377Z,1707927736.377 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-02-14T16:22:16.547Z,1707927736.547 [CTD_Seabird] Loaded
2024-02-14T16:22:16.547Z,1707927736.547 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-02-14T16:22:16.548Z,1707927736.548 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2024-02-14T16:22:16.549Z,1707927736.549 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5266
2024-02-14T16:22:16.569Z,1707927736.569 [PAR_Licor] Loaded
2024-02-14T16:22:16.569Z,1707927736.569 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-02-14T16:22:16.610Z,1707927736.610 [WetLabsBB2FL] Loaded
2024-02-14T16:22:16.611Z,1707927736.611 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-02-14T16:22:16.612Z,1707927736.612 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2024-02-14T16:22:16.612Z,1707927736.612 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5267
2024-02-14T16:22:16.613Z,1707927736.613 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-02-14T16:22:16.613Z,1707927736.614 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-02-14T16:22:16.744Z,1707927736.744 [BuoyancyServo] Loaded
2024-02-14T16:22:16.744Z,1707927736.744 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-02-14T16:22:16.766Z,1707927736.766 [ElevatorServo] Loaded
2024-02-14T16:22:16.766Z,1707927736.766 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-02-14T16:22:16.787Z,1707927736.787 [MassServo] Loaded
2024-02-14T16:22:16.787Z,1707927736.787 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-02-14T16:22:16.807Z,1707927736.807 [RudderServo] Loaded
2024-02-14T16:22:16.807Z,1707927736.807 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-02-14T16:22:16.823Z,1707927736.823 [ThrusterHE] Loaded
2024-02-14T16:22:16.823Z,1707927736.823 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-02-14T16:22:16.824Z,1707927736.824 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-02-14T16:22:16.824Z,1707927736.824 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-02-14T16:22:16.874Z,1707927736.874 [VerticalControl](DEBUG): Construct VerticalControl.
2024-02-14T16:22:16.925Z,1707927736.925 [VerticalControl] Loaded
2024-02-14T16:22:16.925Z,1707927736.925 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-02-14T16:22:16.928Z,1707927736.928 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-02-14T16:22:16.966Z,1707927736.966 [HorizontalControl] Loaded
2024-02-14T16:22:16.966Z,1707927736.966 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-02-14T16:22:16.968Z,1707927736.968 [SpeedControl](DEBUG): Construct SpeedControl.
2024-02-14T16:22:16.970Z,1707927736.970 [SpeedControl] Loaded
2024-02-14T16:22:16.970Z,1707927736.970 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-02-14T16:22:16.973Z,1707927736.973 [LoopControl](DEBUG): Construct LoopControl.
2024-02-14T16:22:16.974Z,1707927736.974 [LoopControl] Loaded
2024-02-14T16:22:16.974Z,1707927736.974 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-02-14T16:22:16.974Z,1707927736.974 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-02-14T16:22:16.975Z,1707927736.975 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-02-14T16:22:17.085Z,1707927737.085 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-02-14T16:22:17.086Z,1707927737.086 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-02-14T16:22:17.229Z,1707927737.229 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-02-14T16:22:17.229Z,1707927737.229 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-02-14T16:22:17.559Z,1707927737.559 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-02-14T16:22:17.559Z,1707927737.559 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-02-14T16:22:17.616Z,1707927737.616 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-02-14T16:22:17.623Z,1707927737.623 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-02-14T16:22:17.626Z,1707927737.626 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-02-14T16:22:17.637Z,1707927737.637 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-02-14T16:22:17.638Z,1707927737.638 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C114E0
2024-02-14T16:22:17.638Z,1707927737.638 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5268
2024-02-14T16:22:17.642Z,1707927737.642 [Supervisor](INFO): Main Thread ID is 5029
2024-02-14T16:22:17.643Z,1707927737.643 [Supervisor](DEBUG): Running supervisor.
2024-02-14T16:22:17.643Z,1707927737.643 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5269
2024-02-14T16:22:17.644Z,1707927737.644 [CommandExec](INFO): Initializing the command executive.
2024-02-14T16:22:17.645Z,1707927737.645 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5270
2024-02-14T16:22:17.647Z,1707927737.647 [controlThread ThreadHandler](INFO): Handler Thread ID is 5271
2024-02-14T16:22:17.648Z,1707927737.648 [controlThread](DEBUG): Initializing ControlThread
2024-02-14T16:22:17.649Z,1707927737.649 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-02-14T16:22:17.649Z,1707927737.649 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-02-14T16:22:17.650Z,1707927737.650 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-02-14T16:22:17.650Z,1707927737.650 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-02-14T16:22:17.650Z,1707927737.650 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-02-14T16:22:17.655Z,1707927737.655 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:22:17.656Z,1707927737.656 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-02-14T16:22:17.656Z,1707927737.656 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-02-14T16:22:17.657Z,1707927737.657 [SBIT](INFO): Initialize SBIT Component.
2024-02-14T16:22:17.657Z,1707927737.657 [SBIT](IMPORTANT): git: 2024-02-14
2024-02-14T16:22:17.657Z,1707927737.657 [SBIT](INFO): git hash: 8fc4e848ca047b5de9ae8a7c02f55958c6301ab2
2024-02-14T16:22:17.657Z,1707927737.657 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-02-14T16:22:17.659Z,1707927737.659 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-02-14T16:22:17.660Z,1707927737.660 [SBIT](INFO): Beginning SBIT in 48.000000 seconds.
2024-02-14T16:22:17.660Z,1707927737.660 [IBIT](INFO): Initialize IBIT Component.
2024-02-14T16:22:17.661Z,1707927737.661 [CBIT](DEBUG): Initialize CBIT Component.
2024-02-14T16:22:17.662Z,1707927737.662 [logger ThreadHandler](INFO): Handler Thread ID is 5272
2024-02-14T16:22:17.672Z,1707927737.672 [CBIT](DEBUG): Initialized mux pins.
2024-02-14T16:22:17.673Z,1707927737.673 [CBIT](DEBUG): Initializing the watchdog timer.
2024-02-14T16:22:17.681Z,1707927737.681 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5273
2024-02-14T16:22:17.693Z,1707927737.693 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5274
2024-02-14T16:22:17.695Z,1707927737.695 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-02-14T16:22:17.697Z,1707927737.697 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-02-14T16:22:17.697Z,1707927737.697 [CBIT](DEBUG): Initializing heartbeat.
2024-02-14T16:22:17.705Z,1707927737.705 [Onboard ThreadHandler](INFO): Handler Thread ID is 5275
2024-02-14T16:22:17.722Z,1707927737.722 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5276
2024-02-14T16:22:17.745Z,1707927737.745 [DAT ThreadHandler](INFO): Handler Thread ID is 5277
2024-02-14T16:22:17.746Z,1707927737.746 [DAT](INFO): Powering up
2024-02-14T16:22:17.746Z,1707927737.746 [DAT](DEBUG): Initializing DAT.
2024-02-14T16:22:17.750Z,1707927737.750 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5279
2024-02-14T16:22:17.750Z,1707927737.750 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-02-14T16:22:17.754Z,1707927737.754 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5281
2024-02-14T16:22:17.757Z,1707927737.757 [WetLabsBB2FL](INFO): Powering up
2024-02-14T16:22:17.758Z,1707927737.758 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5283
2024-02-14T16:22:17.761Z,1707927737.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-02-14T16:22:17.761Z,1707927737.761 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-02-14T16:22:17.761Z,1707927737.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-02-14T16:22:17.762Z,1707927737.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-02-14T16:22:17.762Z,1707927737.762 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-02-14T16:22:17.762Z,1707927737.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-02-14T16:22:17.762Z,1707927737.762 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-02-14T16:22:17.762Z,1707927737.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-02-14T16:22:17.769Z,1707927737.769 [CBIT](DEBUG): Deactivating GF circuits.
2024-02-14T16:22:17.769Z,1707927737.769 [CBIT](DEBUG): Deactivating emergency mode.
2024-02-14T16:22:17.808Z,1707927737.808 [CBIT](DEBUG): Backplane powered.
2024-02-14T16:22:17.814Z,1707927737.814 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-02-14T16:22:17.815Z,1707927737.815 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-02-14T16:22:17.816Z,1707927737.816 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-02-14T16:22:17.817Z,1707927737.817 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-02-14T16:22:17.817Z,1707927737.817 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-02-14T16:22:17.818Z,1707927737.818 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-02-14T16:22:17.818Z,1707927737.818 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-02-14T16:22:17.819Z,1707927737.819 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-02-14T16:22:17.871Z,1707927737.871 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-02-14T16:22:17.876Z,1707927737.876 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-02-14T16:22:17.907Z,1707927737.907 [MissionManager](DEBUG):
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-02-14T16:22:17.908Z,1707927737.908 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2024-02-14T16:22:17.962Z,1707927737.962 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-02-14T16:22:17.963Z,1707927737.963 [Default:A.Wait](DEBUG): Construct Wait.
2024-02-14T16:22:17.981Z,1707927737.981 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-02-14T16:22:18.016Z,1707927738.016 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-02-14T16:22:18.019Z,1707927738.019 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-02-14T16:22:18.045Z,1707927738.045 [Default:E.Execute](DEBUG): Construct Execute.
2024-02-14T16:22:18.048Z,1707927738.048 [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
2024-02-14T16:22:18.053Z,1707927738.053 [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,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-02-14T16:22:18.060Z,1707927738.060 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-02-14T16:22:18.123Z,1707927738.123 [MultiRay](INFO): Powering up MultiRay Lights
2024-02-14T16:22:18.129Z,1707927738.129 [Radio_Surface](INFO): Powering up
2024-02-14T16:22:18.166Z,1707927738.166 [PowerOnly](INFO): Powering up loadControl
2024-02-14T16:22:18.185Z,1707927738.185 [Power24vConverter](INFO): Powering up.
2024-02-14T16:22:18.199Z,1707927738.199 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-02-14T16:22:18.205Z,1707927738.205 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-02-14T16:22:18.206Z,1707927738.206 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-02-14T16:22:18.213Z,1707927738.213 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-02-14T16:22:18.214Z,1707927738.214 [MassServo](DEBUG): Initializing EZServoServo.
2024-02-14T16:22:18.221Z,1707927738.221 [MassServo](DEBUG): Initializing MassServo.
2024-02-14T16:22:18.222Z,1707927738.222 [RudderServo](DEBUG): Initializing EZServoServo.
2024-02-14T16:22:18.229Z,1707927738.229 [RudderServo](DEBUG): Initializing RudderServo.
2024-02-14T16:22:18.230Z,1707927738.230 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-02-14T16:22:18.237Z,1707927738.237 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-02-14T16:22:20.369Z,1707927740.369 [WetLabsBB2FL](INFO): Powering down
2024-02-14T16:22:23.846Z,1707927743.846 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-02-14T16:22:24.781Z,1707927744.781 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-02-14T16:22:30.349Z,1707927750.349 [DAT](INFO): DAT read:
2024-02-14T16:22:30.350Z,1707927750.350 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-02-14T16:22:32.113Z,1707927752.113 [DAT](INFO): DAT read: MF Frequency Band
2024-02-14T16:22:32.115Z,1707927752.115 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-02-14T16:22:32.115Z,1707927752.115 [DAT](INFO): DAT read: Feb 7 2024 17:52:30
2024-02-14T16:22:33.122Z,1707927753.122 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-02-14T16:22:33.123Z,1707927753.123 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-02-14T16:22:33.123Z,1707927753.123 [DAT](INFO): commRate: 800
2024-02-14T16:22:35.189Z,1707927755.189 [DAT](INFO): entering command mode
2024-02-14T16:22:35.389Z,1707927755.389 [DAT](INFO): DAT read:
2024-02-14T16:22:35.389Z,1707927755.389 [DAT](INFO): DAT read: user:1>
2024-02-14T16:22:35.390Z,1707927755.390 [DAT](INFO): setting verbose to 3
2024-02-14T16:22:35.641Z,1707927755.641 [DAT](INFO): DAT read: user:1>
2024-02-14T16:22:35.642Z,1707927755.642 [DAT](INFO): DAT read: Verbose | 3
2024-02-14T16:22:35.642Z,1707927755.642 [DAT](INFO): set verbose to 3
2024-02-14T16:22:35.643Z,1707927755.643 [DAT](INFO): setting DatVerbose to 27440
2024-02-14T16:22:35.893Z,1707927755.893 [DAT](INFO): DAT read: user:2>
2024-02-14T16:22:35.894Z,1707927755.894 [DAT](INFO): DAT read: DatVerbose | 27440
2024-02-14T16:22:35.894Z,1707927755.894 [DAT](INFO): set DatVerbose to 27440
2024-02-14T16:22:35.895Z,1707927755.895 [DAT](INFO): setting transmit power to 8
2024-02-14T16:22:36.145Z,1707927756.145 [DAT](INFO): DAT read: user:3>
2024-02-14T16:22:36.146Z,1707927756.146 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-02-14T16:22:36.146Z,1707927756.146 [DAT](INFO): set transmit power to 8
2024-02-14T16:22:36.147Z,1707927756.147 [DAT](INFO): setting local address to 9
2024-02-14T16:22:36.397Z,1707927756.397 [DAT](INFO): DAT read: user:4>
2024-02-14T16:22:36.398Z,1707927756.398 [DAT](INFO): DAT read: LocalAddr | 9
2024-02-14T16:22:36.398Z,1707927756.398 [DAT](INFO): set local address to 9
2024-02-14T16:22:36.399Z,1707927756.399 [DAT](INFO): Setting time to: 16:22:36 And date to:2/14/2024
2024-02-14T16:22:36.649Z,1707927756.649 [DAT](INFO): DAT read: user:5>
2024-02-14T16:22:36.650Z,1707927756.650 [DAT](INFO): DAT read: Wed Feb 14, 2024 16:22:36
2024-02-14T16:22:36.651Z,1707927756.651 [DAT](INFO): Local DAT time set to Wed Feb 14, 2024 16:22:36
2024-02-14T16:22:45.183Z,1707927765.183 [NAL9602](INFO): Powering up NAL9602
2024-02-14T16:22:56.082Z,1707927776.082 [NAL9602](INFO): NAL9602 initialized
2024-02-14T16:23:06.200Z,1707927786.200 [SBIT](IMPORTANT): Beginning Startup BIT
2024-02-14T16:23:06.220Z,1707927786.220 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-14T16:23:17.156Z,1707927797.156 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005748
CHAN A1 (24V): 0.000654
CHAN A2 (12V): -0.005447
CHAN A3 (5V): -0.001687
CHAN B0 (3.3V): -0.001567
CHAN B1 (3.15aV): -0.000929
CHAN B2 (3.15bV): -0.001793
CHAN B3 (GND): 0.000008
OPEN: 0.004870
Full Scale: +/- 1 mA
2024-02-14T16:23:43.805Z,1707927823.805 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-14T16:23:50.082Z,1707927830.082 [CommandExec](IMPORTANT): got command strobe off
2024-02-14T16:23:50.082Z,1707927830.082 [CommandExec](IMPORTANT): Deactivating strobe
2024-02-14T16:23:58.415Z,1707927838.415 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-14T16:24:00.349Z,1707927840.349 [SBIT](IMPORTANT): SBIT PASSED
2024-02-14T16:24:00.349Z,1707927840.349 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-02-14T16:24:00.350Z,1707927840.350 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool;
2024-02-14T16:24:00.350Z,1707927840.350 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-02-14T16:24:00.350Z,1707927840.350 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=473.280850 cubic_centimeter;
2024-02-14T16:24:00.350Z,1707927840.350 [SBIT](IMPORTANT): VerticalControl.massDefault=-7.151472 millimeter;
2024-02-14T16:24:00.743Z,1707927840.743 [MissionManager](IMPORTANT): Started mission Startup
2024-02-14T16:24:00.744Z,1707927840.744 [Startup] Running Loop=1
2024-02-14T16:24:00.744Z,1707927840.744 [Startup](DEBUG): Aggregate::initialize Startup
2024-02-14T16:24:00.744Z,1707927840.744 [Startup:A.GoToSurface] Running Loop=1
2024-02-14T16:24:00.744Z,1707927840.744 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-02-14T16:24:00.765Z,1707927840.765 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-02-14T16:24:00.765Z,1707927840.765 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-02-14T16:24:00.765Z,1707927840.765 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-02-14T16:24:00.766Z,1707927840.766 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-02-14T16:24:00.766Z,1707927840.766 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-02-14T16:24:00.767Z,1707927840.767 [Startup:StartupSatComms] Running Loop=1
2024-02-14T16:24:00.768Z,1707927840.768 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-02-14T16:24:00.768Z,1707927840.768 [Startup:StartupSatComms:A] Running Loop=1
2024-02-14T16:24:01.140Z,1707927841.140 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-02-14T16:24:43.647Z,1707927883.647 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-14T16:24:45.743Z,1707927885.743 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007917
2024-02-14T16:24:56.531Z,1707927896.531 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-14T16:24:58.502Z,1707927898.502 [BPC1](ERROR): BPC1B: No match for serial number 0000 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-02-14T16:25:00.938Z,1707927900.938 [Startup:StartupSatComms:A](INFO): Timed out from 2024-02-14T16:24:00.8Z
2024-02-14T16:25:00.938Z,1707927900.938 [Startup:StartupSatComms:A] Stopped
2024-02-14T16:25:00.939Z,1707927900.939 [Startup:StartupSatComms:B] Running Loop=1
2024-02-14T16:25:01.336Z,1707927901.336 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-02-14T16:25:08.044Z,1707927908.044 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240214T014855/Courier0010.lzma
2024-02-14T16:25:09.046Z,1707927909.046 [DataOverHttps](INFO): Moved sent file to Logs/20240214T014855/Courier0010.lzma.bak
2024-02-14T16:25:09.047Z,1707927909.047 [DataOverHttps](INFO): SBD MOMSN=19277850
2024-02-14T16:25:17.893Z,1707927917.893 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-02-14T16:25:17.893Z,1707927917.893 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:25:17.894Z,1707927917.894 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-02-14T16:25:17.894Z,1707927917.894 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:25:17.905Z,1707927917.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:25:17.905Z,1707927917.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:25:18.307Z,1707927918.307 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:25:18.307Z,1707927918.307 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-02-14T16:25:18.308Z,1707927918.308 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:25:18.308Z,1707927918.308 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-02-14T16:25:18.713Z,1707927918.713 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:25:24.752Z,1707927924.752 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240214T161904/Courier0000.lzma
2024-02-14T16:25:25.754Z,1707927925.754 [DataOverHttps](INFO): Moved sent file to Logs/20240214T161904/Courier0000.lzma.bak
2024-02-14T16:25:25.754Z,1707927925.754 [DataOverHttps](INFO): SBD MOMSN=19277852
2024-02-14T16:25:41.296Z,1707927941.296 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240214T161904/Courier0004.lzma
2024-02-14T16:25:42.298Z,1707927942.298 [DataOverHttps](INFO): Moved sent file to Logs/20240214T161904/Courier0004.lzma.bak
2024-02-14T16:25:42.298Z,1707927942.298 [DataOverHttps](INFO): SBD MOMSN=19277855
2024-02-14T16:25:57.933Z,1707927957.933 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240214T162206/Courier0000.lzma
2024-02-14T16:25:58.935Z,1707927958.935 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0000.lzma.bak
2024-02-14T16:25:58.935Z,1707927958.935 [DataOverHttps](INFO): SBD MOMSN=19277857
2024-02-14T16:26:01.132Z,1707927961.132 [Startup:StartupSatComms:B](INFO): Timed out from 2024-02-14T16:25:00.9Z
2024-02-14T16:26:01.132Z,1707927961.132 [Startup:StartupSatComms:B] Stopped
2024-02-14T16:26:01.132Z,1707927961.132 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-02-14T16:26:01.133Z,1707927961.133 [Startup:StartupSatComms] Stopped
2024-02-14T16:26:01.133Z,1707927961.133 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-02-14T16:26:01.133Z,1707927961.133 [Startup](INFO): Completed Startup
2024-02-14T16:26:01.134Z,1707927961.134 [MissionManager](INFO): Startup is completed.
2024-02-14T16:26:01.134Z,1707927961.134 [MissionManager](INFO): Uninitializing Mission Startup
2024-02-14T16:26:01.134Z,1707927961.134 [Startup] Stopped
2024-02-14T16:26:01.134Z,1707927961.134 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-02-14T16:26:01.134Z,1707927961.134 [Startup:A.GoToSurface] Stopped
2024-02-14T16:26:01.134Z,1707927961.134 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-02-14T16:26:01.551Z,1707927961.551 [MissionManager](IMPORTANT): Started mission Default
2024-02-14T16:26:01.551Z,1707927961.551 [Default] Running Loop=1
2024-02-14T16:26:01.551Z,1707927961.551 [Default](DEBUG): Aggregate::initialize Default
2024-02-14T16:26:01.552Z,1707927961.552 [Default:B.GoToSurface] Running Loop=1
2024-02-14T16:26:01.552Z,1707927961.552 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-02-14T16:26:01.552Z,1707927961.552 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-02-14T16:26:01.552Z,1707927961.552 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-02-14T16:26:01.553Z,1707927961.553 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-02-14T16:26:01.553Z,1707927961.553 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-02-14T16:26:01.553Z,1707927961.553 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-02-14T16:26:01.554Z,1707927961.554 [Default:A.Wait] Running Loop=1
2024-02-14T16:26:01.554Z,1707927961.554 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-02-14T16:26:11.738Z,1707927971.738 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-14T16:26:14.764Z,1707927974.764 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20240214T014855/Express0011.lzma
2024-02-14T16:26:14.873Z,1707927974.873 [Default:A.Wait](INFO): Done Waiting.
2024-02-14T16:26:14.873Z,1707927974.873 [Default:A.Wait] Stopped
2024-02-14T16:26:14.873Z,1707927974.873 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T16:26:15.263Z,1707927975.263 [Default:CheckIn] Running Loop=1
2024-02-14T16:26:15.263Z,1707927975.263 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T16:26:15.264Z,1707927975.264 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T16:26:15.672Z,1707927975.672 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-02-14T16:26:15.766Z,1707927975.766 [DataOverHttps](INFO): Moved sent file to Logs/20240214T014855/Express0011.lzma.bak
2024-02-14T16:26:15.766Z,1707927975.766 [DataOverHttps](INFO): SBD MOMSN=19277860
2024-02-14T16:26:26.630Z,1707927986.630 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-14T16:26:31.613Z,1707927991.613 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/lab_test_optim.tl
2024-02-14T16:26:31.613Z,1707927991.613 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/lab_test_optim.tl
2024-02-14T16:26:31.613Z,1707927991.613 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/lab_test_optim.tl
2024-02-14T16:26:31.617Z,1707927991.617 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/lab_test_optim.tl
2024-02-14T16:26:32.107Z,1707927992.107 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/lab_test_optim.tl translated into:
15
1
30
1
3
2
100
100
0
Waiting
for backseat to start up...
Disabling Optim recording after
Powering up Optim to try to tide over gap to handoff...
! echo 1 > /dev/loadC3
Optim power on. Spinning for
cycles.
1
! echo 0 > /dev/loadC3
Optim power off. Spinning again.
1
! echo 1 > /dev/loadC3
Optim power on. Handing off to mission.
! echo 1 > /dev/loadC3; sleep 1; echo 0 > /dev/loadC3; sleep 1; echo 1 > dev/loadC3
Hardcoded Optim command sent. Good luck.
Trying to run Optim and Multiray forreal
1
Setting multiray red brightness to
2
Setting muliray white brightness to
Recording for
2024-02-14T16:26:32.114Z,1707927992.114 [MissionManager](INFO): DefineArg lab_test_optim.MissionTimeout = 15.000000 min
2024-02-14T16:26:32.117Z,1707927992.117 [MissionManager](INFO): DefineArg lab_test_optim.RecordTime = 1.000000 min
2024-02-14T16:26:32.120Z,1707927992.120 [MissionManager](INFO): DefineArg lab_test_optim.BackseatWait = 30.000000 s
2024-02-14T16:26:32.122Z,1707927992.122 [MissionManager](INFO): DefineArg lab_test_optim.UseMission = 1 bool
2024-02-14T16:26:32.125Z,1707927992.125 [MissionManager](INFO): DefineArg lab_test_optim.UseHardcode = 0 bool
2024-02-14T16:26:32.128Z,1707927992.128 [MissionManager](INFO): DefineArg lab_test_optim.InitialSampleTime = 1.000000 s
2024-02-14T16:26:32.131Z,1707927992.131 [MissionManager](INFO): DefineArg lab_test_optim.SleepCycles = 3.000000 count
2024-02-14T16:26:32.135Z,1707927992.135 [MissionManager](INFO): DefineArg lab_test_optim.LightMode = 2.000000 count
2024-02-14T16:26:32.138Z,1707927992.138 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessWhite = 100.000000 n/a int32
2024-02-14T16:26:32.169Z,1707927992.169 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessRed = 100.000000 n/a int32
2024-02-14T16:26:32.172Z,1707927992.172 [MissionManager](INFO): DefineOutput lab_test_optim.CyclesWaited = 0.000000 count
2024-02-14T16:26:32.179Z,1707927992.179 [MissionManager](INFO): DefineOutput lab_test_optim.RecordingEnabled = 0 bool
2024-02-14T16:26:32.193Z,1707927992.193 [MissionManager](INFO): DefineOutput lab_test_optim.LightsEnabled = 0 bool
2024-02-14T16:26:32.193Z,1707927992.193 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-02-14T16:26:32.193Z,1707927992.193 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2024-02-14T16:26:32.194Z,1707927992.194 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2024-02-14T16:26:32.282Z,1707927992.282 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2024-02-14T16:26:32.294Z,1707927992.294 [MissionManager](INFO): DefineArg lab_test_optim:BackseatDriver.EnableBackseat = 0 bool
2024-02-14T16:26:32.301Z,1707927992.301 [lab_test_optim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-02-14T16:26:32.324Z,1707927992.324 [lab_test_optim:Startup:B.Wait](DEBUG): Construct Wait.
2024-02-14T16:26:32.346Z,1707927992.346 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Construct Wait.
2024-02-14T16:26:32.363Z,1707927992.363 [lab_test_optim:Startup:UseBackdoor:A.Execute](DEBUG): Construct Execute.
2024-02-14T16:26:32.374Z,1707927992.374 [lab_test_optim:Startup:UseBackdoor:D.Execute](DEBUG): Construct Execute.
2024-02-14T16:26:32.380Z,1707927992.380 [lab_test_optim:Startup:UseBackdoor:G.Execute](DEBUG): Construct Execute.
2024-02-14T16:26:32.388Z,1707927992.388 [lab_test_optim:Startup:UseHardcode:A.Execute](DEBUG): Construct Execute.
2024-02-14T16:26:32.444Z,1707927992.444 [lab_test_optim:Run:H.Wait](DEBUG): Construct Wait.
2024-02-14T16:26:32.452Z,1707927992.452 [lab_test_optim:G.Wait](DEBUG): Construct Wait.
2024-02-14T16:26:32.472Z,1707927992.472 [MissionManager](DEBUG):
mission lab_test_optim {
arguments {
MissionTimeout = 15 minute
"""
Backseat will stay on for this duration, independent of Optim state
"""
RecordTime = 1 minute
"""
How long to record after startup sequence (hopefully) turns on Optim camera
"""
BackseatWait = 30 second
"""
How long to wait for the backseat to power up before attempting to power the Optim
"""
UseMission = true
"""
If true, tries to go through the application and use components/behaviors for commands.
Set InitialSampleTime to change delays. If false, backdoors app. Use SleepCycles to change delays.
Optim must be on C3.
"""
UseHardcode = false
"""
If true, backdoors app with a powercycle and a hardcoded sleep 1. Try the two other modes first?
Optim must be on C3.
"""
InitialSampleTime = 1 second
"""
PowerOnly sample time for initial record. Will go back to continuous after startup.
"""
SleepCycles = 3 count
"""
How many vehicle computation cycles to wait before commanding the Optim off on initial record
"""
LightMode = 2 count
"""
Mode for Multiray lights. 0 is off, 1 is red, 2 is white
"""
BrightnessWhite = 100 none_int
"""
White Multiray LED brightness, 0-100
"""
BrightnessRed = 100 none_int
"""
Red Multiray LED brightness, 0-100
"""
}
output {
CyclesWaited = 0 count
RecordingEnabled = false
LightsEnabled = false
}
timeout duration=MissionTimeout
insert Insert/BackseatDriver.tl
assign in parallel BackseatDriver:EnableBackseat = true
readData strategy="MinError" {
while ( RecordingEnabled )
Sensor:PowerOnly.sampleLoad1
}
readData strategy="MinError" {
while ( LightsEnabled )
Sensor:MultiRay.lightModeLog
}
aggregate Startup {
run in sequence
syslog important "Waiting " + BackseatWait~s + " for backseat to start up..."
behavior Guidance:Wait {
run in sequence
set duration = BackseatWait
}
aggregate UseMission {
run in sequence
break if (
not UseMission
or UseHardcode
)
assign in parallel Sensor:PowerOnly.sampleTime1 = InitialSampleTime
assign in sequence RecordingEnabled = true
syslog important "Disabling Optim recording after " + InitialSampleTime~s
assign in sequence RecordingEnabled = false
behavior Guidance:Wait {
run in sequence
set duration = InitialSampleTime
}
assign in sequence RecordingEnabled = true
syslog important "Powering up Optim to try to tide over gap to handoff..."
}
aggregate UseBackdoor {
run in sequence
break if (
UseMission
or UseHardcode
)
behavior Guidance:Execute {
run in sequence
set command = "! echo 1 > /dev/loadC3"
}
syslog important "Optim power on. Spinning for " + SleepCycles~count + " cycles."
aggregate WaitForStop {
run in sequence repeat=SleepCycles
assign in sequence CyclesWaited = CyclesWaited + 1 count
}
behavior Guidance:Execute {
run in sequence
set command = "! echo 0 > /dev/loadC3"
}
syslog important "Optim power off. Spinning again."
aggregate WaitForStart {
run in sequence repeat=SleepCycles
assign in sequence CyclesWaited = CyclesWaited + 1 count
}
behavior Guidance:Execute {
run in sequence
set command = "! echo 1 > /dev/loadC3"
}
syslog important "Optim power on. Handing off to mission."
}
aggregate UseHardcode {
run in sequence
break if ( not UseHardcode )
behavior Guidance:Execute {
run in sequence
set command = "! echo 1 > /dev/loadC3; sleep 1; echo 0 > /dev/loadC3; sleep 1; echo 1 > dev/loadC3"
}
syslog important "Hardcoded Optim command sent. Good luck."
}
}
aggregate Run {
run in sequence
syslog important "Trying to run Optim and Multiray forreal"
assign in sequence RecordingEnabled = true
assign in sequence LightsEnabled = true
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode
aggregate setRed {
run in sequence
break if ( LightMode != 1 count )
assign in sequence Sensor:MultiRay.brightnessRed = BrightnessRed
syslog info "Setting multiray red brightness to" + BrightnessRed~count
}
aggregate setWhite {
run in sequence
break if ( LightMode != 2 count )
assign in sequence Sensor:MultiRay.brightnessWhite = BrightnessWhite
syslog info "Setting muliray white brightness to" + BrightnessWhite~count
}
syslog important "Recording for " + RecordTime~min
behavior Guidance:Wait {
run in sequence
set duration = RecordTime
}
}
behavior Guidance:Wait {
run in sequence
set duration = MissionTimeout
}
}
2024-02-14T16:26:32.478Z,1707927992.478 [CommandExec](IMPORTANT): Running ./Missions/Engineering/lab_test_optim.tl
2024-02-14T16:26:32.671Z,1707927992.671 [Default] Stopped
2024-02-14T16:26:32.671Z,1707927992.671 [Default](DEBUG): Aggregate::uninitialize Default
2024-02-14T16:26:32.671Z,1707927992.671 [Default:B.GoToSurface] Stopped
2024-02-14T16:26:32.671Z,1707927992.671 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-02-14T16:26:32.671Z,1707927992.671 [Default:CheckIn] Stopped
2024-02-14T16:26:32.672Z,1707927992.672 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-14T16:26:32.672Z,1707927992.672 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T16:26:32.672Z,1707927992.672 [MissionManager](IMPORTANT): Started mission lab_test_optim
2024-02-14T16:26:32.672Z,1707927992.672 [lab_test_optim] Running Loop=1
2024-02-14T16:26:32.672Z,1707927992.672 [lab_test_optim](DEBUG): Aggregate::initialize lab_test_optim
2024-02-14T16:26:32.672Z,1707927992.672 [lab_test_optim:BackseatDriver] Running Loop=1
2024-02-14T16:26:32.673Z,1707927992.673 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::initialize lab_test_optim:BackseatDriver
2024-02-14T16:26:32.673Z,1707927992.673 [lab_test_optim:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-02-14T16:26:32.673Z,1707927992.673 [lab_test_optim:B] Running Loop=1
2024-02-14T16:26:32.673Z,1707927992.673 [lab_test_optim:C] Running Loop=1
2024-02-14T16:26:32.673Z,1707927992.673 [lab_test_optim:D] Running Loop=1
2024-02-14T16:26:32.673Z,1707927992.673 [lab_test_optim:Startup] Running Loop=1
2024-02-14T16:26:32.673Z,1707927992.673 [lab_test_optim:Startup](DEBUG): Aggregate::initialize lab_test_optim:Startup
2024-02-14T16:26:32.673Z,1707927992.673 [lab_test_optim:Startup:A] Running Loop=1
2024-02-14T16:26:32.674Z,1707927992.674 [lab_test_optim:Startup:A](IMPORTANT): Waiting 30.000000 s for backseat to start up...
2024-02-14T16:26:32.674Z,1707927992.674 [lab_test_optim:Startup:A] Stopped
2024-02-14T16:26:32.674Z,1707927992.674 [lab_test_optim:Startup:B.Wait] Running Loop=1
2024-02-14T16:26:32.674Z,1707927992.674 [lab_test_optim:Startup:B.Wait](DEBUG): Initialize Wait Component.
2024-02-14T16:26:32.674Z,1707927992.674 [lab_test_optim:D] Stopped
2024-02-14T16:26:32.674Z,1707927992.674 [lab_test_optim:C] Stopped
2024-02-14T16:26:32.674Z,1707927992.674 [lab_test_optim:B] Running Loop=1
2024-02-14T16:26:32.675Z,1707927992.675 [lab_test_optim:BackseatDriver] Running Loop=1
2024-02-14T16:26:32.675Z,1707927992.675 [lab_test_optim:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2024-02-14T16:26:32.741Z,1707927992.741 [BackseatComponent](INFO): Powering up
2024-02-14T16:26:32.741Z,1707927992.741 [BackseatComponent](INFO): Subscribing to LCM channels.
2024-02-14T16:27:03.346Z,1707928023.346 [lab_test_optim:Startup:B.Wait](INFO): Done Waiting.
2024-02-14T16:27:03.346Z,1707928023.346 [lab_test_optim:Startup:B.Wait] Stopped
2024-02-14T16:27:03.346Z,1707928023.346 [lab_test_optim:Startup:B.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T16:27:03.346Z,1707928023.346 [lab_test_optim:Startup:UseMission] Running Loop=1
2024-02-14T16:27:03.346Z,1707928023.346 [lab_test_optim:Startup:UseMission](DEBUG): Aggregate::initialize lab_test_optim:Startup:UseMission
2024-02-14T16:27:03.346Z,1707928023.346 [lab_test_optim:Startup:UseMission:A] Running Loop=1
2024-02-14T16:27:03.346Z,1707928023.346 [lab_test_optim:Startup:UseMission:B] Running Loop=1
2024-02-14T16:27:03.759Z,1707928023.759 [lab_test_optim:Startup:UseMission:B] Stopped
2024-02-14T16:27:03.760Z,1707928023.760 [lab_test_optim:Startup:UseMission:C] Running Loop=1
2024-02-14T16:27:03.760Z,1707928023.760 [lab_test_optim:Startup:UseMission:A] Running Loop=1
2024-02-14T16:27:04.187Z,1707928024.187 [lab_test_optim:Startup:UseMission:C](IMPORTANT): Disabling Optim recording after 1.000000 s
2024-02-14T16:27:04.187Z,1707928024.187 [lab_test_optim:Startup:UseMission:C] Stopped
2024-02-14T16:27:04.187Z,1707928024.187 [lab_test_optim:Startup:UseMission:D] Running Loop=1
2024-02-14T16:27:04.188Z,1707928024.188 [lab_test_optim:C] Running Loop=1
2024-02-14T16:27:04.188Z,1707928024.188 [lab_test_optim:C] Running Loop=1
2024-02-14T16:27:04.189Z,1707928024.189 [lab_test_optim:C](DEBUG): Initialize ReadDataComponent to sense PowerOnly.sampleLoad1
2024-02-14T16:27:04.565Z,1707928024.565 [PowerOnly](INFO): Powering down loadControl
2024-02-14T16:27:04.605Z,1707928024.605 [lab_test_optim:Startup:UseMission:D] Stopped
2024-02-14T16:27:04.605Z,1707928024.605 [lab_test_optim:Startup:UseMission:E.Wait] Running Loop=1
2024-02-14T16:27:04.605Z,1707928024.605 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Initialize Wait Component.
2024-02-14T16:27:04.983Z,1707928024.983 [lab_test_optim:C] Stopped
2024-02-14T16:27:06.171Z,1707928026.171 [lab_test_optim:Startup:UseMission:E.Wait](INFO): Done Waiting.
2024-02-14T16:27:06.171Z,1707928026.171 [lab_test_optim:Startup:UseMission:E.Wait] Stopped
2024-02-14T16:27:06.171Z,1707928026.171 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T16:27:06.172Z,1707928026.172 [lab_test_optim:Startup:UseMission:F] Running Loop=1
2024-02-14T16:27:06.580Z,1707928026.580 [lab_test_optim:Startup:UseMission:F] Stopped
2024-02-14T16:27:06.580Z,1707928026.580 [lab_test_optim:Startup:UseMission:G] Running Loop=1
2024-02-14T16:27:06.995Z,1707928026.995 [lab_test_optim:Startup:UseMission:G](IMPORTANT): Powering up Optim to try to tide over gap to handoff...
2024-02-14T16:27:06.996Z,1707928026.996 [lab_test_optim:Startup:UseMission:G] Stopped
2024-02-14T16:27:06.996Z,1707928026.996 [lab_test_optim:Startup:UseMission](INFO): Completed lab_test_optim:Startup:UseMission
2024-02-14T16:27:06.996Z,1707928026.996 [lab_test_optim:Startup:UseMission] Stopped
2024-02-14T16:27:06.996Z,1707928026.996 [lab_test_optim:Startup:UseMission](DEBUG): Aggregate::uninitialize lab_test_optim:Startup:UseMission
2024-02-14T16:27:06.996Z,1707928026.996 [lab_test_optim:Startup:UseMission:A] Stopped
2024-02-14T16:27:06.997Z,1707928026.997 [lab_test_optim:Startup:UseBackdoor] Running Loop=1
2024-02-14T16:27:06.997Z,1707928026.997 [lab_test_optim:Startup:UseBackdoor](DEBUG): Aggregate::initialize lab_test_optim:Startup:UseBackdoor
2024-02-14T16:27:06.997Z,1707928026.997 [lab_test_optim:Startup:UseBackdoor:A.Execute] Running Loop=1
2024-02-14T16:27:06.997Z,1707928026.997 [lab_test_optim:C] Running Loop=1
2024-02-14T16:27:07.379Z,1707928027.379 [PowerOnly](INFO): Powering up loadControl
2024-02-14T16:27:07.423Z,1707928027.423 [lab_test_optim:Startup:UseBackdoor] Stopped
2024-02-14T16:27:07.424Z,1707928027.424 [lab_test_optim:Startup:UseBackdoor](DEBUG): Aggregate::uninitialize lab_test_optim:Startup:UseBackdoor
2024-02-14T16:27:07.424Z,1707928027.424 [lab_test_optim:Startup:UseBackdoor:A.Execute] Stopped
2024-02-14T16:27:07.424Z,1707928027.424 [lab_test_optim:Startup:UseHardcode] Running Loop=1
2024-02-14T16:27:07.424Z,1707928027.424 [lab_test_optim:Startup:UseHardcode](DEBUG): Aggregate::initialize lab_test_optim:Startup:UseHardcode
2024-02-14T16:27:07.424Z,1707928027.424 [lab_test_optim:Startup:UseHardcode:A.Execute] Running Loop=1
2024-02-14T16:27:07.791Z,1707928027.791 [lab_test_optim:Startup:UseHardcode] Stopped
2024-02-14T16:27:07.791Z,1707928027.791 [lab_test_optim:Startup:UseHardcode](DEBUG): Aggregate::uninitialize lab_test_optim:Startup:UseHardcode
2024-02-14T16:27:07.791Z,1707928027.791 [lab_test_optim:Startup:UseHardcode:A.Execute] Stopped
2024-02-14T16:27:07.792Z,1707928027.792 [lab_test_optim:Startup](INFO): Completed lab_test_optim:Startup
2024-02-14T16:27:07.792Z,1707928027.792 [lab_test_optim:Startup] Stopped
2024-02-14T16:27:07.792Z,1707928027.792 [lab_test_optim:Startup](DEBUG): Aggregate::uninitialize lab_test_optim:Startup
2024-02-14T16:27:07.792Z,1707928027.792 [lab_test_optim:Run] Running Loop=1
2024-02-14T16:27:07.792Z,1707928027.792 [lab_test_optim:Run](DEBUG): Aggregate::initialize lab_test_optim:Run
2024-02-14T16:27:07.792Z,1707928027.792 [lab_test_optim:Run:A] Running Loop=1
2024-02-14T16:27:08.202Z,1707928028.202 [lab_test_optim:Run:A](IMPORTANT): Trying to run Optim and Multiray forreal
2024-02-14T16:27:08.202Z,1707928028.202 [lab_test_optim:Run:A] Stopped
2024-02-14T16:27:08.202Z,1707928028.202 [lab_test_optim:Run:B] Running Loop=1
2024-02-14T16:27:08.609Z,1707928028.609 [lab_test_optim:Run:B] Stopped
2024-02-14T16:27:08.609Z,1707928028.609 [lab_test_optim:Run:C] Running Loop=1
2024-02-14T16:27:09.003Z,1707928029.003 [lab_test_optim:Run:C] Stopped
2024-02-14T16:27:09.003Z,1707928029.003 [lab_test_optim:Run:D] Running Loop=1
2024-02-14T16:27:09.442Z,1707928029.442 [lab_test_optim:Run:D] Stopped
2024-02-14T16:27:09.442Z,1707928029.442 [lab_test_optim:Run:setRed] Running Loop=1
2024-02-14T16:27:09.442Z,1707928029.442 [lab_test_optim:Run:setRed](DEBUG): Aggregate::initialize lab_test_optim:Run:setRed
2024-02-14T16:27:09.442Z,1707928029.442 [lab_test_optim:Run:setRed:A] Running Loop=1
2024-02-14T16:27:09.442Z,1707928029.442 [lab_test_optim:D] Running Loop=1
2024-02-14T16:27:09.442Z,1707928029.442 [lab_test_optim:D] Running Loop=1
2024-02-14T16:27:09.443Z,1707928029.443 [lab_test_optim:D](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog
2024-02-14T16:27:09.831Z,1707928029.831 [lab_test_optim:Run:setRed] Stopped
2024-02-14T16:27:09.832Z,1707928029.832 [lab_test_optim:Run:setRed](DEBUG): Aggregate::uninitialize lab_test_optim:Run:setRed
2024-02-14T16:27:09.832Z,1707928029.832 [lab_test_optim:Run:setRed:A] Stopped
2024-02-14T16:27:09.832Z,1707928029.832 [lab_test_optim:Run:setWhite] Running Loop=1
2024-02-14T16:27:09.832Z,1707928029.832 [lab_test_optim:Run:setWhite](DEBUG): Aggregate::initialize lab_test_optim:Run:setWhite
2024-02-14T16:27:09.832Z,1707928029.832 [lab_test_optim:Run:setWhite:A] Running Loop=1
2024-02-14T16:27:10.259Z,1707928030.259 [lab_test_optim:Run:setWhite:A] Stopped
2024-02-14T16:27:10.259Z,1707928030.259 [lab_test_optim:Run:setWhite:B] Running Loop=1
2024-02-14T16:27:10.671Z,1707928030.671 [lab_test_optim:Run:setWhite:B](INFO): Setting muliray white brightness to 100.000000 count
2024-02-14T16:27:10.671Z,1707928030.671 [lab_test_optim:Run:setWhite:B] Stopped
2024-02-14T16:27:10.671Z,1707928030.671 [lab_test_optim:Run:setWhite](INFO): Completed lab_test_optim:Run:setWhite
2024-02-14T16:27:10.672Z,1707928030.672 [lab_test_optim:Run:setWhite] Stopped
2024-02-14T16:27:10.672Z,1707928030.672 [lab_test_optim:Run:setWhite](DEBUG): Aggregate::uninitialize lab_test_optim:Run:setWhite
2024-02-14T16:27:10.672Z,1707928030.672 [lab_test_optim:Run:G] Running Loop=1
2024-02-14T16:27:11.025Z,1707928031.025 [lab_test_optim:Run:G](IMPORTANT): Recording for 1.000000 min
2024-02-14T16:27:11.025Z,1707928031.025 [lab_test_optim:Run:G] Stopped
2024-02-14T16:27:11.025Z,1707928031.025 [lab_test_optim:Run:H.Wait] Running Loop=1
2024-02-14T16:27:11.025Z,1707928031.025 [lab_test_optim:Run:H.Wait](DEBUG): Initialize Wait Component.
2024-02-14T16:27:26.810Z,1707928046.810 [BackseatComponent](IMPORTANT): ikshana: running backseat application.
2024-02-14T16:27:29.312Z,1707928049.312 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2024-02-14T16:27:29.312Z,1707928049.312 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2024-02-14T16:27:29.619Z,1707928049.619 [lab_test_optim:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.data_dir_logging_rate with code: 1918
2024-02-14T16:27:59.923Z,1707928079.923 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-02-14T16:28:11.624Z,1707928091.624 [lab_test_optim:Run:H.Wait](INFO): Done Waiting.
2024-02-14T16:28:11.624Z,1707928091.624 [lab_test_optim:Run:H.Wait] Stopped
2024-02-14T16:28:11.624Z,1707928091.624 [lab_test_optim:Run:H.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T16:28:11.625Z,1707928091.625 [lab_test_optim:Run](INFO): Completed lab_test_optim:Run
2024-02-14T16:28:11.625Z,1707928091.625 [lab_test_optim:Run] Stopped
2024-02-14T16:28:11.625Z,1707928091.625 [lab_test_optim:Run](DEBUG): Aggregate::uninitialize lab_test_optim:Run
2024-02-14T16:28:11.625Z,1707928091.625 [lab_test_optim:G.Wait] Running Loop=1
2024-02-14T16:28:11.625Z,1707928091.625 [lab_test_optim:G.Wait](DEBUG): Initialize Wait Component.
2024-02-14T16:28:18.907Z,1707928098.907 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-02-14T16:28:18.907Z,1707928098.907 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:28:18.908Z,1707928098.908 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-02-14T16:28:18.908Z,1707928098.908 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:28:18.921Z,1707928098.921 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:28:18.921Z,1707928098.921 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:28:19.363Z,1707928099.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:28:19.363Z,1707928099.363 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-02-14T16:28:19.363Z,1707928099.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:28:19.363Z,1707928099.363 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-02-14T16:28:19.701Z,1707928099.701 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:28:21.301Z,1707928101.301 [CommandExec](IMPORTANT): got command stop
2024-02-14T16:28:21.301Z,1707928101.301 [CommandExec](IMPORTANT): Scheduling is paused
2024-02-14T16:28:21.302Z,1707928101.302 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2024-02-14T16:28:21.369Z,1707928101.369 [MissionManager](INFO): MissionManager is completed.
2024-02-14T16:28:21.369Z,1707928101.369 [MissionManager](INFO): Uninitializing Mission lab_test_optim
2024-02-14T16:28:21.369Z,1707928101.369 [lab_test_optim] Stopped
2024-02-14T16:28:21.369Z,1707928101.369 [lab_test_optim](DEBUG): Aggregate::uninitialize lab_test_optim
2024-02-14T16:28:21.369Z,1707928101.369 [lab_test_optim:BackseatDriver] Stopped
2024-02-14T16:28:21.369Z,1707928101.369 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::uninitialize lab_test_optim:BackseatDriver
2024-02-14T16:28:21.369Z,1707928101.369 [lab_test_optim:BackseatDriver:A.BackseatDriver] Stopped
2024-02-14T16:28:21.369Z,1707928101.369 [lab_test_optim:B] Stopped
2024-02-14T16:28:21.369Z,1707928101.369 [lab_test_optim:C] Stopped
2024-02-14T16:28:21.370Z,1707928101.370 [lab_test_optim:D] Stopped
2024-02-14T16:28:21.370Z,1707928101.370 [lab_test_optim:G.Wait] Stopped
2024-02-14T16:28:21.370Z,1707928101.370 [lab_test_optim:G.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T16:28:21.500Z,1707928101.500 [BackseatComponent](INFO): Sent LCM shutdown request.
2024-02-14T16:28:21.500Z,1707928101.500 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2024-02-14T16:28:21.739Z,1707928101.739 [MissionManager](IMPORTANT): Started mission Default
2024-02-14T16:28:21.739Z,1707928101.739 [Default] Running Loop=1
2024-02-14T16:28:21.739Z,1707928101.739 [Default](DEBUG): Aggregate::initialize Default
2024-02-14T16:28:21.739Z,1707928101.739 [Default:B.GoToSurface] Running Loop=1
2024-02-14T16:28:21.740Z,1707928101.740 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-02-14T16:28:21.740Z,1707928101.740 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-02-14T16:28:21.740Z,1707928101.740 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-02-14T16:28:21.769Z,1707928101.769 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-02-14T16:28:21.769Z,1707928101.769 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-02-14T16:28:21.769Z,1707928101.769 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-02-14T16:28:21.770Z,1707928101.770 [Default:A.Wait] Running Loop=1
2024-02-14T16:28:21.770Z,1707928101.770 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-02-14T16:28:35.067Z,1707928115.067 [Default:A.Wait](INFO): Done Waiting.
2024-02-14T16:28:35.067Z,1707928115.067 [Default:A.Wait] Stopped
2024-02-14T16:28:35.067Z,1707928115.067 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T16:28:35.467Z,1707928115.467 [Default:CheckIn] Running Loop=1
2024-02-14T16:28:35.467Z,1707928115.467 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T16:28:35.467Z,1707928115.467 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T16:28:39.661Z,1707928119.661 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2024-02-14T16:28:39.662Z,1707928119.662 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2024-02-14T16:28:39.662Z,1707928119.662 [LcmUniversalReporter](INFO): Deactivating messaging.
2024-02-14T16:28:42.777Z,1707928122.777 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-14T16:28:57.325Z,1707928137.325 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-14T16:29:11.810Z,1707928151.810 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2024-02-14T16:29:11.813Z,1707928151.813 [BPC1](INFO): Received data from all battery sticks.
2024-02-14T16:31:19.901Z,1707928279.901 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-02-14T16:31:19.901Z,1707928279.901 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:31:19.902Z,1707928279.902 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2024-02-14T16:31:19.902Z,1707928279.902 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:31:19.926Z,1707928279.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:31:19.926Z,1707928279.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:31:20.312Z,1707928280.312 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:31:20.312Z,1707928280.312 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-02-14T16:31:20.312Z,1707928280.312 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:31:20.312Z,1707928280.312 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2024-02-14T16:31:20.707Z,1707928280.707 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:32:58.454Z,1707928378.454 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-14T16:32:58.454Z,1707928378.454 [NAL9602] Data Fault, FailCount= 1
2024-02-14T16:32:58.454Z,1707928378.454 [NAL9602](ERROR): Data Fault
2024-02-14T16:32:58.472Z,1707928378.472 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-14T16:32:58.860Z,1707928378.860 [NAL9602](INFO): Powering down
2024-02-14T16:32:59.701Z,1707928379.701 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-14T16:32:59.701Z,1707928379.701 [NAL9602] No Fault, FailCount= 1
2024-02-14T16:33:29.154Z,1707928409.154 [NAL9602](INFO): Powering up NAL9602
2024-02-14T16:33:35.635Z,1707928415.635 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T16:28:35.5Z
2024-02-14T16:33:35.635Z,1707928415.635 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T16:33:35.635Z,1707928415.635 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-14T16:33:36.035Z,1707928416.035 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-02-14T16:33:40.062Z,1707928420.062 [NAL9602](INFO): NAL9602 initialized
2024-02-14T16:33:42.544Z,1707928422.544 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240214T162206/Courier0004.lzma
2024-02-14T16:33:43.546Z,1707928423.546 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0004.lzma.bak
2024-02-14T16:33:43.546Z,1707928423.546 [DataOverHttps](INFO): SBD MOMSN=19277865
2024-02-14T16:33:59.214Z,1707928439.214 [DataOverHttps](INFO): Sending 879 bytes from file Logs/20240214T161904/Express0001.lzma
2024-02-14T16:34:00.214Z,1707928440.214 [DataOverHttps](INFO): Moved sent file to Logs/20240214T161904/Express0001.lzma.bak
2024-02-14T16:34:00.214Z,1707928440.214 [DataOverHttps](INFO): SBD MOMSN=19277886
2024-02-14T16:34:15.784Z,1707928455.784 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20240214T161904/Express0005.lzma
2024-02-14T16:34:16.786Z,1707928456.786 [DataOverHttps](INFO): Moved sent file to Logs/20240214T161904/Express0005.lzma.bak
2024-02-14T16:34:16.787Z,1707928456.787 [DataOverHttps](INFO): SBD MOMSN=19277910
2024-02-14T16:34:20.916Z,1707928460.916 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-02-14T16:34:20.916Z,1707928460.916 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:34:20.921Z,1707928460.921 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2024-02-14T16:34:20.921Z,1707928460.921 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:34:20.940Z,1707928460.940 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:34:20.940Z,1707928460.940 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:34:21.342Z,1707928461.342 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:34:21.342Z,1707928461.342 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-02-14T16:34:21.342Z,1707928461.342 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:34:21.343Z,1707928461.343 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2024-02-14T16:34:21.709Z,1707928461.709 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:34:32.666Z,1707928472.666 [DataOverHttps](INFO): Sending 775 bytes from file Logs/20240214T162206/Express0001.lzma
2024-02-14T16:34:33.666Z,1707928473.666 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0001.lzma.bak
2024-02-14T16:34:33.666Z,1707928473.666 [DataOverHttps](INFO): SBD MOMSN=19277913
2024-02-14T16:34:49.246Z,1707928489.246 [DataOverHttps](INFO): Sending 577 bytes from file Logs/20240214T162206/Express0005.lzma
2024-02-14T16:34:50.246Z,1707928490.246 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0005.lzma.bak
2024-02-14T16:34:50.246Z,1707928490.246 [DataOverHttps](INFO): SBD MOMSN=19277931
2024-02-14T16:34:51.611Z,1707928491.611 [Default:CheckIn:Read_Iridium] Stopped
2024-02-14T16:34:51.611Z,1707928491.611 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-14T16:34:51.611Z,1707928491.611 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-14T16:37:21.890Z,1707928641.890 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-02-14T16:37:21.890Z,1707928641.890 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:37:21.891Z,1707928641.891 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2024-02-14T16:37:21.891Z,1707928641.891 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:37:21.902Z,1707928641.902 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:37:21.902Z,1707928641.902 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:37:22.290Z,1707928642.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:37:22.291Z,1707928642.291 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-02-14T16:37:22.291Z,1707928642.291 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:37:22.291Z,1707928642.291 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2024-02-14T16:37:22.677Z,1707928642.677 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:39:52.164Z,1707928792.164 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-14T16:39:52.164Z,1707928792.164 [Default:CheckIn:C.Wait] Stopped
2024-02-14T16:39:52.164Z,1707928792.164 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T16:39:52.164Z,1707928792.164 [Default:CheckIn:D] Running Loop=1
2024-02-14T16:39:52.571Z,1707928792.571 [Default:CheckIn:D] Stopped
2024-02-14T16:39:52.571Z,1707928792.571 [Default:CheckIn:E] Running Loop=1
2024-02-14T16:39:52.984Z,1707928792.984 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.513852 min
2024-02-14T16:39:52.988Z,1707928792.988 [Default:CheckIn:E] Stopped
2024-02-14T16:39:52.989Z,1707928792.989 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-14T16:39:52.989Z,1707928792.989 [Default:CheckIn] Stopped
2024-02-14T16:39:52.989Z,1707928792.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-14T16:39:52.989Z,1707928792.989 [Default:CheckIn](INFO): Running loop #2
2024-02-14T16:39:52.989Z,1707928792.989 [Default:CheckIn] Running Loop=2
2024-02-14T16:39:52.989Z,1707928792.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T16:39:52.989Z,1707928792.989 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T16:40:22.886Z,1707928822.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-02-14T16:40:22.886Z,1707928822.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:40:22.887Z,1707928822.887 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2024-02-14T16:40:22.887Z,1707928822.887 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:40:22.922Z,1707928822.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:40:22.922Z,1707928822.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:40:23.285Z,1707928823.285 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:40:23.285Z,1707928823.285 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-02-14T16:40:23.285Z,1707928823.285 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:40:23.285Z,1707928823.285 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2024-02-14T16:40:23.674Z,1707928823.674 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:43:23.914Z,1707929003.914 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-02-14T16:43:23.914Z,1707929003.914 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:43:23.915Z,1707929003.915 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2024-02-14T16:43:23.915Z,1707929003.915 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:43:23.960Z,1707929003.960 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:43:23.960Z,1707929003.960 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:43:24.327Z,1707929004.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:43:24.327Z,1707929004.327 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-02-14T16:43:24.327Z,1707929004.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:43:24.327Z,1707929004.327 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2024-02-14T16:43:24.713Z,1707929004.713 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:43:42.875Z,1707929022.875 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-14T16:43:42.875Z,1707929022.875 [NAL9602] Data Fault, FailCount= 2
2024-02-14T16:43:42.875Z,1707929022.875 [NAL9602](ERROR): Data Fault
2024-02-14T16:43:42.892Z,1707929022.892 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-14T16:43:43.279Z,1707929023.279 [NAL9602](INFO): Powering down
2024-02-14T16:43:44.099Z,1707929024.099 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-14T16:43:44.099Z,1707929024.099 [NAL9602] No Fault, FailCount= 2
2024-02-14T16:44:13.578Z,1707929053.578 [NAL9602](INFO): Powering up NAL9602
2024-02-14T16:44:24.486Z,1707929064.486 [NAL9602](INFO): NAL9602 initialized
2024-02-14T16:44:53.204Z,1707929093.204 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T16:39:52.0Z
2024-02-14T16:44:53.204Z,1707929093.204 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T16:44:53.204Z,1707929093.204 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-14T16:44:59.580Z,1707929099.580 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240214T162206/Courier0007.lzma
2024-02-14T16:45:00.582Z,1707929100.582 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0007.lzma.bak
2024-02-14T16:45:00.582Z,1707929100.582 [DataOverHttps](INFO): SBD MOMSN=19277983
2024-02-14T16:45:16.205Z,1707929116.205 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20240214T162206/Express0008.lzma
2024-02-14T16:45:17.206Z,1707929117.206 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0008.lzma.bak
2024-02-14T16:45:17.206Z,1707929117.206 [DataOverHttps](INFO): SBD MOMSN=19277985
2024-02-14T16:45:18.263Z,1707929118.263 [Default:CheckIn:Read_Iridium] Stopped
2024-02-14T16:45:18.263Z,1707929118.263 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-14T16:45:18.263Z,1707929118.263 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-14T16:46:24.886Z,1707929184.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-02-14T16:46:24.886Z,1707929184.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:46:24.887Z,1707929184.887 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2024-02-14T16:46:24.887Z,1707929184.887 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:46:24.898Z,1707929184.898 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:46:24.898Z,1707929184.898 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:46:25.315Z,1707929185.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:46:25.315Z,1707929185.315 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-02-14T16:46:25.316Z,1707929185.316 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:46:25.316Z,1707929185.316 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2024-02-14T16:46:25.723Z,1707929185.723 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:49:25.894Z,1707929365.894 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-02-14T16:49:25.894Z,1707929365.894 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:49:25.895Z,1707929365.895 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2024-02-14T16:49:25.895Z,1707929365.895 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:49:25.905Z,1707929365.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:49:25.905Z,1707929365.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:49:26.310Z,1707929366.310 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:49:26.310Z,1707929366.310 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-02-14T16:49:26.310Z,1707929366.310 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:49:26.311Z,1707929366.311 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2024-02-14T16:49:26.685Z,1707929366.685 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:50:18.803Z,1707929418.803 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-14T16:50:18.803Z,1707929418.803 [Default:CheckIn:C.Wait] Stopped
2024-02-14T16:50:18.803Z,1707929418.803 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T16:50:18.804Z,1707929418.804 [Default:CheckIn:D] Running Loop=1
2024-02-14T16:50:19.207Z,1707929419.207 [Default:CheckIn:D] Stopped
2024-02-14T16:50:19.207Z,1707929419.207 [Default:CheckIn:E] Running Loop=1
2024-02-14T16:50:19.612Z,1707929419.612 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.957798 min
2024-02-14T16:50:19.612Z,1707929419.612 [Default:CheckIn:E] Stopped
2024-02-14T16:50:19.613Z,1707929419.613 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-14T16:50:19.613Z,1707929419.613 [Default:CheckIn] Stopped
2024-02-14T16:50:19.613Z,1707929419.613 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-14T16:50:19.613Z,1707929419.613 [Default:CheckIn](INFO): Running loop #3
2024-02-14T16:50:19.613Z,1707929419.613 [Default:CheckIn] Running Loop=3
2024-02-14T16:50:19.613Z,1707929419.613 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T16:50:19.613Z,1707929419.613 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T16:50:23.097Z,1707929423.097 [CommandExec](IMPORTANT): got command failComponent
2024-02-14T16:50:23.097Z,1707929423.097 [CommandExec](IMPORTANT): Failed components:
2024-02-14T16:50:23.098Z,1707929423.098 [CommandExec](IMPORTANT): No failed Components.
2024-02-14T16:52:26.933Z,1707929546.933 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-02-14T16:52:26.933Z,1707929546.933 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:52:26.934Z,1707929546.934 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2024-02-14T16:52:26.934Z,1707929546.934 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:52:26.949Z,1707929546.949 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:52:26.949Z,1707929546.949 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:52:27.363Z,1707929547.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:52:27.364Z,1707929547.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-02-14T16:52:27.364Z,1707929547.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:52:27.364Z,1707929547.364 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2024-02-14T16:52:27.737Z,1707929547.737 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:54:27.310Z,1707929667.310 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-14T16:54:27.310Z,1707929667.310 [NAL9602] Data Fault, FailCount= 3
2024-02-14T16:54:27.310Z,1707929667.310 [NAL9602](ERROR): Data Fault
2024-02-14T16:54:27.328Z,1707929667.328 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-14T16:54:27.710Z,1707929667.710 [NAL9602](INFO): Powering down
2024-02-14T16:54:28.535Z,1707929668.535 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-14T16:54:28.535Z,1707929668.535 [NAL9602] No Fault, FailCount= 3
2024-02-14T16:54:58.010Z,1707929698.010 [NAL9602](INFO): Powering up NAL9602
2024-02-14T16:55:08.915Z,1707929708.915 [NAL9602](INFO): NAL9602 initialized
2024-02-14T16:55:19.835Z,1707929719.835 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T16:50:19.6Z
2024-02-14T16:55:19.836Z,1707929719.836 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T16:55:19.836Z,1707929719.836 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-14T16:55:26.968Z,1707929726.968 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240214T162206/Courier0010.lzma
2024-02-14T16:55:27.945Z,1707929727.945 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-02-14T16:55:27.945Z,1707929727.945 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:55:27.946Z,1707929727.946 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2024-02-14T16:55:27.946Z,1707929727.946 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:55:27.957Z,1707929727.957 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:55:27.957Z,1707929727.957 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:55:27.970Z,1707929727.970 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0010.lzma.bak
2024-02-14T16:55:27.970Z,1707929727.970 [DataOverHttps](INFO): SBD MOMSN=19277998
2024-02-14T16:55:28.359Z,1707929728.359 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:55:28.359Z,1707929728.359 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-02-14T16:55:28.359Z,1707929728.359 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:55:28.359Z,1707929728.359 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2024-02-14T16:55:28.745Z,1707929728.745 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T16:55:43.545Z,1707929743.545 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20240214T162206/Express0011.lzma
2024-02-14T16:55:44.546Z,1707929744.546 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0011.lzma.bak
2024-02-14T16:55:44.546Z,1707929744.546 [DataOverHttps](INFO): SBD MOMSN=19278000
2024-02-14T16:55:45.708Z,1707929745.708 [Default:CheckIn:Read_Iridium] Stopped
2024-02-14T16:55:45.708Z,1707929745.708 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-14T16:55:45.708Z,1707929745.708 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-14T16:58:28.926Z,1707929908.926 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-02-14T16:58:28.926Z,1707929908.926 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T16:58:28.928Z,1707929908.928 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2024-02-14T16:58:28.928Z,1707929908.928 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T16:58:28.938Z,1707929908.938 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T16:58:28.938Z,1707929908.938 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T16:58:29.346Z,1707929909.346 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T16:58:29.347Z,1707929909.347 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-02-14T16:58:29.347Z,1707929909.347 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T16:58:29.347Z,1707929909.347 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2024-02-14T16:58:29.744Z,1707929909.744 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:00:46.295Z,1707930046.295 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-14T17:00:46.295Z,1707930046.295 [Default:CheckIn:C.Wait] Stopped
2024-02-14T17:00:46.295Z,1707930046.295 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T17:00:46.295Z,1707930046.295 [Default:CheckIn:D] Running Loop=1
2024-02-14T17:00:46.685Z,1707930046.685 [Default:CheckIn:D] Stopped
2024-02-14T17:00:46.685Z,1707930046.685 [Default:CheckIn:E] Running Loop=1
2024-02-14T17:00:47.076Z,1707930047.076 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.415763 min
2024-02-14T17:00:47.076Z,1707930047.076 [Default:CheckIn:E] Stopped
2024-02-14T17:00:47.076Z,1707930047.076 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-14T17:00:47.076Z,1707930047.076 [Default:CheckIn] Stopped
2024-02-14T17:00:47.076Z,1707930047.076 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-14T17:00:47.076Z,1707930047.076 [Default:CheckIn](INFO): Running loop #4
2024-02-14T17:00:47.077Z,1707930047.077 [Default:CheckIn] Running Loop=4
2024-02-14T17:00:47.077Z,1707930047.077 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T17:00:47.077Z,1707930047.077 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T17:01:29.914Z,1707930089.914 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-02-14T17:01:29.914Z,1707930089.914 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:01:29.915Z,1707930089.915 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2024-02-14T17:01:29.915Z,1707930089.915 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:01:29.926Z,1707930089.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:01:29.926Z,1707930089.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:01:30.322Z,1707930090.322 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:01:30.322Z,1707930090.322 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-02-14T17:01:30.322Z,1707930090.322 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:01:30.323Z,1707930090.323 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2024-02-14T17:01:30.705Z,1707930090.705 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:04:30.895Z,1707930270.895 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2024-02-14T17:04:30.895Z,1707930270.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:04:30.896Z,1707930270.896 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2024-02-14T17:04:30.896Z,1707930270.896 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:04:30.907Z,1707930270.907 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:04:30.907Z,1707930270.907 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:04:31.318Z,1707930271.318 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:04:31.318Z,1707930271.318 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2024-02-14T17:04:31.318Z,1707930271.318 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:04:31.318Z,1707930271.318 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2024-02-14T17:04:31.701Z,1707930271.701 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:05:10.478Z,1707930310.478 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-14T17:05:10.478Z,1707930310.478 [NAL9602] Data Fault, FailCount= 4
2024-02-14T17:05:10.478Z,1707930310.478 [NAL9602](ERROR): Data Fault
2024-02-14T17:05:10.521Z,1707930310.521 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-14T17:05:10.878Z,1707930310.878 [NAL9602](INFO): Powering down
2024-02-14T17:05:11.723Z,1707930311.723 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-14T17:05:11.723Z,1707930311.723 [NAL9602] No Fault, FailCount= 4
2024-02-14T17:05:41.182Z,1707930341.182 [NAL9602](INFO): Powering up NAL9602
2024-02-14T17:05:47.255Z,1707930347.255 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T17:00:47.1Z
2024-02-14T17:05:47.255Z,1707930347.255 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T17:05:47.255Z,1707930347.255 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-14T17:05:52.086Z,1707930352.086 [NAL9602](INFO): NAL9602 initialized
2024-02-14T17:05:54.056Z,1707930354.056 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240214T162206/Courier0013.lzma
2024-02-14T17:05:55.058Z,1707930355.058 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0013.lzma.bak
2024-02-14T17:05:55.058Z,1707930355.058 [DataOverHttps](INFO): SBD MOMSN=19278016
2024-02-14T17:06:10.636Z,1707930370.636 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240214T162206/Express0014.lzma
2024-02-14T17:06:11.638Z,1707930371.638 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0014.lzma.bak
2024-02-14T17:06:11.639Z,1707930371.639 [DataOverHttps](INFO): SBD MOMSN=19278018
2024-02-14T17:06:12.703Z,1707930372.703 [Default:CheckIn:Read_Iridium] Stopped
2024-02-14T17:06:12.703Z,1707930372.703 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-14T17:06:12.704Z,1707930372.704 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-14T17:07:31.886Z,1707930451.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2024-02-14T17:07:31.886Z,1707930451.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:07:31.887Z,1707930451.887 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2024-02-14T17:07:31.887Z,1707930451.887 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:07:31.922Z,1707930451.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:07:31.922Z,1707930451.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:07:32.303Z,1707930452.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:07:32.303Z,1707930452.303 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2024-02-14T17:07:32.303Z,1707930452.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:07:32.304Z,1707930452.304 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2024-02-14T17:07:32.695Z,1707930452.695 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:10:32.890Z,1707930632.890 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2024-02-14T17:10:32.890Z,1707930632.890 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:10:32.891Z,1707930632.891 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2024-02-14T17:10:32.891Z,1707930632.891 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:10:32.902Z,1707930632.902 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:10:32.902Z,1707930632.902 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:10:33.291Z,1707930633.291 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:10:33.291Z,1707930633.291 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2024-02-14T17:10:33.291Z,1707930633.291 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:10:33.291Z,1707930633.291 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2024-02-14T17:10:33.686Z,1707930633.686 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:11:13.299Z,1707930673.299 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-14T17:11:13.299Z,1707930673.299 [Default:CheckIn:C.Wait] Stopped
2024-02-14T17:11:13.299Z,1707930673.299 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T17:11:13.300Z,1707930673.300 [Default:CheckIn:D] Running Loop=1
2024-02-14T17:11:13.707Z,1707930673.707 [Default:CheckIn:D] Stopped
2024-02-14T17:11:13.707Z,1707930673.707 [Default:CheckIn:E] Running Loop=1
2024-02-14T17:11:14.108Z,1707930674.108 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.866134 min
2024-02-14T17:11:14.108Z,1707930674.108 [Default:CheckIn:E] Stopped
2024-02-14T17:11:14.109Z,1707930674.109 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-14T17:11:14.109Z,1707930674.109 [Default:CheckIn] Stopped
2024-02-14T17:11:14.109Z,1707930674.109 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-14T17:11:14.109Z,1707930674.109 [Default:CheckIn](INFO): Running loop #5
2024-02-14T17:11:14.109Z,1707930674.109 [Default:CheckIn] Running Loop=5
2024-02-14T17:11:14.109Z,1707930674.109 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T17:11:14.109Z,1707930674.109 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T17:13:33.869Z,1707930813.869 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2024-02-14T17:13:33.869Z,1707930813.869 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:13:33.870Z,1707930813.870 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2024-02-14T17:13:33.870Z,1707930813.870 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:13:33.914Z,1707930813.914 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:13:33.915Z,1707930813.915 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:13:34.279Z,1707930814.279 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:13:34.279Z,1707930814.279 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2024-02-14T17:13:34.279Z,1707930814.279 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:13:34.279Z,1707930814.279 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2024-02-14T17:13:34.685Z,1707930814.685 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:15:55.666Z,1707930955.666 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-14T17:15:55.666Z,1707930955.666 [NAL9602] Data Fault, FailCount= 5
2024-02-14T17:15:55.666Z,1707930955.666 [NAL9602](ERROR): Data Fault
2024-02-14T17:15:55.721Z,1707930955.721 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-14T17:15:55.724Z,1707930955.724 [CBIT](CRITICAL): Data Fault in component: NAL9602
2024-02-14T17:15:56.071Z,1707930956.071 [NAL9602](INFO): Powering down
2024-02-14T17:15:56.086Z,1707930956.086 [CommandExec](FAULT): Scheduling is paused
2024-02-14T17:15:56.087Z,1707930956.087 [CBIT](INFO): Critical error at 20240214T171555
2024-02-14T17:16:14.255Z,1707930974.255 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T17:11:14.1Z
2024-02-14T17:16:14.255Z,1707930974.255 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T17:16:14.255Z,1707930974.255 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-14T17:16:20.487Z,1707930980.487 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20240214T162206/Courier0016.lzma
2024-02-14T17:16:21.486Z,1707930981.486 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0016.lzma.bak
2024-02-14T17:16:21.486Z,1707930981.486 [DataOverHttps](INFO): SBD MOMSN=19278030
2024-02-14T17:16:34.873Z,1707930994.873 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2024-02-14T17:16:34.873Z,1707930994.873 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:16:34.874Z,1707930994.874 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2024-02-14T17:16:34.874Z,1707930994.874 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:16:34.925Z,1707930994.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:16:34.925Z,1707930994.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:16:35.300Z,1707930995.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:16:35.300Z,1707930995.300 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2024-02-14T17:16:35.300Z,1707930995.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:16:35.328Z,1707930995.328 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2024-02-14T17:16:35.685Z,1707930995.685 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:16:37.232Z,1707930997.232 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20240214T162206/Express0017.lzma
2024-02-14T17:16:38.234Z,1707930998.234 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0017.lzma.bak
2024-02-14T17:16:38.234Z,1707930998.234 [DataOverHttps](INFO): SBD MOMSN=19278033
2024-02-14T17:16:39.306Z,1707930999.306 [Default:CheckIn:Read_Iridium] Stopped
2024-02-14T17:16:39.306Z,1707930999.306 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-14T17:16:39.306Z,1707930999.306 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-14T17:17:55.680Z,1707931075.680 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-14T17:17:55.681Z,1707931075.681 [NAL9602] No Fault, FailCount= 5
2024-02-14T17:17:56.054Z,1707931076.054 [NAL9602](INFO): Powering up NAL9602
2024-02-14T17:18:06.967Z,1707931086.967 [NAL9602](INFO): NAL9602 initialized
2024-02-14T17:19:35.862Z,1707931175.862 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2024-02-14T17:19:35.862Z,1707931175.862 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:19:35.863Z,1707931175.863 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2024-02-14T17:19:35.863Z,1707931175.863 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:19:35.873Z,1707931175.873 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:19:35.873Z,1707931175.873 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:19:36.278Z,1707931176.278 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:19:36.278Z,1707931176.278 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2024-02-14T17:19:36.278Z,1707931176.278 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:19:36.279Z,1707931176.279 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2024-02-14T17:19:36.665Z,1707931176.665 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:21:39.903Z,1707931299.903 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-14T17:21:39.903Z,1707931299.903 [Default:CheckIn:C.Wait] Stopped
2024-02-14T17:21:39.903Z,1707931299.903 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T17:21:39.904Z,1707931299.904 [Default:CheckIn:D] Running Loop=1
2024-02-14T17:21:40.311Z,1707931300.311 [Default:CheckIn:D] Stopped
2024-02-14T17:21:40.311Z,1707931300.311 [Default:CheckIn:E] Running Loop=1
2024-02-14T17:21:40.714Z,1707931300.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.309530 min
2024-02-14T17:21:40.714Z,1707931300.714 [Default:CheckIn:E] Stopped
2024-02-14T17:21:40.714Z,1707931300.714 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-14T17:21:40.714Z,1707931300.714 [Default:CheckIn] Stopped
2024-02-14T17:21:40.714Z,1707931300.714 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-14T17:21:40.714Z,1707931300.714 [Default:CheckIn](INFO): Running loop #6
2024-02-14T17:21:40.714Z,1707931300.714 [Default:CheckIn] Running Loop=6
2024-02-14T17:21:40.715Z,1707931300.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T17:21:40.715Z,1707931300.715 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T17:22:36.854Z,1707931356.854 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2024-02-14T17:22:36.854Z,1707931356.854 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:22:36.855Z,1707931356.855 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2024-02-14T17:22:36.855Z,1707931356.855 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:22:36.914Z,1707931356.914 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:22:36.915Z,1707931356.915 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:22:37.275Z,1707931357.275 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:22:37.275Z,1707931357.275 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2024-02-14T17:22:37.276Z,1707931357.276 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:22:37.276Z,1707931357.276 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2024-02-14T17:22:37.672Z,1707931357.672 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:25:37.914Z,1707931537.914 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2024-02-14T17:25:37.914Z,1707931537.914 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:25:37.915Z,1707931537.915 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 21
2024-02-14T17:25:37.915Z,1707931537.915 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:25:37.926Z,1707931537.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:25:37.926Z,1707931537.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:25:38.335Z,1707931538.335 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:25:38.335Z,1707931538.335 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2024-02-14T17:25:38.335Z,1707931538.335 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:25:38.336Z,1707931538.336 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 21
2024-02-14T17:25:38.698Z,1707931538.698 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:26:40.960Z,1707931600.960 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T17:21:40.7Z
2024-02-14T17:26:40.960Z,1707931600.960 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T17:26:40.960Z,1707931600.960 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-14T17:26:47.764Z,1707931607.764 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240214T162206/Courier0019.lzma
2024-02-14T17:26:48.766Z,1707931608.766 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0019.lzma.bak
2024-02-14T17:26:48.766Z,1707931608.766 [DataOverHttps](INFO): SBD MOMSN=19278075
2024-02-14T17:27:04.404Z,1707931624.404 [DataOverHttps](INFO): Sending 108 bytes from file Logs/20240214T162206/Express0020.lzma
2024-02-14T17:27:05.406Z,1707931625.406 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0020.lzma.bak
2024-02-14T17:27:05.407Z,1707931625.407 [DataOverHttps](INFO): SBD MOMSN=19278077
2024-02-14T17:27:06.798Z,1707931626.798 [Default:CheckIn:Read_Iridium] Stopped
2024-02-14T17:27:06.798Z,1707931626.798 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-14T17:27:06.799Z,1707931626.799 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-14T17:28:10.190Z,1707931690.190 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-14T17:28:10.191Z,1707931690.191 [NAL9602] Data Fault, FailCount= 1
2024-02-14T17:28:10.191Z,1707931690.191 [NAL9602](ERROR): Data Fault
2024-02-14T17:28:10.237Z,1707931690.237 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-14T17:28:10.592Z,1707931690.592 [NAL9602](INFO): Powering down
2024-02-14T17:28:11.431Z,1707931691.431 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-14T17:28:11.431Z,1707931691.431 [NAL9602] No Fault, FailCount= 1
2024-02-14T17:28:38.886Z,1707931718.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2024-02-14T17:28:38.886Z,1707931718.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:28:38.887Z,1707931718.887 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 22
2024-02-14T17:28:38.887Z,1707931718.887 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:28:38.898Z,1707931718.898 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:28:38.898Z,1707931718.898 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:28:39.310Z,1707931719.310 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:28:39.310Z,1707931719.310 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2024-02-14T17:28:39.311Z,1707931719.311 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:28:39.311Z,1707931719.311 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 22
2024-02-14T17:28:39.688Z,1707931719.688 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:28:40.894Z,1707931720.894 [NAL9602](INFO): Powering up NAL9602
2024-02-14T17:28:51.798Z,1707931731.798 [NAL9602](INFO): NAL9602 initialized
2024-02-14T17:30:00.045Z,1707931800.045 [CommandExec](IMPORTANT): got command ibit
2024-02-14T17:30:00.115Z,1707931800.115 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-02-14T17:30:00.115Z,1707931800.115 [IBIT](IMPORTANT): Beginning control surface checks.
2024-02-14T17:30:00.119Z,1707931800.119 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-14T17:30:11.023Z,1707931811.023 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007273
CHAN A1 (24V): 0.000337
CHAN A2 (12V): -0.005793
CHAN A3 (5V): -0.002192
CHAN B0 (3.3V): -0.001067
CHAN B1 (3.15aV): -0.001173
CHAN B2 (3.15bV): -0.001447
CHAN B3 (GND): -0.000222
OPEN: 0.004897
Full Scale: +/- 1 mA
2024-02-14T17:31:39.933Z,1707931899.933 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2024-02-14T17:31:39.933Z,1707931899.933 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:31:39.934Z,1707931899.934 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 23
2024-02-14T17:31:39.934Z,1707931899.934 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:31:39.943Z,1707931899.943 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:31:39.944Z,1707931899.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:31:40.330Z,1707931900.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:31:40.330Z,1707931900.330 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2024-02-14T17:31:40.330Z,1707931900.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:31:40.330Z,1707931900.330 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 23
2024-02-14T17:31:40.721Z,1707931900.721 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:31:51.210Z,1707931911.210 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-02-14T17:31:51.606Z,1707931911.606 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 368.223999
Voltage: 16.650999
2024-02-14T17:31:51.607Z,1707931911.607 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-02-14T17:31:51.607Z,1707931911.607 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-02-14T17:31:52.011Z,1707931912.011 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-02-14T17:31:52.011Z,1707931912.011 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-02-14T17:31:52.012Z,1707931912.012 [IBIT](IMPORTANT): Pressure:8.559553 PSI
2024-02-14T17:31:52.012Z,1707931912.012 [IBIT](IMPORTANT): Humidity:30.209986 %
2024-02-14T17:31:52.424Z,1707931912.424 [IBIT](IMPORTANT): Vehicle Pitch:-0.653900 degrees
2024-02-14T17:31:52.428Z,1707931912.428 [IBIT](IMPORTANT): Vehicle Roll:-0.849625 degrees
2024-02-14T17:31:52.429Z,1707931912.429 [IBIT](IMPORTANT): Vehicle Heading:62.134510 degrees
2024-02-14T17:31:52.945Z,1707931912.945 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-02-14T17:31:52.945Z,1707931912.945 [IBIT](IMPORTANT): buoyancyNeutral: 473.280853 cc
2024-02-14T17:31:52.945Z,1707931912.945 [IBIT](IMPORTANT): massDefault: -0.715147 cm
2024-02-14T17:31:52.946Z,1707931912.946 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2024-02-14T17:31:52.946Z,1707931912.946 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-02-14T17:31:52.946Z,1707931912.946 [IBIT](IMPORTANT): IBIT FAILED
2024-02-14T17:32:07.375Z,1707931927.375 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-14T17:32:07.375Z,1707931927.375 [Default:CheckIn:C.Wait] Stopped
2024-02-14T17:32:07.376Z,1707931927.376 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T17:32:07.376Z,1707931927.376 [Default:CheckIn:D] Running Loop=1
2024-02-14T17:32:07.787Z,1707931927.787 [Default:CheckIn:D] Stopped
2024-02-14T17:32:07.787Z,1707931927.787 [Default:CheckIn:E] Running Loop=1
2024-02-14T17:32:08.183Z,1707931928.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.767464 min
2024-02-14T17:32:08.183Z,1707931928.183 [Default:CheckIn:E] Stopped
2024-02-14T17:32:08.183Z,1707931928.183 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-14T17:32:08.183Z,1707931928.183 [Default:CheckIn] Stopped
2024-02-14T17:32:08.183Z,1707931928.183 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-14T17:32:08.183Z,1707931928.183 [Default:CheckIn](INFO): Running loop #7
2024-02-14T17:32:08.183Z,1707931928.183 [Default:CheckIn] Running Loop=7
2024-02-14T17:32:08.183Z,1707931928.183 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T17:32:08.183Z,1707931928.183 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T17:34:40.886Z,1707932080.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2024-02-14T17:34:40.886Z,1707932080.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:34:40.887Z,1707932080.887 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 24
2024-02-14T17:34:40.887Z,1707932080.887 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:34:40.898Z,1707932080.898 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:34:40.898Z,1707932080.898 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:34:41.307Z,1707932081.307 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:34:41.307Z,1707932081.307 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2024-02-14T17:34:41.308Z,1707932081.308 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:34:41.308Z,1707932081.308 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 24
2024-02-14T17:34:41.677Z,1707932081.677 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:34:42.929Z,1707932082.929 [CommandLine](INFO): End of History
2024-02-14T17:34:42.930Z,1707932082.930 [CommandLine](INFO): End of History
2024-02-14T17:34:42.931Z,1707932082.931 [CommandLine](INFO): End of History
2024-02-14T17:34:46.717Z,1707932086.717 [CommandExec](IMPORTANT): got command ibit
2024-02-14T17:34:47.027Z,1707932087.027 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-02-14T17:34:47.027Z,1707932087.027 [IBIT](IMPORTANT): Beginning control surface checks.
2024-02-14T17:34:47.030Z,1707932087.030 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-14T17:34:57.864Z,1707932097.864 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006403
CHAN A1 (24V): 0.000624
CHAN A2 (12V): -0.005296
CHAN A3 (5V): -0.002778
CHAN B0 (3.3V): -0.001501
CHAN B1 (3.15aV): -0.001540
CHAN B2 (3.15bV): -0.001409
CHAN B3 (GND): -0.000263
OPEN: 0.005136
Full Scale: +/- 1 mA
2024-02-14T17:36:38.076Z,1707932198.076 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-02-14T17:36:38.464Z,1707932198.464 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 368.223999
Voltage: 16.650999
2024-02-14T17:36:38.511Z,1707932198.511 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-02-14T17:36:38.511Z,1707932198.511 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-02-14T17:36:38.876Z,1707932198.876 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-02-14T17:36:38.876Z,1707932198.876 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-02-14T17:36:38.897Z,1707932198.897 [IBIT](IMPORTANT): Pressure:8.587647 PSI
2024-02-14T17:36:38.898Z,1707932198.898 [IBIT](IMPORTANT): Humidity:30.332071 %
2024-02-14T17:36:39.292Z,1707932199.292 [IBIT](IMPORTANT): Vehicle Pitch:-0.669098 degrees
2024-02-14T17:36:39.292Z,1707932199.292 [IBIT](IMPORTANT): Vehicle Roll:-0.835571 degrees
2024-02-14T17:36:39.293Z,1707932199.293 [IBIT](IMPORTANT): Vehicle Heading:62.162304 degrees
2024-02-14T17:36:39.697Z,1707932199.697 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-02-14T17:36:39.697Z,1707932199.697 [IBIT](IMPORTANT): buoyancyNeutral: 473.280853 cc
2024-02-14T17:36:39.697Z,1707932199.697 [IBIT](IMPORTANT): massDefault: -0.715147 cm
2024-02-14T17:36:39.698Z,1707932199.698 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2024-02-14T17:36:39.698Z,1707932199.698 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-02-14T17:36:39.698Z,1707932199.698 [IBIT](IMPORTANT): IBIT FAILED
2024-02-14T17:36:40.469Z,1707932200.469 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2024-02-14T17:36:40.471Z,1707932200.471 [BPC1](INFO): Received data from all battery sticks.
2024-02-14T17:36:46.225Z,1707932206.225 [CommandExec](IMPORTANT): got command ibit
2024-02-14T17:36:46.538Z,1707932206.538 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-02-14T17:36:46.538Z,1707932206.538 [IBIT](IMPORTANT): Beginning control surface checks.
2024-02-14T17:36:46.561Z,1707932206.561 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-14T17:36:57.472Z,1707932217.472 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006758
CHAN A1 (24V): 0.000831
CHAN A2 (12V): -0.004846
CHAN A3 (5V): -0.002272
CHAN B0 (3.3V): -0.000575
CHAN B1 (3.15aV): -0.001508
CHAN B2 (3.15bV): -0.001279
CHAN B3 (GND): -0.000763
OPEN: 0.005102
Full Scale: +/- 1 mA
2024-02-14T17:37:41.901Z,1707932261.901 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2024-02-14T17:37:41.901Z,1707932261.901 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:37:41.902Z,1707932261.902 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 25
2024-02-14T17:37:41.902Z,1707932261.902 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:37:41.911Z,1707932261.911 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:37:41.911Z,1707932261.911 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:37:42.314Z,1707932262.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:37:42.314Z,1707932262.314 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2024-02-14T17:37:42.315Z,1707932262.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:37:42.315Z,1707932262.315 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 25
2024-02-14T17:37:42.686Z,1707932262.686 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:38:37.653Z,1707932317.653 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-02-14T17:38:38.061Z,1707932318.061 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2024-02-14T17:38:38.061Z,1707932318.061 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-02-14T17:38:38.061Z,1707932318.061 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-02-14T17:38:38.474Z,1707932318.474 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-02-14T17:38:38.474Z,1707932318.474 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-02-14T17:38:38.475Z,1707932318.475 [IBIT](IMPORTANT): Pressure:8.545895 PSI
2024-02-14T17:38:38.475Z,1707932318.475 [IBIT](IMPORTANT): Humidity:30.441948 %
2024-02-14T17:38:38.849Z,1707932318.849 [IBIT](IMPORTANT): Vehicle Pitch:-0.659128 degrees
2024-02-14T17:38:38.849Z,1707932318.849 [IBIT](IMPORTANT): Vehicle Roll:-0.840013 degrees
2024-02-14T17:38:38.850Z,1707932318.850 [IBIT](IMPORTANT): Vehicle Heading:62.150970 degrees
2024-02-14T17:38:39.259Z,1707932319.259 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-02-14T17:38:39.259Z,1707932319.259 [IBIT](IMPORTANT): buoyancyNeutral: 473.280853 cc
2024-02-14T17:38:39.259Z,1707932319.259 [IBIT](IMPORTANT): massDefault: -0.715147 cm
2024-02-14T17:38:39.259Z,1707932319.259 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2024-02-14T17:38:39.260Z,1707932319.260 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-02-14T17:38:39.260Z,1707932319.260 [IBIT](IMPORTANT): IBIT FAILED
2024-02-14T17:38:39.661Z,1707932319.661 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T17:32:08.2Z
2024-02-14T17:38:39.661Z,1707932319.661 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T17:38:39.662Z,1707932319.662 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-14T17:38:46.824Z,1707932326.824 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240214T162206/Courier0022.lzma
2024-02-14T17:38:47.826Z,1707932327.826 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0022.lzma.bak
2024-02-14T17:38:47.826Z,1707932327.826 [DataOverHttps](INFO): SBD MOMSN=19278098
2024-02-14T17:38:54.187Z,1707932334.187 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-14T17:38:54.187Z,1707932334.187 [NAL9602] Data Fault, FailCount= 2
2024-02-14T17:38:54.187Z,1707932334.187 [NAL9602](ERROR): Data Fault
2024-02-14T17:38:54.215Z,1707932334.215 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-14T17:38:54.586Z,1707932334.586 [NAL9602](INFO): Powering down
2024-02-14T17:38:55.409Z,1707932335.409 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-14T17:38:55.409Z,1707932335.409 [NAL9602] No Fault, FailCount= 2
2024-02-14T17:39:03.407Z,1707932343.407 [DataOverHttps](INFO): Sending 1043 bytes from file Logs/20240214T162206/Express0023.lzma
2024-02-14T17:39:04.402Z,1707932344.402 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0023.lzma.bak
2024-02-14T17:39:04.403Z,1707932344.403 [DataOverHttps](INFO): SBD MOMSN=19278100
2024-02-14T17:39:05.514Z,1707932345.514 [Default:CheckIn:Read_Iridium] Stopped
2024-02-14T17:39:05.514Z,1707932345.514 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-14T17:39:05.514Z,1707932345.514 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-14T17:39:24.890Z,1707932364.890 [NAL9602](INFO): Powering up NAL9602
2024-02-14T17:39:35.802Z,1707932375.802 [NAL9602](INFO): NAL9602 initialized
2024-02-14T17:40:42.894Z,1707932442.894 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2024-02-14T17:40:42.894Z,1707932442.894 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:40:42.895Z,1707932442.895 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 26
2024-02-14T17:40:42.895Z,1707932442.895 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:40:42.905Z,1707932442.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:40:42.906Z,1707932442.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:40:43.323Z,1707932443.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:40:43.323Z,1707932443.323 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2024-02-14T17:40:43.324Z,1707932443.324 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:40:43.324Z,1707932443.324 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 26
2024-02-14T17:40:43.689Z,1707932443.689 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:43:43.874Z,1707932623.874 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2024-02-14T17:43:43.874Z,1707932623.874 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:43:43.875Z,1707932623.875 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 27
2024-02-14T17:43:43.875Z,1707932623.875 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:43:43.894Z,1707932623.894 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:43:43.894Z,1707932623.894 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:43:44.283Z,1707932624.283 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:43:44.283Z,1707932624.283 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2024-02-14T17:43:44.284Z,1707932624.284 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:43:44.284Z,1707932624.284 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 27
2024-02-14T17:43:44.675Z,1707932624.675 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:44:06.091Z,1707932646.091 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-14T17:44:06.091Z,1707932646.091 [Default:CheckIn:C.Wait] Stopped
2024-02-14T17:44:06.091Z,1707932646.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T17:44:06.091Z,1707932646.091 [Default:CheckIn:D] Running Loop=1
2024-02-14T17:44:06.523Z,1707932646.523 [Default:CheckIn:D] Stopped
2024-02-14T17:44:06.523Z,1707932646.523 [Default:CheckIn:E] Running Loop=1
2024-02-14T17:44:06.920Z,1707932646.920 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.746395 min
2024-02-14T17:44:06.920Z,1707932646.920 [Default:CheckIn:E] Stopped
2024-02-14T17:44:06.920Z,1707932646.920 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-14T17:44:06.920Z,1707932646.920 [Default:CheckIn] Stopped
2024-02-14T17:44:06.920Z,1707932646.920 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-14T17:44:06.921Z,1707932646.921 [Default:CheckIn](INFO): Running loop #8
2024-02-14T17:44:06.921Z,1707932646.921 [Default:CheckIn] Running Loop=8
2024-02-14T17:44:06.921Z,1707932646.921 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T17:44:06.921Z,1707932646.921 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T17:46:44.858Z,1707932804.858 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2024-02-14T17:46:44.858Z,1707932804.858 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:46:44.859Z,1707932804.859 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 28
2024-02-14T17:46:44.859Z,1707932804.859 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:46:44.894Z,1707932804.894 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:46:44.894Z,1707932804.894 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:46:45.274Z,1707932805.274 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:46:45.274Z,1707932805.274 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2024-02-14T17:46:45.275Z,1707932805.275 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:46:45.275Z,1707932805.275 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 28
2024-02-14T17:46:45.677Z,1707932805.677 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:49:07.120Z,1707932947.120 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T17:44:06.9Z
2024-02-14T17:49:07.120Z,1707932947.120 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T17:49:07.120Z,1707932947.120 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-14T17:49:13.864Z,1707932953.864 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240214T162206/Courier0025.lzma
2024-02-14T17:49:14.867Z,1707932954.867 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0025.lzma.bak
2024-02-14T17:49:14.867Z,1707932954.867 [DataOverHttps](INFO): SBD MOMSN=19278265
2024-02-14T17:49:30.436Z,1707932970.436 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240214T162206/Express0026.lzma
2024-02-14T17:49:31.438Z,1707932971.438 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0026.lzma.bak
2024-02-14T17:49:31.439Z,1707932971.439 [DataOverHttps](INFO): SBD MOMSN=19278267
2024-02-14T17:49:32.548Z,1707932972.548 [Default:CheckIn:Read_Iridium] Stopped
2024-02-14T17:49:32.548Z,1707932972.548 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-14T17:49:32.548Z,1707932972.548 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-14T17:49:37.362Z,1707932977.362 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-14T17:49:37.362Z,1707932977.362 [NAL9602] Data Fault, FailCount= 3
2024-02-14T17:49:37.362Z,1707932977.362 [NAL9602](ERROR): Data Fault
2024-02-14T17:49:37.432Z,1707932977.432 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-14T17:49:37.775Z,1707932977.775 [NAL9602](INFO): Powering down
2024-02-14T17:49:38.608Z,1707932978.608 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-14T17:49:38.608Z,1707932978.608 [NAL9602] No Fault, FailCount= 3
2024-02-14T17:49:45.854Z,1707932985.854 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2024-02-14T17:49:45.854Z,1707932985.854 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:49:45.855Z,1707932985.855 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 29
2024-02-14T17:49:45.855Z,1707932985.855 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:49:45.865Z,1707932985.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:49:45.865Z,1707932985.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:49:46.279Z,1707932986.279 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:49:46.279Z,1707932986.279 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29
2024-02-14T17:49:46.280Z,1707932986.280 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:49:46.280Z,1707932986.280 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 29
2024-02-14T17:49:46.661Z,1707932986.661 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:50:08.066Z,1707933008.066 [NAL9602](INFO): Powering up NAL9602
2024-02-14T17:50:18.974Z,1707933018.974 [NAL9602](INFO): NAL9602 initialized
2024-02-14T17:52:46.874Z,1707933166.874 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30
2024-02-14T17:52:46.874Z,1707933166.874 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:52:46.876Z,1707933166.876 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 30
2024-02-14T17:52:46.876Z,1707933166.876 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:52:46.886Z,1707933166.886 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:52:46.886Z,1707933166.886 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:52:47.301Z,1707933167.301 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:52:47.301Z,1707933167.301 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30
2024-02-14T17:52:47.301Z,1707933167.301 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:52:47.302Z,1707933167.302 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 30
2024-02-14T17:52:47.665Z,1707933167.665 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:54:33.113Z,1707933273.113 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-14T17:54:33.113Z,1707933273.113 [Default:CheckIn:C.Wait] Stopped
2024-02-14T17:54:33.113Z,1707933273.113 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-14T17:54:33.113Z,1707933273.113 [Default:CheckIn:D] Running Loop=1
2024-02-14T17:54:33.504Z,1707933273.504 [Default:CheckIn:D] Stopped
2024-02-14T17:54:33.504Z,1707933273.504 [Default:CheckIn:E] Running Loop=1
2024-02-14T17:54:33.907Z,1707933273.907 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.196077 min
2024-02-14T17:54:33.907Z,1707933273.907 [Default:CheckIn:E] Stopped
2024-02-14T17:54:33.907Z,1707933273.907 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-14T17:54:33.908Z,1707933273.908 [Default:CheckIn] Stopped
2024-02-14T17:54:33.908Z,1707933273.908 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-14T17:54:33.908Z,1707933273.908 [Default:CheckIn](INFO): Running loop #9
2024-02-14T17:54:33.908Z,1707933273.908 [Default:CheckIn] Running Loop=9
2024-02-14T17:54:33.908Z,1707933273.908 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-14T17:54:33.908Z,1707933273.908 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-14T17:55:47.838Z,1707933347.838 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31
2024-02-14T17:55:47.838Z,1707933347.838 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:55:47.839Z,1707933347.839 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 31
2024-02-14T17:55:47.839Z,1707933347.839 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:55:47.849Z,1707933347.849 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:55:47.850Z,1707933347.850 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:55:48.271Z,1707933348.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:55:48.271Z,1707933348.271 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31
2024-02-14T17:55:48.272Z,1707933348.272 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:55:48.272Z,1707933348.272 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 31
2024-02-14T17:55:48.665Z,1707933348.665 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:58:48.871Z,1707933528.871 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32
2024-02-14T17:58:48.871Z,1707933528.871 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-14T17:58:48.872Z,1707933528.872 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 32
2024-02-14T17:58:48.872Z,1707933528.872 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-02-14T17:58:48.882Z,1707933528.882 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-14T17:58:48.882Z,1707933528.882 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-02-14T17:58:49.305Z,1707933529.305 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-14T17:58:49.305Z,1707933529.305 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32
2024-02-14T17:58:49.305Z,1707933529.305 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-02-14T17:58:49.305Z,1707933529.305 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 32
2024-02-14T17:58:49.707Z,1707933529.707 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-02-14T17:59:06.561Z,1707933546.561 [CommandExec](IMPORTANT): got command ibit
2024-02-14T17:59:06.689Z,1707933546.689 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-02-14T17:59:06.689Z,1707933546.689 [IBIT](IMPORTANT): Beginning control surface checks.
2024-02-14T17:59:06.697Z,1707933546.697 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-14T17:59:17.568Z,1707933557.568 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007064
CHAN A1 (24V): 0.000973
CHAN A2 (12V): -0.005875
CHAN A3 (5V): -0.003299
CHAN B0 (3.3V): -0.001504
CHAN B1 (3.15aV): -0.000922
CHAN B2 (3.15bV): -0.001049
CHAN B3 (GND): -0.000412
OPEN: 0.004717
Full Scale: +/- 1 mA
2024-02-14T17:59:42.522Z,1707933582.522 [DataOverHttps](IMPORTANT): SBD MTMSN=20240214T175941
2024-02-14T17:59:51.161Z,1707933591.161 [DataOverHttps](INFO): Received command: ibit
2024-02-14T17:59:51.193Z,1707933591.193 [CommandExec](IMPORTANT): got command ibit
2024-02-14T18:00:22.186Z,1707933622.186 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-14T18:00:22.186Z,1707933622.186 [NAL9602] Data Fault, FailCount= 4
2024-02-14T18:00:22.186Z,1707933622.186 [NAL9602](ERROR): Data Fault
2024-02-14T18:00:22.226Z,1707933622.226 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-14T18:00:22.590Z,1707933622.590 [NAL9602](INFO): Powering down
2024-02-14T18:00:23.434Z,1707933623.434 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-14T18:00:23.434Z,1707933623.434 [NAL9602] No Fault, FailCount= 4
2024-02-14T18:00:52.894Z,1707933652.894 [NAL9602](INFO): Powering up NAL9602
2024-02-14T18:00:57.781Z,1707933657.781 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-02-14T18:00:58.188Z,1707933658.188 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2024-02-14T18:00:58.196Z,1707933658.196 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-02-14T18:00:58.197Z,1707933658.197 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-02-14T18:00:58.586Z,1707933658.586 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-02-14T18:00:58.586Z,1707933658.586 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-02-14T18:00:58.587Z,1707933658.587 [IBIT](IMPORTANT): Pressure:8.560187 PSI
2024-02-14T18:00:58.587Z,1707933658.587 [IBIT](IMPORTANT): Humidity:30.722744 %
2024-02-14T18:00:58.986Z,1707933658.986 [IBIT](IMPORTANT): Vehicle Pitch:-0.653565 degrees
2024-02-14T18:00:58.986Z,1707933658.986 [IBIT](IMPORTANT): Vehicle Roll:-0.852471 degrees
2024-02-14T18:00:58.987Z,1707933658.987 [IBIT](IMPORTANT): Vehicle Heading:62.146130 degrees
2024-02-14T18:00:59.422Z,1707933659.422 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-02-14T18:00:59.422Z,1707933659.422 [IBIT](IMPORTANT): buoyancyNeutral: 473.280853 cc
2024-02-14T18:00:59.422Z,1707933659.422 [IBIT](IMPORTANT): massDefault: -0.715147 cm
2024-02-14T18:00:59.422Z,1707933659.422 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2024-02-14T18:00:59.423Z,1707933659.423 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-02-14T18:00:59.423Z,1707933659.423 [IBIT](IMPORTANT): IBIT FAILED
2024-02-14T18:00:59.811Z,1707933659.811 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-14T17:54:33.9Z
2024-02-14T18:00:59.811Z,1707933659.811 [Default:CheckIn:Read_GPS] Stopped
2024-02-14T18:00:59.811Z,1707933659.811 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-14T18:01:00.512Z,1707933660.512 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240214T162206/Courier0028.lzma
2024-02-14T18:01:01.214Z,1707933661.214 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Courier0028.lzma.bak
2024-02-14T18:01:01.214Z,1707933661.214 [DataOverHttps](INFO): SBD MOMSN=19278316
2024-02-14T18:01:03.803Z,1707933663.803 [NAL9602](INFO): NAL9602 initialized
2024-02-14T18:01:16.895Z,1707933676.895 [DataOverHttps](INFO): Sending 797 bytes from file Logs/20240214T162206/Express0029.lzma
2024-02-14T18:01:17.895Z,1707933677.895 [DataOverHttps](INFO): Moved sent file to Logs/20240214T162206/Express0029.lzma.bak
2024-02-14T18:01:17.895Z,1707933677.895 [DataOverHttps](IMPORTANT): SBD MOMSN=19278319, MTMSN=20240214T180116
2024-02-14T18:01:19.163Z,1707933679.163 [Default:CheckIn:Read_Iridium] Stopped
2024-02-14T18:01:19.163Z,1707933679.163 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-14T18:01:19.163Z,1707933679.163 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-14T18:01:25.172Z,1707933685.172 [DataOverHttps](INFO): Received command: restart sys
2024-02-14T18:01:25.250Z,1707933685.250 [CommandExec](IMPORTANT): got command restart system
2024-02-14T18:01:27.309Z,1707933687.309 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-14T18:01:27.309Z,1707933687.309 [CommandExec](INFO): Uninitializing the command executive.
2024-02-14T18:01:27.309Z,1707933687.309 [CommandExec](INFO): Uninitializing the command scheduler.
2024-02-14T18:01:27.309Z,1707933687.309 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-02-14T18:01:27.452Z,1707933687.452 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-02-14T18:01:27.453Z,1707933687.453 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-02-14T18:01:27.453Z,1707933687.453 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-02-14T18:01:27.453Z,1707933687.453 [NavChartDb](INFO): Join timeout helper Thread ID is 5420
2024-02-14T18:01:27.837Z,1707933687.837 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-14T18:01:27.837Z,1707933687.837 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-02-14T18:01:27.852Z,1707933687.852 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-02-14T18:01:27.853Z,1707933687.853 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-02-14T18:01:27.853Z,1707933687.853 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5421
2024-02-14T18:01:28.049Z,1707933688.049 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-14T18:01:28.049Z,1707933688.049 [WetLabsBB2FL](INFO): Powering down
2024-02-14