2023-03-30T19:00:33.282Z,1680202833.282 [Supervisor](DEBUG): Initializing supervisor.
2023-03-30T19:00:33.287Z,1680202833.287 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-03-30T19:00:33.287Z,1680202833.287 [SyncHandler](INFO): Protected caller Thread ID is 1664
2023-03-30T19:00:33.288Z,1680202833.288 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-03-30T19:00:33.289Z,1680202833.289 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-03-30T19:00:33.289Z,1680202833.289 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1665
2023-03-30T19:00:33.294Z,1680202833.294 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-03-30T19:00:33.311Z,1680202833.311 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-03-30T19:00:33.312Z,1680202833.312 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-03-30T19:00:33.312Z,1680202833.312 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1666
2023-03-30T19:00:33.316Z,1680202833.316 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-03-30T19:00:33.318Z,1680202833.318 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-03-30T19:00:33.318Z,1680202833.318 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1667
2023-03-30T19:00:33.320Z,1680202833.320 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-03-30T19:00:33.321Z,1680202833.321 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-03-30T19:00:33.321Z,1680202833.321 [logger ThreadHandler](INFO): Protected caller Thread ID is 1668
2023-03-30T19:00:33.325Z,1680202833.325 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-03-30T19:00:33.325Z,1680202833.325 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-03-30T19:00:33.327Z,1680202833.327 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-03-30T19:00:33.581Z,1680202833.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-03-30T19:00:33.582Z,1680202833.582 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-03-30T19:00:33.659Z,1680202833.659 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-03-30T19:00:34.108Z,1680202834.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-03-30T19:00:34.108Z,1680202834.108 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-03-30T19:00:34.413Z,1680202834.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-03-30T19:00:34.414Z,1680202834.414 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-03-30T19:00:34.507Z,1680202834.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-03-30T19:00:34.508Z,1680202834.508 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-03-30T19:00:34.879Z,1680202834.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-03-30T19:00:34.880Z,1680202834.880 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-03-30T19:00:35.078Z,1680202835.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-03-30T19:00:35.079Z,1680202835.079 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-03-30T19:00:35.560Z,1680202835.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-03-30T19:00:35.560Z,1680202835.560 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-03-30T19:00:35.693Z,1680202835.693 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-03-30T19:00:35.693Z,1680202835.693 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-03-30T19:00:35.771Z,1680202835.771 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-03-30T19:00:36.478Z,1680202836.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-03-30T19:00:36.479Z,1680202836.479 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-03-30T19:00:36.827Z,1680202836.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-03-30T19:00:36.828Z,1680202836.828 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-03-30T19:00:37.004Z,1680202837.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-03-30T19:00:37.004Z,1680202837.004 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-03-30T19:00:37.111Z,1680202837.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-03-30T19:00:37.112Z,1680202837.112 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-03-30T19:00:37.324Z,1680202837.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-03-30T19:00:37.325Z,1680202837.325 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-03-30T19:00:37.550Z,1680202837.550 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-03-30T19:00:37.552Z,1680202837.552 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-03-30T19:00:37.553Z,1680202837.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-03-30T19:00:37.641Z,1680202837.641 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-03-30T19:00:37.724Z,1680202837.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-03-30T19:00:37.821Z,1680202837.821 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-03-30T19:00:37.902Z,1680202837.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-03-30T19:00:38.009Z,1680202838.009 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-03-30T19:00:38.108Z,1680202838.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-03-30T19:00:38.291Z,1680202838.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-03-30T19:00:38.528Z,1680202838.528 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-03-30T19:00:38.811Z,1680202838.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-03-30T19:00:38.990Z,1680202838.990 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-03-30T19:00:39.321Z,1680202839.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-03-30T19:00:39.643Z,1680202839.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-03-30T19:00:39.645Z,1680202839.645 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2023-03-30T19:00:39.645Z,1680202839.645 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-03-30T19:00:39.663Z,1680202839.663 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-03-30T19:00:39.714Z,1680202839.714 [VerticalControl](DEBUG): Construct VerticalControl.
2023-03-30T19:00:39.772Z,1680202839.772 [VerticalControl] Loaded
2023-03-30T19:00:39.772Z,1680202839.772 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-03-30T19:00:39.775Z,1680202839.775 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-03-30T19:00:39.815Z,1680202839.815 [HorizontalControl] Loaded
2023-03-30T19:00:39.816Z,1680202839.816 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-03-30T19:00:39.818Z,1680202839.818 [SpeedControl](DEBUG): Construct SpeedControl.
2023-03-30T19:00:39.821Z,1680202839.821 [SpeedControl] Loaded
2023-03-30T19:00:39.821Z,1680202839.821 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-03-30T19:00:39.824Z,1680202839.824 [LoopControl](DEBUG): Construct LoopControl.
2023-03-30T19:00:39.824Z,1680202839.824 [LoopControl] Loaded
2023-03-30T19:00:39.825Z,1680202839.825 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-03-30T19:00:39.825Z,1680202839.825 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-03-30T19:00:39.825Z,1680202839.825 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-03-30T19:00:39.835Z,1680202839.835 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-03-30T19:00:39.836Z,1680202839.836 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-03-30T19:00:39.985Z,1680202839.985 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-03-30T19:00:39.985Z,1680202839.985 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-03-30T19:00:40.137Z,1680202840.137 [BuoyancyServo] Loaded
2023-03-30T19:00:40.137Z,1680202840.137 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-03-30T19:00:40.157Z,1680202840.157 [ElevatorServo] Loaded
2023-03-30T19:00:40.157Z,1680202840.157 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-03-30T19:00:40.176Z,1680202840.176 [MassServo] Loaded
2023-03-30T19:00:40.176Z,1680202840.176 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-03-30T19:00:40.194Z,1680202840.194 [RudderServo] Loaded
2023-03-30T19:00:40.194Z,1680202840.194 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-03-30T19:00:40.211Z,1680202840.211 [ThrusterServo] Loaded
2023-03-30T19:00:40.211Z,1680202840.211 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-03-30T19:00:40.212Z,1680202840.212 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-03-30T19:00:40.212Z,1680202840.212 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-03-30T19:00:40.323Z,1680202840.323 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-03-30T19:00:40.323Z,1680202840.323 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-03-30T19:00:40.337Z,1680202840.337 [NavChart] Loaded
2023-03-30T19:00:40.337Z,1680202840.337 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-03-30T19:00:40.343Z,1680202840.343 [UniversalFixResidualReporter] Loaded
2023-03-30T19:00:40.343Z,1680202840.343 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-03-30T19:00:40.343Z,1680202840.343 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-03-30T19:00:40.344Z,1680202840.344 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-03-30T19:00:40.400Z,1680202840.400 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-03-30T19:00:40.401Z,1680202840.401 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-03-30T19:00:40.818Z,1680202840.818 [AHRS_M2] Loaded
2023-03-30T19:00:40.818Z,1680202840.818 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-03-30T19:00:41.624Z,1680202841.624 [BPC1] Loaded
2023-03-30T19:00:41.624Z,1680202841.624 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-03-30T19:00:41.696Z,1680202841.696 [DataOverHttps] Loaded
2023-03-30T19:00:41.696Z,1680202841.696 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-03-30T19:00:41.697Z,1680202841.697 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-03-30T19:00:41.698Z,1680202841.698 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1753
2023-03-30T19:00:41.719Z,1680202841.719 [Depth_Keller] Loaded
2023-03-30T19:00:41.719Z,1680202841.719 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-03-30T19:00:41.724Z,1680202841.724 [DropWeight] Loaded
2023-03-30T19:00:41.724Z,1680202841.724 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-03-30T19:00:41.743Z,1680202841.743 [MultiRay] Loaded
2023-03-30T19:00:41.743Z,1680202841.743 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-03-30T19:00:41.804Z,1680202841.804 [NAL9602] Loaded
2023-03-30T19:00:41.804Z,1680202841.804 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-03-30T19:00:41.833Z,1680202841.833 [Onboard] Loaded
2023-03-30T19:00:41.834Z,1680202841.834 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-03-30T19:00:41.835Z,1680202841.835 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2023-03-30T19:00:41.835Z,1680202841.835 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1754
2023-03-30T19:00:41.848Z,1680202841.848 [Power24vConverter] Loaded
2023-03-30T19:00:41.849Z,1680202841.849 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-03-30T19:00:41.862Z,1680202841.862 [Radio_Surface] Loaded
2023-03-30T19:00:41.862Z,1680202841.862 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-03-30T19:00:41.863Z,1680202841.863 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2023-03-30T19:00:41.864Z,1680202841.864 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1755
2023-03-30T19:00:41.865Z,1680202841.865 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-03-30T19:00:41.865Z,1680202841.865 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-03-30T19:00:41.916Z,1680202841.916 [DepthRateCalculator] Loaded
2023-03-30T19:00:41.916Z,1680202841.916 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-03-30T19:00:41.921Z,1680202841.921 [PitchRateCalculator] Loaded
2023-03-30T19:00:41.921Z,1680202841.921 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-03-30T19:00:41.930Z,1680202841.930 [SpeedCalculator] Loaded
2023-03-30T19:00:41.930Z,1680202841.930 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-03-30T19:00:41.946Z,1680202841.946 [VerticalHomogeneityIndexCalculator] Loaded
2023-03-30T19:00:41.946Z,1680202841.946 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2023-03-30T19:00:41.950Z,1680202841.950 [YawRateCalculator] Loaded
2023-03-30T19:00:41.950Z,1680202841.950 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-03-30T19:00:41.969Z,1680202841.969 [ElevatorOffsetCalculator] Loaded
2023-03-30T19:00:41.969Z,1680202841.969 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-03-30T19:00:41.985Z,1680202841.985 [ShortestDistanceToPolygonSidesCalculator] Loaded
2023-03-30T19:00:41.985Z,1680202841.985 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2023-03-30T19:00:41.986Z,1680202841.986 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-03-30T19:00:41.986Z,1680202841.986 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-03-30T19:00:42.115Z,1680202842.115 [CANONSampler] Loaded
2023-03-30T19:00:42.115Z,1680202842.115 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-03-30T19:00:42.174Z,1680202842.174 [CTD_Seabird] Loaded
2023-03-30T19:00:42.175Z,1680202842.175 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-03-30T19:00:42.176Z,1680202842.176 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2023-03-30T19:00:42.176Z,1680202842.176 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1756
2023-03-30T19:00:42.196Z,1680202842.196 [PAR_Licor] Loaded
2023-03-30T19:00:42.196Z,1680202842.196 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-03-30T19:00:42.227Z,1680202842.227 [WetLabsBB2FL] Loaded
2023-03-30T19:00:42.227Z,1680202842.227 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-03-30T19:00:42.228Z,1680202842.228 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2023-03-30T19:00:42.228Z,1680202842.228 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1757
2023-03-30T19:00:42.229Z,1680202842.229 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-03-30T19:00:42.230Z,1680202842.230 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-03-30T19:00:42.256Z,1680202842.256 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-03-30T19:00:42.256Z,1680202842.256 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-03-30T19:00:42.563Z,1680202842.563 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-03-30T19:00:42.563Z,1680202842.563 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-03-30T19:00:42.701Z,1680202842.701 [SBIT](DEBUG): Construct Startup Built In Test.
2023-03-30T19:00:42.710Z,1680202842.710 [SBIT] Loaded
2023-03-30T19:00:42.710Z,1680202842.710 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-03-30T19:00:42.714Z,1680202842.714 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-03-30T19:00:42.728Z,1680202842.728 [IBIT] Loaded
2023-03-30T19:00:42.728Z,1680202842.728 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-03-30T19:00:42.734Z,1680202842.734 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-03-30T19:00:42.836Z,1680202842.836 [CBIT] Loaded
2023-03-30T19:00:42.836Z,1680202842.836 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-03-30T19:00:42.837Z,1680202842.837 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-03-30T19:00:42.837Z,1680202842.837 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-03-30T19:00:42.936Z,1680202842.936 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-03-30T19:00:42.942Z,1680202842.942 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-03-30T19:00:42.945Z,1680202842.945 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-03-30T19:00:42.956Z,1680202842.956 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-03-30T19:00:42.957Z,1680202842.957 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B7E4E0
2023-03-30T19:00:42.958Z,1680202842.958 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1758
2023-03-30T19:00:42.962Z,1680202842.962 [Supervisor](INFO): Main Thread ID is 1271
2023-03-30T19:00:42.962Z,1680202842.962 [Supervisor](DEBUG): Running supervisor.
2023-03-30T19:00:42.963Z,1680202842.963 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1759
2023-03-30T19:00:42.963Z,1680202842.963 [CommandExec](INFO): Initializing the command executive.
2023-03-30T19:00:42.965Z,1680202842.965 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1760
2023-03-30T19:00:42.967Z,1680202842.967 [controlThread ThreadHandler](INFO): Handler Thread ID is 1761
2023-03-30T19:00:42.967Z,1680202842.967 [controlThread](DEBUG): Initializing ControlThread
2023-03-30T19:00:42.968Z,1680202842.968 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-03-30T19:00:42.970Z,1680202842.970 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-03-30T19:00:42.970Z,1680202842.970 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-03-30T19:00:42.971Z,1680202842.971 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-03-30T19:00:42.973Z,1680202842.973 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-03-30T19:00:42.973Z,1680202842.973 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-03-30T19:00:42.979Z,1680202842.979 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-03-30T19:00:42.980Z,1680202842.980 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-03-30T19:00:42.980Z,1680202842.980 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-03-30T19:00:42.980Z,1680202842.980 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2023-03-30T19:00:42.981Z,1680202842.981 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-03-30T19:00:42.982Z,1680202842.982 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-03-30T19:00:42.982Z,1680202842.982 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2023-03-30T19:00:42.986Z,1680202842.986 [SBIT](INFO): Initialize SBIT Component.
2023-03-30T19:00:42.986Z,1680202842.986 [SBIT](IMPORTANT): git: 2023-03-30
2023-03-30T19:00:42.986Z,1680202842.986 [SBIT](INFO): git hash: f81c159dfc35abf96de73a53ce302b073cdde557
2023-03-30T19:00:42.987Z,1680202842.987 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-03-30T19:00:42.988Z,1680202842.988 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-03-30T19:00:42.989Z,1680202842.989 [SBIT](INFO): Beginning SBIT in 76.000000 seconds.
2023-03-30T19:00:42.990Z,1680202842.990 [IBIT](INFO): Initialize IBIT Component.
2023-03-30T19:00:42.990Z,1680202842.990 [CBIT](DEBUG): Initialize CBIT Component.
2023-03-30T19:00:42.991Z,1680202842.991 [logger ThreadHandler](INFO): Handler Thread ID is 1762
2023-03-30T19:00:43.001Z,1680202843.001 [CBIT](DEBUG): Initialized mux pins.
2023-03-30T19:00:43.001Z,1680202843.001 [CBIT](DEBUG): Initializing the watchdog timer.
2023-03-30T19:00:43.010Z,1680202843.010 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1763
2023-03-30T19:00:43.011Z,1680202843.011 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-03-30T19:00:43.022Z,1680202843.022 [Onboard ThreadHandler](INFO): Handler Thread ID is 1764
2023-03-30T19:00:43.026Z,1680202843.026 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-03-30T19:00:43.026Z,1680202843.026 [CBIT](DEBUG): Initializing heartbeat.
2023-03-30T19:00:43.042Z,1680202843.042 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1765
2023-03-30T19:00:43.058Z,1680202843.058 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1766
2023-03-30T19:00:43.058Z,1680202843.058 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-03-30T19:00:43.063Z,1680202843.063 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1768
2023-03-30T19:00:43.066Z,1680202843.066 [WetLabsBB2FL](INFO): Powering up
2023-03-30T19:00:43.067Z,1680202843.067 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1770
2023-03-30T19:00:43.070Z,1680202843.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-03-30T19:00:43.071Z,1680202843.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-03-30T19:00:43.071Z,1680202843.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-03-30T19:00:43.071Z,1680202843.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-03-30T19:00:43.071Z,1680202843.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-03-30T19:00:43.071Z,1680202843.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-03-30T19:00:43.071Z,1680202843.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-03-30T19:00:43.072Z,1680202843.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-03-30T19:00:43.097Z,1680202843.097 [CBIT](DEBUG): Deactivating GF circuits.
2023-03-30T19:00:43.097Z,1680202843.097 [CBIT](DEBUG): Deactivating emergency mode.
2023-03-30T19:00:43.137Z,1680202843.137 [CBIT](DEBUG): Backplane powered.
2023-03-30T19:00:43.138Z,1680202843.138 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-03-30T19:00:43.151Z,1680202843.151 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-30T19:00:43.186Z,1680202843.186 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-03-30T19:00:43.187Z,1680202843.187 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-03-30T19:00:43.261Z,1680202843.261 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-03-30T19:00:43.262Z,1680202843.262 [Default:A.Wait](DEBUG): Construct Wait.
2023-03-30T19:00:43.264Z,1680202843.264 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-30T19:00:43.308Z,1680202843.308 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-03-30T19:00:43.310Z,1680202843.310 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-03-30T19:00:43.328Z,1680202843.328 [Default:E.Execute](DEBUG): Construct Execute.
2023-03-30T19:00:43.332Z,1680202843.332 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-03-30T19:00:43.356Z,1680202843.356 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-03-30T19:00:43.390Z,1680202843.390 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-03-30T19:00:43.449Z,1680202843.449 [Radio_Surface](INFO): Powering up
2023-03-30T19:00:43.454Z,1680202843.454 [Depth_Keller](ERROR): Pressure or depth reading out of range: 915.616455 decibar, 0.000000 m
2023-03-30T19:00:43.455Z,1680202843.455 [MultiRay](INFO): Powering up MultiRay Lights
2023-03-30T19:00:43.510Z,1680202843.510 [Power24vConverter](INFO): Powering up.
2023-03-30T19:00:43.511Z,1680202843.511 [CANONSampler](INFO): Powering down
2023-03-30T19:00:43.522Z,1680202843.522 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-03-30T19:00:43.587Z,1680202843.587 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-03-30T19:00:43.672Z,1680202843.672 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-03-30T19:00:43.673Z,1680202843.673 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-03-30T19:00:43.681Z,1680202843.681 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-03-30T19:00:43.682Z,1680202843.682 [MassServo](DEBUG): Initializing EZServoServo.
2023-03-30T19:00:43.690Z,1680202843.690 [MassServo](DEBUG): Initializing MassServo.
2023-03-30T19:00:43.691Z,1680202843.691 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-30T19:00:43.698Z,1680202843.698 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-30T19:00:43.698Z,1680202843.698 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-03-30T19:00:43.722Z,1680202843.722 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-03-30T19:00:43.812Z,1680202843.812 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-03-30T19:00:43.812Z,1680202843.812 [DropWeight] Hardware Fault, FailCount= 1
2023-03-30T19:00:43.812Z,1680202843.812 [DropWeight](ERROR): Hardware Fault
2023-03-30T19:00:43.880Z,1680202843.880 [CommandExec](FAULT): Scheduling is paused
2023-03-30T19:00:43.881Z,1680202843.881 [CBIT](INFO): Critical error at 20230330T190043
2023-03-30T19:00:43.881Z,1680202843.881 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-03-30T19:00:43.892Z,1680202843.892 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-03-30T19:00:43.892Z,1680202843.892 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-03-30T19:00:44.517Z,1680202844.517 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-03-30T19:00:44.517Z,1680202844.517 [RudderServo](FAULT): Rudder failed to initialize
2023-03-30T19:00:44.518Z,1680202844.518 [RudderServo] Communications Fault, FailCount= 1
2023-03-30T19:00:44.518Z,1680202844.518 [RudderServo](ERROR): Communications Fault
2023-03-30T19:00:44.616Z,1680202844.616 [CBIT](INFO): Critical error at 20230330T190043
2023-03-30T19:00:44.618Z,1680202844.618 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-03-30T19:00:44.998Z,1680202844.998 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-03-30T19:00:44.998Z,1680202844.998 [RudderServo](INFO): Powering down
2023-03-30T19:00:45.458Z,1680202845.458 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-30T19:00:45.578Z,1680202845.578 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-30T19:00:45.582Z,1680202845.582 [CBIT](INFO): Clearing failed state for component RudderServo
2023-03-30T19:00:45.583Z,1680202845.583 [RudderServo] No Fault, FailCount= 1
2023-03-30T19:00:45.677Z,1680202845.677 [WetLabsBB2FL](INFO): Powering down
2023-03-30T19:01:12.106Z,1680202872.106 [NAL9602](INFO): Powering up NAL9602
2023-03-30T19:01:23.011Z,1680202883.011 [NAL9602](INFO): NAL9602 initialized
2023-03-30T19:01:59.802Z,1680202919.802 [SBIT](IMPORTANT): Beginning Startup BIT
2023-03-30T19:01:59.806Z,1680202919.806 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-30T19:02:10.705Z,1680202930.705 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-03-30T19:02:11.114Z,1680202931.114 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-30T19:02:22.157Z,1680202942.157 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.011811
CHAN A1 (24V): 0.000124
CHAN A2 (12V): -0.002204
CHAN A3 (5V): -0.001645
CHAN B0 (3.3V): -0.000001
CHAN B1 (3.15aV): -0.000338
CHAN B2 (3.15bV): -0.000237
CHAN B3 (GND): -0.000389
OPEN: -0.000585
Full Scale: +/- 1 mA
2023-03-30T19:02:53.949Z,1680202973.949 [SBIT](IMPORTANT): SBIT PASSED
2023-03-30T19:02:53.949Z,1680202973.949 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-03-30T19:02:53.950Z,1680202973.950 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-03-30T19:02:53.950Z,1680202973.950 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;

2023-03-30T19:02:53.950Z,1680202973.950 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-03-30T19:02:53.950Z,1680202973.950 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-03-30T19:02:53.951Z,1680202973.951 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-03-30T19:02:53.951Z,1680202973.951 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.100000 celsius;

2023-03-30T19:02:53.951Z,1680202973.951 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1=36.81 degree;

2023-03-30T19:02:53.951Z,1680202973.951 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2=36.81 degree;

2023-03-30T19:02:53.951Z,1680202973.951 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3=36.78 degree;

2023-03-30T19:02:53.951Z,1680202973.951 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4=36.78 degree;

2023-03-30T19:02:53.951Z,1680202973.951 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;

2023-03-30T19:02:53.951Z,1680202973.951 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1=-121.87 degree;

2023-03-30T19:02:53.952Z,1680202973.952 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2=-121.83 degree;

2023-03-30T19:02:53.952Z,1680202973.952 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3=-121.83 degree;

2023-03-30T19:02:53.952Z,1680202973.952 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4=-121.87 degree;

2023-03-30T19:02:53.952Z,1680202973.952 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=336.456139 cubic_centimeter;

2023-03-30T19:02:53.952Z,1680202973.952 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.157167 millimeter;

2023-03-30T19:02:53.952Z,1680202973.952 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=15 meter;

2023-03-30T19:02:53.952Z,1680202973.952 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=20 meter;

2023-03-30T19:02:53.952Z,1680202973.952 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;

2023-03-30T19:02:54.342Z,1680202974.342 [MissionManager](IMPORTANT): Started mission Startup
2023-03-30T19:02:54.342Z,1680202974.342 [Startup] Running Loop=1
2023-03-30T19:02:54.342Z,1680202974.342 [Startup](DEBUG): Aggregate::initialize Startup
2023-03-30T19:02:54.342Z,1680202974.342 [Startup:A.GoToSurface] Running Loop=1
2023-03-30T19:02:54.343Z,1680202974.343 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-30T19:02:54.343Z,1680202974.343 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-30T19:02:54.344Z,1680202974.344 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-30T19:02:54.344Z,1680202974.344 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-30T19:02:54.344Z,1680202974.344 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-30T19:02:54.345Z,1680202974.345 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-30T19:02:54.346Z,1680202974.346 [Startup:StartupSatComms] Running Loop=1
2023-03-30T19:02:54.346Z,1680202974.346 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-03-30T19:02:54.346Z,1680202974.346 [Startup:StartupSatComms:A] Running Loop=1
2023-03-30T19:02:54.739Z,1680202974.739 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-03-30T19:03:25.831Z,1680203005.831 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004582
2023-03-30T19:03:27.042Z,1680203007.042 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-03-30T19:03:43.230Z,1680203023.230 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-03-30T19:03:43.230Z,1680203023.230 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:03:43.241Z,1680203023.241 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:03:43.642Z,1680203023.642 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:03:43.642Z,1680203023.642 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-03-30T19:03:54.552Z,1680203034.552 [Startup:StartupSatComms:A](INFO): Timed out from 2023-03-30T19:02:54.3Z
2023-03-30T19:03:54.552Z,1680203034.552 [Startup:StartupSatComms:A] Stopped
2023-03-30T19:03:54.552Z,1680203034.552 [Startup:StartupSatComms:B] Running Loop=1
2023-03-30T19:03:54.965Z,1680203034.965 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-30T19:03:55.106Z,1680203035.106 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20230330T184641/Courier0004.lzma
2023-03-30T19:03:56.071Z,1680203036.071 [DataOverHttps](INFO): Moved sent file to Logs/20230330T184641/Courier0004.lzma.bak
2023-03-30T19:03:56.071Z,1680203036.071 [DataOverHttps](INFO): SBD MOMSN=17827877
2023-03-30T19:04:11.565Z,1680203051.565 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20230330T185426/Courier0000.lzma
2023-03-30T19:04:12.567Z,1680203052.567 [DataOverHttps](INFO): Moved sent file to Logs/20230330T185426/Courier0000.lzma.bak
2023-03-30T19:04:12.567Z,1680203052.567 [DataOverHttps](INFO): SBD MOMSN=17827881
2023-03-30T19:04:27.986Z,1680203067.986 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20230330T185719/Courier0000.lzma
2023-03-30T19:04:28.987Z,1680203068.987 [DataOverHttps](INFO): Moved sent file to Logs/20230330T185719/Courier0000.lzma.bak
2023-03-30T19:04:28.987Z,1680203068.987 [DataOverHttps](INFO): SBD MOMSN=17827891
2023-03-30T19:04:44.629Z,1680203084.629 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20230330T185836/Courier0000.lzma
2023-03-30T19:04:45.631Z,1680203085.631 [DataOverHttps](INFO): Moved sent file to Logs/20230330T185836/Courier0000.lzma.bak
2023-03-30T19:04:45.631Z,1680203085.631 [DataOverHttps](INFO): SBD MOMSN=17827896
2023-03-30T19:04:54.796Z,1680203094.796 [Startup:StartupSatComms:B](INFO): Timed out from 2023-03-30T19:03:54.6Z
2023-03-30T19:04:54.796Z,1680203094.796 [Startup:StartupSatComms:B] Stopped
2023-03-30T19:04:54.796Z,1680203094.796 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-03-30T19:04:54.796Z,1680203094.796 [Startup:StartupSatComms] Stopped
2023-03-30T19:04:54.796Z,1680203094.796 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-03-30T19:04:54.797Z,1680203094.797 [Startup](INFO): Completed Startup
2023-03-30T19:04:54.797Z,1680203094.797 [MissionManager](INFO): Startup is completed.
2023-03-30T19:04:54.798Z,1680203094.798 [MissionManager](INFO): Uninitializing Mission Startup
2023-03-30T19:04:54.798Z,1680203094.798 [Startup] Stopped
2023-03-30T19:04:54.798Z,1680203094.798 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-03-30T19:04:54.798Z,1680203094.798 [Startup:A.GoToSurface] Stopped
2023-03-30T19:04:54.798Z,1680203094.798 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-30T19:04:55.164Z,1680203095.164 [MissionManager](IMPORTANT): Started mission Default
2023-03-30T19:04:55.165Z,1680203095.165 [Default] Running Loop=1
2023-03-30T19:04:55.165Z,1680203095.165 [Default](DEBUG): Aggregate::initialize Default
2023-03-30T19:04:55.165Z,1680203095.165 [Default:B.GoToSurface] Running Loop=1
2023-03-30T19:04:55.165Z,1680203095.165 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-30T19:04:55.169Z,1680203095.169 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-30T19:04:55.170Z,1680203095.170 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-30T19:04:55.170Z,1680203095.170 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-30T19:04:55.170Z,1680203095.170 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-30T19:04:55.171Z,1680203095.171 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-30T19:04:55.171Z,1680203095.171 [Default:A.Wait] Running Loop=1
2023-03-30T19:04:55.171Z,1680203095.171 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-03-30T19:05:01.097Z,1680203101.097 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20230330T190033/Courier0000.lzma
2023-03-30T19:05:02.099Z,1680203102.099 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0000.lzma.bak
2023-03-30T19:05:02.099Z,1680203102.099 [DataOverHttps](INFO): SBD MOMSN=17827901
2023-03-30T19:05:08.456Z,1680203108.456 [Default:A.Wait](INFO): Done Waiting.
2023-03-30T19:05:08.456Z,1680203108.456 [Default:A.Wait] Stopped
2023-03-30T19:05:08.456Z,1680203108.456 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T19:05:08.869Z,1680203108.869 [Default:CheckIn] Running Loop=1
2023-03-30T19:05:08.869Z,1680203108.869 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T19:05:08.869Z,1680203108.869 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T19:05:09.278Z,1680203109.278 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-03-30T19:06:26.423Z,1680203186.423 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-03-30T19:06:44.211Z,1680203204.211 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-03-30T19:06:44.211Z,1680203204.211 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:06:44.221Z,1680203204.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:06:44.634Z,1680203204.634 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:06:44.634Z,1680203204.634 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-03-30T19:09:45.262Z,1680203385.262 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-03-30T19:09:45.262Z,1680203385.262 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:09:45.272Z,1680203385.272 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:09:45.616Z,1680203385.616 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:09:45.616Z,1680203385.616 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-03-30T19:10:09.036Z,1680203409.036 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-30T19:05:08.9Z
2023-03-30T19:10:09.036Z,1680203409.036 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T19:10:09.036Z,1680203409.036 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T19:10:09.440Z,1680203409.440 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-30T19:10:15.965Z,1680203415.965 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230330T190033/Courier0004.lzma
2023-03-30T19:10:16.967Z,1680203416.967 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0004.lzma.bak
2023-03-30T19:10:16.967Z,1680203416.967 [DataOverHttps](INFO): SBD MOMSN=17827936
2023-03-30T19:10:32.593Z,1680203432.593 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230330T182053/Express0011.lzma
2023-03-30T19:10:33.595Z,1680203433.595 [DataOverHttps](INFO): Moved sent file to Logs/20230330T182053/Express0011.lzma.bak
2023-03-30T19:10:33.595Z,1680203433.595 [DataOverHttps](INFO): SBD MOMSN=17827938
2023-03-30T19:10:39.340Z,1680203439.340 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T19:10:39.575Z,1680203439.575 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 5.
2023-03-30T19:10:39.591Z,1680203439.591 [BPC1](INFO): Received data from all battery sticks.
2023-03-30T19:10:44.230Z,1680203444.230 [Power24vConverter](INFO): Powering down.
2023-03-30T19:10:55.085Z,1680203455.085 [DataOverHttps](INFO): Sending 372 bytes from file Logs/20230330T183624/Express0001.lzma
2023-03-30T19:10:56.087Z,1680203456.087 [DataOverHttps](INFO): Moved sent file to Logs/20230330T183624/Express0001.lzma.bak
2023-03-30T19:10:56.087Z,1680203456.087 [DataOverHttps](INFO): SBD MOMSN=17828293
2023-03-30T19:11:11.443Z,1680203471.443 [DataOverHttps](INFO): Sending 1200 bytes from file Logs/20230330T183703/Express0001.lzma
2023-03-30T19:11:12.443Z,1680203472.443 [DataOverHttps](INFO): Moved sent file to Logs/20230330T183703/Express0001.lzma.bak
2023-03-30T19:11:12.443Z,1680203472.443 [DataOverHttps](INFO): SBD MOMSN=17828301
2023-03-30T19:11:25.831Z,1680203485.831 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-30T19:11:25.831Z,1680203485.831 [NAL9602] Data Fault, FailCount= 1
2023-03-30T19:11:25.831Z,1680203485.831 [NAL9602](ERROR): Data Fault
2023-03-30T19:11:25.898Z,1680203485.898 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-30T19:11:26.235Z,1680203486.235 [NAL9602](INFO): Powering down
2023-03-30T19:11:27.080Z,1680203487.080 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-30T19:11:27.080Z,1680203487.080 [NAL9602] No Fault, FailCount= 1
2023-03-30T19:11:27.778Z,1680203487.778 [DataOverHttps](INFO): Sending 487 bytes from file Logs/20230330T183703/Express0005.lzma
2023-03-30T19:11:28.779Z,1680203488.779 [DataOverHttps](INFO): Moved sent file to Logs/20230330T183703/Express0005.lzma.bak
2023-03-30T19:11:28.779Z,1680203488.779 [DataOverHttps](INFO): SBD MOMSN=17828350
2023-03-30T19:11:44.109Z,1680203504.109 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230330T183703/Express0008.lzma
2023-03-30T19:11:45.111Z,1680203505.111 [DataOverHttps](INFO): Moved sent file to Logs/20230330T183703/Express0008.lzma.bak
2023-03-30T19:11:45.111Z,1680203505.111 [DataOverHttps](INFO): SBD MOMSN=17828373
2023-03-30T19:11:56.535Z,1680203516.535 [NAL9602](INFO): Powering up NAL9602
2023-03-30T19:12:00.338Z,1680203520.338 [DataOverHttps](INFO): Sending 1027 bytes from file Logs/20230330T184641/Express0001.lzma
2023-03-30T19:12:01.339Z,1680203521.339 [DataOverHttps](INFO): Moved sent file to Logs/20230330T184641/Express0001.lzma.bak
2023-03-30T19:12:01.339Z,1680203521.339 [DataOverHttps](INFO): SBD MOMSN=17828375
2023-03-30T19:12:07.443Z,1680203527.443 [NAL9602](INFO): NAL9602 initialized
2023-03-30T19:12:16.626Z,1680203536.626 [DataOverHttps](INFO): Sending 624 bytes from file Logs/20230330T184641/Express0005.lzma
2023-03-30T19:12:17.627Z,1680203537.627 [DataOverHttps](INFO): Moved sent file to Logs/20230330T184641/Express0005.lzma.bak
2023-03-30T19:12:17.627Z,1680203537.627 [DataOverHttps](INFO): SBD MOMSN=17828409
2023-03-30T19:12:32.983Z,1680203552.983 [DataOverHttps](INFO): Sending 1416 bytes from file Logs/20230330T185426/Express0001.lzma
2023-03-30T19:12:33.979Z,1680203553.979 [DataOverHttps](INFO): Moved sent file to Logs/20230330T185426/Express0001.lzma.bak
2023-03-30T19:12:33.979Z,1680203553.979 [DataOverHttps](INFO): SBD MOMSN=17828445
2023-03-30T19:12:46.266Z,1680203566.266 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-03-30T19:12:46.266Z,1680203566.266 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:12:46.305Z,1680203566.305 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:12:46.688Z,1680203566.688 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:12:46.688Z,1680203566.688 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-03-30T19:12:49.325Z,1680203569.325 [DataOverHttps](INFO): Sending 416 bytes from file Logs/20230330T185719/Express0001.lzma
2023-03-30T19:12:50.327Z,1680203570.327 [DataOverHttps](INFO): Moved sent file to Logs/20230330T185719/Express0001.lzma.bak
2023-03-30T19:12:50.327Z,1680203570.327 [DataOverHttps](INFO): SBD MOMSN=17828511
2023-03-30T19:13:05.718Z,1680203585.718 [DataOverHttps](INFO): Sending 395 bytes from file Logs/20230330T185836/Express0001.lzma
2023-03-30T19:13:06.719Z,1680203586.719 [DataOverHttps](INFO): Moved sent file to Logs/20230330T185836/Express0001.lzma.bak
2023-03-30T19:13:06.719Z,1680203586.719 [DataOverHttps](INFO): SBD MOMSN=17828520
2023-03-30T19:13:21.962Z,1680203601.962 [DataOverHttps](INFO): Sending 1022 bytes from file Logs/20230330T190033/Express0001.lzma
2023-03-30T19:13:22.963Z,1680203602.963 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0001.lzma.bak
2023-03-30T19:13:22.963Z,1680203602.963 [DataOverHttps](INFO): SBD MOMSN=17828531
2023-03-30T19:13:38.341Z,1680203618.341 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230330T190033/Express0005.lzma
2023-03-30T19:13:39.343Z,1680203619.343 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0005.lzma.bak
2023-03-30T19:13:39.343Z,1680203619.343 [DataOverHttps](INFO): SBD MOMSN=17828565
2023-03-30T19:13:40.420Z,1680203620.420 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T19:13:40.420Z,1680203620.420 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T19:13:40.420Z,1680203620.420 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T19:15:44.030Z,1680203744.030 [CBIT](INFO): Clearing failed state for component DropWeight
2023-03-30T19:15:44.031Z,1680203744.031 [DropWeight] No Fault, FailCount= 1
2023-03-30T19:15:47.235Z,1680203747.235 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-03-30T19:15:47.235Z,1680203747.235 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:15:47.246Z,1680203747.246 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:15:47.662Z,1680203747.662 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:15:47.662Z,1680203747.662 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-03-30T19:16:49.291Z,1680203809.291 [DataOverHttps](IMPORTANT): SBD MTMSN=20230330T191648
2023-03-30T19:16:56.457Z,1680203816.457 [DataOverHttps](INFO): Received command: burn on
2023-03-30T19:16:56.459Z,1680203816.459 [CommandExec](IMPORTANT): got command burn on
2023-03-30T19:16:56.459Z,1680203816.459 [CommandExec](IMPORTANT): Activating dropweight wire
2023-03-30T19:16:57.515Z,1680203817.515 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-03-30T19:16:57.515Z,1680203817.515 [DropWeight] Hardware Fault, FailCount= 1
2023-03-30T19:16:57.515Z,1680203817.515 [DropWeight](ERROR): Hardware Fault
2023-03-30T19:16:57.566Z,1680203817.566 [CBIT](INFO): Critical error at 20230330T191657
2023-03-30T19:16:57.568Z,1680203817.568 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-03-30T19:16:57.568Z,1680203817.568 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-03-30T19:16:57.959Z,1680203817.959 [CBIT](INFO): Critical error at 20230330T191657
2023-03-30T19:17:27.751Z,1680203847.751 [DataOverHttps](IMPORTANT): SBD MTMSN=20230330T191726
2023-03-30T19:17:34.849Z,1680203854.849 [DataOverHttps](INFO): Received command: burn off
2023-03-30T19:17:34.870Z,1680203854.870 [CommandExec](IMPORTANT): got command burn off
2023-03-30T19:17:34.870Z,1680203854.870 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-03-30T19:18:40.951Z,1680203920.951 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T19:18:40.951Z,1680203920.951 [Default:CheckIn:C.Wait] Stopped
2023-03-30T19:18:40.951Z,1680203920.951 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T19:18:40.951Z,1680203920.951 [Default:CheckIn:D] Running Loop=1
2023-03-30T19:18:41.360Z,1680203921.360 [Default:CheckIn:D] Stopped
2023-03-30T19:18:41.360Z,1680203921.360 [Default:CheckIn:E] Running Loop=1
2023-03-30T19:18:41.774Z,1680203921.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.769922 min 
2023-03-30T19:18:41.774Z,1680203921.774 [Default:CheckIn:E] Stopped
2023-03-30T19:18:41.774Z,1680203921.774 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T19:18:41.774Z,1680203921.774 [Default:CheckIn] Stopped
2023-03-30T19:18:41.774Z,1680203921.774 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T19:18:41.775Z,1680203921.775 [Default:CheckIn](INFO): Running loop #2
2023-03-30T19:18:41.775Z,1680203921.775 [Default:CheckIn] Running Loop=2
2023-03-30T19:18:41.775Z,1680203921.775 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T19:18:41.775Z,1680203921.775 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T19:18:48.227Z,1680203928.227 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-03-30T19:18:48.227Z,1680203928.227 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:18:48.238Z,1680203928.238 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:18:48.653Z,1680203928.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:18:48.654Z,1680203928.654 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-03-30T19:21:49.284Z,1680204109.284 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-03-30T19:21:49.284Z,1680204109.284 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:21:49.322Z,1680204109.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:21:49.682Z,1680204109.682 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:21:49.682Z,1680204109.682 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-03-30T19:22:09.463Z,1680204129.463 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-30T19:22:09.463Z,1680204129.463 [NAL9602] Data Fault, FailCount= 2
2023-03-30T19:22:09.463Z,1680204129.463 [NAL9602](ERROR): Data Fault
2023-03-30T19:22:09.481Z,1680204129.481 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-30T19:22:09.867Z,1680204129.867 [NAL9602](INFO): Powering down
2023-03-30T19:22:10.708Z,1680204130.708 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-30T19:22:10.708Z,1680204130.708 [NAL9602] No Fault, FailCount= 2
2023-03-30T19:22:40.163Z,1680204160.163 [NAL9602](INFO): Powering up NAL9602
2023-03-30T19:22:51.075Z,1680204171.075 [NAL9602](INFO): NAL9602 initialized
2023-03-30T19:23:41.985Z,1680204221.985 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-30T19:18:41.8Z
2023-03-30T19:23:41.985Z,1680204221.985 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T19:23:41.985Z,1680204221.985 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T19:23:48.993Z,1680204228.993 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20230330T190033/Courier0007.lzma
2023-03-30T19:23:49.995Z,1680204229.995 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0007.lzma.bak
2023-03-30T19:23:49.995Z,1680204229.995 [DataOverHttps](INFO): SBD MOMSN=17828964
2023-03-30T19:23:51.754Z,1680204231.754 [CommandExec](IMPORTANT): got command failComponent
2023-03-30T19:23:51.754Z,1680204231.754 [CommandExec](IMPORTANT): Failed components:
2023-03-30T19:23:51.754Z,1680204231.754 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-03-30T19:23:58.125Z,1680204238.125 [CommandExec](IMPORTANT): got command failComponent none DropWeight
2023-03-30T19:23:58.125Z,1680204238.125 [DropWeight] No Fault, FailCount= 1
2023-03-30T19:23:58.130Z,1680204238.130 [CommandExec](IMPORTANT): DropWeight failureMode is No Fault
2023-03-30T19:24:05.429Z,1680204245.429 [DataOverHttps](INFO): Sending 310 bytes from file Logs/20230330T190033/Express0008.lzma
2023-03-30T19:24:06.431Z,1680204246.431 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0008.lzma.bak
2023-03-30T19:24:06.432Z,1680204246.432 [DataOverHttps](INFO): SBD MOMSN=17828968
2023-03-30T19:24:21.721Z,1680204261.721 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230330T190033/Express0011.lzma
2023-03-30T19:24:22.723Z,1680204262.723 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0011.lzma.bak
2023-03-30T19:24:22.723Z,1680204262.723 [DataOverHttps](INFO): SBD MOMSN=17828979
2023-03-30T19:24:24.029Z,1680204264.029 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T19:24:24.029Z,1680204264.029 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T19:24:24.029Z,1680204264.029 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T19:24:39.458Z,1680204279.458 [CommandExec](IMPORTANT): got command failComponent
2023-03-30T19:24:39.458Z,1680204279.458 [CommandExec](IMPORTANT): Failed components:
2023-03-30T19:24:39.458Z,1680204279.458 [CommandExec](IMPORTANT): No failed Components.
2023-03-30T19:24:50.266Z,1680204290.266 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-03-30T19:24:50.266Z,1680204290.266 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:24:50.303Z,1680204290.303 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:24:50.680Z,1680204290.680 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:24:50.680Z,1680204290.680 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-03-30T19:27:51.258Z,1680204471.258 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-03-30T19:27:51.258Z,1680204471.258 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:27:51.285Z,1680204471.285 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:27:51.703Z,1680204471.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:27:51.703Z,1680204471.703 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-03-30T19:29:24.583Z,1680204564.583 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T19:29:24.583Z,1680204564.583 [Default:CheckIn:C.Wait] Stopped
2023-03-30T19:29:24.584Z,1680204564.584 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T19:29:24.584Z,1680204564.584 [Default:CheckIn:D] Running Loop=1
2023-03-30T19:29:24.987Z,1680204564.987 [Default:CheckIn:D] Stopped
2023-03-30T19:29:24.987Z,1680204564.987 [Default:CheckIn:E] Running Loop=1
2023-03-30T19:29:25.395Z,1680204565.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.497040 min 
2023-03-30T19:29:25.395Z,1680204565.395 [Default:CheckIn:E] Stopped
2023-03-30T19:29:25.395Z,1680204565.395 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T19:29:25.395Z,1680204565.395 [Default:CheckIn] Stopped
2023-03-30T19:29:25.395Z,1680204565.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T19:29:25.396Z,1680204565.396 [Default:CheckIn](INFO): Running loop #3
2023-03-30T19:29:25.396Z,1680204565.396 [Default:CheckIn] Running Loop=3
2023-03-30T19:29:25.396Z,1680204565.396 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T19:29:25.396Z,1680204565.396 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T19:30:52.279Z,1680204652.279 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-03-30T19:30:52.279Z,1680204652.279 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:30:52.289Z,1680204652.289 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:30:52.705Z,1680204652.705 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:30:52.706Z,1680204652.706 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-03-30T19:32:13.646Z,1680204733.646 [CommandExec](IMPORTANT): got command failComponent
2023-03-30T19:32:13.646Z,1680204733.646 [CommandExec](IMPORTANT): Failed components:
2023-03-30T19:32:13.646Z,1680204733.646 [CommandExec](IMPORTANT): No failed Components.
2023-03-30T19:32:52.272Z,1680204772.272 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-30T19:32:52.272Z,1680204772.272 [NAL9602] Data Fault, FailCount= 3
2023-03-30T19:32:52.272Z,1680204772.272 [NAL9602](ERROR): Data Fault
2023-03-30T19:32:52.289Z,1680204772.289 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-30T19:32:52.664Z,1680204772.664 [NAL9602](INFO): Powering down
2023-03-30T19:32:53.495Z,1680204773.495 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-30T19:32:53.495Z,1680204773.495 [NAL9602] No Fault, FailCount= 3
2023-03-30T19:33:22.964Z,1680204802.964 [NAL9602](INFO): Powering up NAL9602
2023-03-30T19:33:33.875Z,1680204813.875 [NAL9602](INFO): NAL9602 initialized
2023-03-30T19:33:53.292Z,1680204833.292 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-03-30T19:33:53.293Z,1680204833.293 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:33:53.303Z,1680204833.303 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:33:53.706Z,1680204833.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:33:53.706Z,1680204833.706 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-03-30T19:34:25.603Z,1680204865.603 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-30T19:29:25.4Z
2023-03-30T19:34:25.604Z,1680204865.604 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T19:34:25.604Z,1680204865.604 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T19:34:32.937Z,1680204872.937 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230330T190033/Courier0013.lzma
2023-03-30T19:34:33.939Z,1680204873.939 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0013.lzma.bak
2023-03-30T19:34:33.939Z,1680204873.939 [DataOverHttps](INFO): SBD MOMSN=17829022
2023-03-30T19:34:50.413Z,1680204890.413 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20230330T190033/Express0014.lzma
2023-03-30T19:34:51.415Z,1680204891.415 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0014.lzma.bak
2023-03-30T19:34:51.415Z,1680204891.415 [DataOverHttps](INFO): SBD MOMSN=17829377
2023-03-30T19:34:52.681Z,1680204892.681 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T19:34:52.689Z,1680204892.689 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T19:34:52.689Z,1680204892.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T19:36:54.315Z,1680205014.315 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-03-30T19:36:54.315Z,1680205014.315 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:36:54.334Z,1680205014.334 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:36:54.750Z,1680205014.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:36:54.750Z,1680205014.750 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-03-30T19:39:53.281Z,1680205193.281 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T19:39:53.281Z,1680205193.281 [Default:CheckIn:C.Wait] Stopped
2023-03-30T19:39:53.281Z,1680205193.281 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T19:39:53.281Z,1680205193.281 [Default:CheckIn:D] Running Loop=1
2023-03-30T19:39:53.686Z,1680205193.686 [Default:CheckIn:D] Stopped
2023-03-30T19:39:53.686Z,1680205193.686 [Default:CheckIn:E] Running Loop=1
2023-03-30T19:39:54.076Z,1680205194.076 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.975346 min 
2023-03-30T19:39:54.076Z,1680205194.076 [Default:CheckIn:E] Stopped
2023-03-30T19:39:54.076Z,1680205194.076 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T19:39:54.076Z,1680205194.076 [Default:CheckIn] Stopped
2023-03-30T19:39:54.076Z,1680205194.076 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T19:39:54.076Z,1680205194.076 [Default:CheckIn](INFO): Running loop #4
2023-03-30T19:39:54.076Z,1680205194.076 [Default:CheckIn] Running Loop=4
2023-03-30T19:39:54.076Z,1680205194.076 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T19:39:54.077Z,1680205194.077 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T19:39:55.319Z,1680205195.319 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-03-30T19:39:55.319Z,1680205195.319 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:39:55.330Z,1680205195.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:39:55.721Z,1680205195.721 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:39:55.721Z,1680205195.721 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-03-30T19:42:56.293Z,1680205376.293 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2023-03-30T19:42:56.293Z,1680205376.293 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-30T19:42:56.303Z,1680205376.303 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-30T19:42:56.720Z,1680205376.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-30T19:42:56.720Z,1680205376.720 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2023-03-30T19:43:03.961Z,1680205383.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194302.00,A,3648.17918,N,12147.28463,W,0.058,81.44,300323,,,A*41
2023-03-30T19:43:03.964Z,1680205383.964 [NAL9602](INFO): GPS fix at 20230330T194302: (36.802986, -121.788077)
2023-03-30T19:43:03.976Z,1680205383.976 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T19:43:03.976Z,1680205383.976 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T19:43:12.597Z,1680205392.597 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230330T190033/Courier0016.lzma
2023-03-30T19:43:13.599Z,1680205393.599 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0016.lzma.bak
2023-03-30T19:43:13.599Z,1680205393.599 [DataOverHttps](IMPORTANT): SBD MOMSN=17829401, MTMSN=20230330T194312
2023-03-30T19:43:21.718Z,1680205401.718 [DataOverHttps](INFO): Received command: ibit
2023-03-30T19:43:21.806Z,1680205401.806 [CommandExec](IMPORTANT): got command ibit
2023-03-30T19:43:22.186Z,1680205402.186 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-03-30T19:43:22.186Z,1680205402.186 [IBIT](IMPORTANT): Beginning control surface checks.
2023-03-30T19:43:22.210Z,1680205402.210 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-30T19:43:23.756Z,1680205403.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194322.00,A,3648.17908,N,12147.28407,W,0.039,81.44,300323,,,A*47
2023-03-30T19:43:23.759Z,1680205403.759 [NAL9602](INFO): GPS fix at 20230330T194322: (36.802985, -121.788068)
2023-03-30T19:43:25.062Z,1680205405.062 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-03-30T19:43:26.989Z,1680205406.989 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194325.00,A,3648.17913,N,12147.28398,W,0.058,81.44,300323,,,A*4C
2023-03-30T19:43:26.995Z,1680205406.995 [NAL9602](INFO): GPS fix at 20230330T194325: (36.802985, -121.788066)
2023-03-30T19:43:29.816Z,1680205409.816 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194328.00,A,3648.17911,N,12147.28392,W,0.019,81.44,300323,,,A*4C
2023-03-30T19:43:29.819Z,1680205409.819 [NAL9602](INFO): GPS fix at 20230330T194328: (36.802985, -121.788065)
2023-03-30T19:43:30.773Z,1680205410.773 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20230330T190033/Express0017.lzma
2023-03-30T19:43:31.775Z,1680205411.775 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0017.lzma.bak
2023-03-30T19:43:31.775Z,1680205411.775 [DataOverHttps](INFO): SBD MOMSN=17829406
2023-03-30T19:43:33.047Z,1680205413.047 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194331.00,A,3648.17908,N,12147.28398,W,0.097,81.44,300323,,,A*40
2023-03-30T19:43:33.049Z,1680205413.049 [NAL9602](INFO): GPS fix at 20230330T194331: (36.802985, -121.788066)
2023-03-30T19:43:33.194Z,1680205413.194 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-03-30T19:43:33.606Z,1680205413.606 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-30T19:43:35.876Z,1680205415.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194334.00,A,3648.17912,N,12147.28405,W,0.019,81.44,300323,,,A*4B
2023-03-30T19:43:35.887Z,1680205415.887 [NAL9602](INFO): GPS fix at 20230330T194334: (36.802985, -121.788067)
2023-03-30T19:43:36.506Z,1680205416.506 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-03-30T19:43:38.705Z,1680205418.705 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194337.00,A,3648.17913,N,12147.28413,W,0.019,81.44,300323,,,A*4E
2023-03-30T19:43:38.707Z,1680205418.707 [NAL9602](INFO): GPS fix at 20230330T194337: (36.802985, -121.788069)
2023-03-30T19:43:41.940Z,1680205421.940 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194340.00,A,3648.17912,N,12147.28418,W,0.078,81.44,300323,,,A*43
2023-03-30T19:43:41.954Z,1680205421.954 [NAL9602](INFO): GPS fix at 20230330T194340: (36.802985, -121.788070)
2023-03-30T19:43:44.494Z,1680205424.494 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-03-30T19:43:44.764Z,1680205424.764 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194343.00,A,3648.17910,N,12147.28419,W,0.019,81.44,300323,,,A*44
2023-03-30T19:43:44.767Z,1680205424.767 [NAL9602](INFO): GPS fix at 20230330T194343: (36.802985, -121.788070)
2023-03-30T19:43:44.903Z,1680205424.903 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-30T19:43:47.715Z,1680205427.715 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-03-30T19:43:48.016Z,1680205428.016 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194346.00,A,3648.17912,N,12147.28419,W,0.019,81.44,300323,,,A*43
2023-03-30T19:43:48.026Z,1680205428.026 [NAL9602](INFO): GPS fix at 20230330T194346: (36.802985, -121.788070)
2023-03-30T19:43:48.877Z,1680205428.877 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230330T190033/Courier0019.lzma
2023-03-30T19:43:49.879Z,1680205429.879 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0019.lzma.bak
2023-03-30T19:43:49.880Z,1680205429.880 [DataOverHttps](INFO): SBD MOMSN=17829409
2023-03-30T19:43:50.829Z,1680205430.829 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194349.00,A,3648.17914,N,12147.28417,W,0.039,81.44,300323,,,A*46
2023-03-30T19:43:50.831Z,1680205430.831 [NAL9602](INFO): GPS fix at 20230330T194349: (36.802986, -121.788070)
2023-03-30T19:43:54.066Z,1680205434.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194352.00,A,3648.17911,N,12147.28415,W,0.078,81.44,300323,,,A*4E
2023-03-30T19:43:54.068Z,1680205434.068 [NAL9602](INFO): GPS fix at 20230330T194352: (36.802985, -121.788069)
2023-03-30T19:43:55.818Z,1680205435.818 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-03-30T19:43:55.819Z,1680205435.819 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-03-30T19:43:56.895Z,1680205436.895 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194355.00,A,3648.17914,N,12147.28421,W,0.039,81.44,300323,,,A*4E
2023-03-30T19:43:56.897Z,1680205436.897 [NAL9602](INFO): GPS fix at 20230330T194355: (36.802986, -121.788070)
2023-03-30T19:44:00.120Z,1680205440.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194358.00,A,3648.17915,N,12147.28423,W,0.000,81.44,300323,,,A*4A
2023-03-30T19:44:00.131Z,1680205440.131 [NAL9602](INFO): GPS fix at 20230330T194358: (36.802986, -121.788071)
2023-03-30T19:44:02.961Z,1680205442.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194401.00,A,3648.17924,N,12147.28420,W,0.311,81.44,300323,,,A*43
2023-03-30T19:44:02.963Z,1680205442.963 [NAL9602](INFO): GPS fix at 20230330T194401: (36.802987, -121.788070)
2023-03-30T19:44:05.776Z,1680205445.776 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194404.00,A,3648.17924,N,12147.28425,W,0.039,81.44,300323,,,A*4A
2023-03-30T19:44:05.779Z,1680205445.779 [NAL9602](INFO): GPS fix at 20230330T194404: (36.802987, -121.788071)
2023-03-30T19:44:06.397Z,1680205446.397 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20230330T190033/Express0020.lzma
2023-03-30T19:44:07.399Z,1680205447.399 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0020.lzma.bak
2023-03-30T19:44:07.399Z,1680205447.399 [DataOverHttps](INFO): SBD MOMSN=17829413
2023-03-30T19:44:09.008Z,1680205449.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194407.00,A,3648.17926,N,12147.28424,W,0.039,81.44,300323,,,A*4A
2023-03-30T19:44:09.011Z,1680205449.011 [NAL9602](INFO): GPS fix at 20230330T194407: (36.802988, -121.788071)
2023-03-30T19:44:11.833Z,1680205451.833 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194410.00,A,3648.17929,N,12147.28421,W,0.058,81.44,300323,,,A*41
2023-03-30T19:44:11.839Z,1680205451.839 [NAL9602](INFO): GPS fix at 20230330T194410: (36.802988, -121.788070)
2023-03-30T19:44:15.068Z,1680205455.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194413.00,A,3648.17933,N,12147.28418,W,0.019,81.44,300323,,,A*46
2023-03-30T19:44:15.070Z,1680205455.070 [NAL9602](INFO): GPS fix at 20230330T194413: (36.802989, -121.788070)
2023-03-30T19:44:17.894Z,1680205457.894 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194416.00,A,3648.17941,N,12147.28414,W,0.058,81.44,300323,,,A*4F
2023-03-30T19:44:17.896Z,1680205457.896 [NAL9602](INFO): GPS fix at 20230330T194416: (36.802990, -121.788069)
2023-03-30T19:44:21.156Z,1680205461.156 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194419.00,A,3648.17947,N,12147.28404,W,0.039,81.44,300323,,,A*40
2023-03-30T19:44:21.159Z,1680205461.159 [NAL9602](INFO): GPS fix at 20230330T194419: (36.802991, -121.788067)
2023-03-30T19:44:24.013Z,1680205464.013 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194422.00,A,3648.17951,N,12147.28395,W,0.039,81.44,300323,,,A*40
2023-03-30T19:44:24.015Z,1680205464.015 [NAL9602](INFO): GPS fix at 20230330T194422: (36.802992, -121.788066)
2023-03-30T19:44:26.848Z,1680205466.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194425.00,A,3648.17962,N,12147.28388,W,0.058,81.44,300323,,,A*4C
2023-03-30T19:44:26.851Z,1680205466.851 [NAL9602](INFO): GPS fix at 20230330T194425: (36.802994, -121.788065)
2023-03-30T19:44:30.077Z,1680205470.077 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194428.00,A,3648.17979,N,12147.28381,W,0.019,81.44,300323,,,A*47
2023-03-30T19:44:30.079Z,1680205470.079 [NAL9602](INFO): GPS fix at 20230330T194428: (36.802996, -121.788064)
2023-03-30T19:44:32.904Z,1680205472.904 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194431.00,A,3648.17972,N,12147.28371,W,0.058,81.44,300323,,,A*4E
2023-03-30T19:44:32.907Z,1680205472.907 [NAL9602](INFO): GPS fix at 20230330T194431: (36.802995, -121.788062)
2023-03-30T19:44:35.747Z,1680205475.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194434.00,A,3648.17977,N,12147.28361,W,0.175,81.44,300323,,,A*41
2023-03-30T19:44:35.749Z,1680205475.749 [NAL9602](INFO): GPS fix at 20230330T194434: (36.802996, -121.788060)
2023-03-30T19:44:38.997Z,1680205478.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194437.00,A,3648.17980,N,12147.28356,W,0.039,81.44,300323,,,A*47
2023-03-30T19:44:39.003Z,1680205479.003 [NAL9602](INFO): GPS fix at 20230330T194437: (36.802997, -121.788059)
2023-03-30T19:44:41.793Z,1680205481.793 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194440.00,A,3648.17984,N,12147.28353,W,0.058,81.44,300323,,,A*41
2023-03-30T19:44:41.795Z,1680205481.795 [NAL9602](INFO): GPS fix at 20230330T194440: (36.802997, -121.788059)
2023-03-30T19:44:45.036Z,1680205485.036 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194443.00,A,3648.17990,N,12147.28354,W,0.058,81.44,300323,,,A*40
2023-03-30T19:44:45.039Z,1680205485.039 [NAL9602](INFO): GPS fix at 20230330T194443: (36.802998, -121.788059)
2023-03-30T19:44:47.852Z,1680205487.852 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194446.00,A,3648.17996,N,12147.28354,W,0.039,81.44,300323,,,A*44
2023-03-30T19:44:47.854Z,1680205487.854 [NAL9602](INFO): GPS fix at 20230330T194446: (36.802999, -121.788059)
2023-03-30T19:44:51.130Z,1680205491.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194449.00,A,3648.17997,N,12147.28358,W,0.097,81.44,300323,,,A*42
2023-03-30T19:44:51.133Z,1680205491.133 [NAL9602](INFO): GPS fix at 20230330T194449: (36.802999, -121.788060)
2023-03-30T19:44:53.964Z,1680205493.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194452.00,A,3648.18011,N,12147.28365,W,0.292,81.44,300323,,,A*49
2023-03-30T19:44:53.967Z,1680205493.967 [NAL9602](INFO): GPS fix at 20230330T194452: (36.803002, -121.788061)
2023-03-30T19:44:56.792Z,1680205496.792 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194455.00,A,3648.18022,N,12147.28371,W,0.078,81.44,300323,,,A*4D
2023-03-30T19:44:56.794Z,1680205496.794 [NAL9602](INFO): GPS fix at 20230330T194455: (36.803004, -121.788062)
2023-03-30T19:45:00.040Z,1680205500.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194458.00,A,3648.18030,N,12147.28382,W,0.175,81.44,300323,,,A*43
2023-03-30T19:45:00.042Z,1680205500.042 [NAL9602](INFO): GPS fix at 20230330T194458: (36.803005, -121.788064)
2023-03-30T19:45:02.869Z,1680205502.869 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194501.00,A,3648.18020,N,12147.28390,W,0.136,81.44,300323,,,A*4B
2023-03-30T19:45:02.871Z,1680205502.871 [NAL9602](INFO): GPS fix at 20230330T194501: (36.803003, -121.788065)
2023-03-30T19:45:06.100Z,1680205506.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194504.00,A,3648.18040,N,12147.28397,W,0.039,81.44,300323,,,A*41
2023-03-30T19:45:06.103Z,1680205506.103 [NAL9602](INFO): GPS fix at 20230330T194504: (36.803007, -121.788066)
2023-03-30T19:45:08.928Z,1680205508.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194507.00,A,3648.18034,N,12147.28409,W,0.272,81.44,300323,,,A*4C
2023-03-30T19:45:08.931Z,1680205508.931 [NAL9602](INFO): GPS fix at 20230330T194507: (36.803006, -121.788068)
2023-03-30T19:45:11.757Z,1680205511.757 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194510.00,A,3648.18034,N,12147.28424,W,0.156,81.44,300323,,,A*40
2023-03-30T19:45:11.759Z,1680205511.759 [NAL9602](INFO): GPS fix at 20230330T194510: (36.803006, -121.788071)
2023-03-30T19:45:12.992Z,1680205512.992 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2023-03-30T19:45:13.387Z,1680205513.387 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.580000
2023-03-30T19:45:13.388Z,1680205513.388 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-03-30T19:45:13.388Z,1680205513.388 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-03-30T19:45:13.805Z,1680205513.805 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-03-30T19:45:13.805Z,1680205513.805 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-03-30T19:45:13.806Z,1680205513.806 [IBIT](IMPORTANT): Pressure:9.900266 PSI
2023-03-30T19:45:13.806Z,1680205513.806 [IBIT](IMPORTANT): Humidity:1.092663 %
2023-03-30T19:45:14.212Z,1680205514.212 [IBIT](IMPORTANT): Vehicle Pitch:-0.004489 degrees
2023-03-30T19:45:14.212Z,1680205514.212 [IBIT](IMPORTANT): Vehicle  Roll:-4.629527 degrees
2023-03-30T19:45:14.213Z,1680205514.213 [IBIT](IMPORTANT): Vehicle  Heading:192.413055 degrees
2023-03-30T19:45:14.639Z,1680205514.639 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-03-30T19:45:14.639Z,1680205514.639 [IBIT](IMPORTANT): buoyancyNeutral: 336.456146 cc
2023-03-30T19:45:14.639Z,1680205514.639 [IBIT](IMPORTANT): massDefault: -1.415717 cm
2023-03-30T19:45:14.639Z,1680205514.639 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-03-30T19:45:14.640Z,1680205514.640 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-03-30T19:45:14.640Z,1680205514.640 [IBIT](IMPORTANT): IBIT FAILED
2023-03-30T19:45:14.994Z,1680205514.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194513.00,A,3648.18047,N,12147.28430,W,0.428,81.44,300323,,,A*4E
2023-03-30T19:45:14.996Z,1680205514.996 [NAL9602](INFO): GPS fix at 20230330T194513: (36.803008, -121.788072)
2023-03-30T19:45:15.016Z,1680205515.016 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T19:45:15.016Z,1680205515.016 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T19:45:15.016Z,1680205515.016 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T19:45:47.307Z,1680205547.307 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-30T19:50:15.590Z,1680205815.590 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T19:50:15.590Z,1680205815.590 [Default:CheckIn:C.Wait] Stopped
2023-03-30T19:50:15.590Z,1680205815.590 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T19:50:15.590Z,1680205815.590 [Default:CheckIn:D] Running Loop=1
2023-03-30T19:50:15.987Z,1680205815.987 [Default:CheckIn:D] Stopped
2023-03-30T19:50:15.987Z,1680205815.987 [Default:CheckIn:E] Running Loop=1
2023-03-30T19:50:16.384Z,1680205816.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.347042 min 
2023-03-30T19:50:16.384Z,1680205816.384 [Default:CheckIn:E] Stopped
2023-03-30T19:50:16.384Z,1680205816.384 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T19:50:16.385Z,1680205816.385 [Default:CheckIn] Stopped
2023-03-30T19:50:16.385Z,1680205816.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T19:50:16.385Z,1680205816.385 [Default:CheckIn](INFO): Running loop #5
2023-03-30T19:50:16.385Z,1680205816.385 [Default:CheckIn] Running Loop=5
2023-03-30T19:50:16.385Z,1680205816.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T19:50:16.385Z,1680205816.385 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T19:50:18.396Z,1680205818.396 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195017.00,A,3648.17977,N,12147.28448,W,0.136,81.44,300323,,,A*4E
2023-03-30T19:50:18.399Z,1680205818.399 [NAL9602](INFO): GPS fix at 20230330T195017: (36.802996, -121.788075)
2023-03-30T19:50:18.419Z,1680205818.419 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T19:50:18.419Z,1680205818.419 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T19:50:28.701Z,1680205828.701 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230330T190033/Courier0022.lzma
2023-03-30T19:50:29.703Z,1680205829.703 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0022.lzma.bak
2023-03-30T19:50:29.703Z,1680205829.703 [DataOverHttps](INFO): SBD MOMSN=17829807
2023-03-30T19:50:32.591Z,1680205832.591 [NAL9602](INFO): SBD MO Status=0, MOMSN=39558, MT Status=0, MTMSN=0
2023-03-30T19:50:32.591Z,1680205832.591 [NAL9602](INFO): No messages in MT queue
2023-03-30T19:50:45.354Z,1680205845.354 [DataOverHttps](INFO): Sending 615 bytes from file Logs/20230330T190033/Express0023.lzma
2023-03-30T19:50:46.355Z,1680205846.355 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0023.lzma.bak
2023-03-30T19:50:46.355Z,1680205846.355 [DataOverHttps](INFO): SBD MOMSN=17829810
2023-03-30T19:50:47.578Z,1680205847.578 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T19:50:47.578Z,1680205847.578 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T19:50:47.578Z,1680205847.578 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T19:51:03.362Z,1680205863.362 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-30T19:52:28.507Z,1680205948.507 [DataOverHttps](IMPORTANT): SBD MTMSN=20230330T195227
2023-03-30T19:52:35.941Z,1680205955.941 [DataOverHttps](INFO): Received command: ibit
2023-03-30T19:52:36.006Z,1680205956.006 [CommandExec](IMPORTANT): got command ibit
2023-03-30T19:52:36.353Z,1680205956.353 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-03-30T19:52:36.353Z,1680205956.353 [IBIT](IMPORTANT): Beginning control surface checks.
2023-03-30T19:52:36.356Z,1680205956.356 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-30T19:52:37.958Z,1680205957.958 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195237.00,A,3648.17733,N,12147.28603,W,0.039,81.44,300323,,,A*43
2023-03-30T19:52:37.960Z,1680205957.960 [NAL9602](INFO): GPS fix at 20230330T195237: (36.802956, -121.788100)
2023-03-30T19:52:39.274Z,1680205959.274 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-03-30T19:52:47.296Z,1680205967.296 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002567
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002077
CHAN A3 (5V): -0.001481
CHAN B0 (3.3V): -0.000304
CHAN B1 (3.15aV): -0.001020
CHAN B2 (3.15bV): -0.000684
CHAN B3 (GND): 0.000003
OPEN: -0.000536
Full Scale: +/- 1 mA
2023-03-30T19:53:00.576Z,1680205980.576 [NAL9602](INFO): SBD MO Status=0, MOMSN=39559, MT Status=0, MTMSN=0
2023-03-30T19:53:00.576Z,1680205980.576 [NAL9602](INFO): No messages in MT queue
2023-03-30T19:53:01.796Z,1680205981.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195301.00,A,3648.17736,N,12147.28609,W,0.058,81.44,300323,,,A*4F
2023-03-30T19:53:01.799Z,1680205981.799 [NAL9602](INFO): GPS fix at 20230330T195301: (36.802956, -121.788101)
2023-03-30T19:53:05.037Z,1680205985.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195304.00,A,3648.17735,N,12147.28611,W,0.156,81.44,300323,,,A*4F
2023-03-30T19:53:05.039Z,1680205985.039 [NAL9602](INFO): GPS fix at 20230330T195304: (36.802956, -121.788102)
2023-03-30T19:53:07.852Z,1680205987.852 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195307.00,A,3648.17727,N,12147.28620,W,0.039,81.44,300323,,,A*45
2023-03-30T19:53:07.855Z,1680205987.855 [NAL9602](INFO): GPS fix at 20230330T195307: (36.802954, -121.788103)
2023-03-30T19:53:11.080Z,1680205991.080 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195310.00,A,3648.17723,N,12147.28628,W,0.019,81.44,300323,,,A*4D
2023-03-30T19:53:11.083Z,1680205991.083 [NAL9602](INFO): GPS fix at 20230330T195310: (36.802954, -121.788105)
2023-03-30T19:53:13.912Z,1680205993.912 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195313.00,A,3648.17723,N,12147.28634,W,0.078,81.44,300323,,,A*44
2023-03-30T19:53:13.914Z,1680205993.914 [NAL9602](INFO): GPS fix at 20230330T195313: (36.802954, -121.788106)
2023-03-30T19:53:16.740Z,1680205996.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195316.00,A,3648.17726,N,12147.28637,W,0.039,81.44,300323,,,A*42
2023-03-30T19:53:16.751Z,1680205996.751 [NAL9602](INFO): GPS fix at 20230330T195316: (36.802954, -121.788106)
2023-03-30T19:53:19.972Z,1680205999.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195319.00,A,3648.17734,N,12147.28641,W,0.039,81.44,300323,,,A*4F
2023-03-30T19:53:19.983Z,1680205999.983 [NAL9602](INFO): GPS fix at 20230330T195319: (36.802956, -121.788107)
2023-03-30T19:53:22.446Z,1680206002.446 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802956 Longitude: -121.788109
2023-03-30T19:53:22.806Z,1680206002.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195322.00,A,3648.17741,N,12147.28652,W,0.019,81.44,300323,,,A*45
2023-03-30T19:53:22.808Z,1680206002.808 [NAL9602](INFO): GPS fix at 20230330T195322: (36.802957, -121.788109)
2023-03-30T19:53:22.825Z,1680206002.825 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.580000
2023-03-30T19:53:22.826Z,1680206002.826 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-03-30T19:53:22.826Z,1680206002.826 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-03-30T19:53:23.244Z,1680206003.244 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-03-30T19:53:23.244Z,1680206003.244 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-03-30T19:53:23.245Z,1680206003.245 [IBIT](IMPORTANT): Pressure:9.873232 PSI
2023-03-30T19:53:23.245Z,1680206003.245 [IBIT](IMPORTANT): Humidity:1.092663 %
2023-03-30T19:53:23.624Z,1680206003.624 [IBIT](IMPORTANT): Vehicle Pitch:0.033425 degrees
2023-03-30T19:53:23.624Z,1680206003.624 [IBIT](IMPORTANT): Vehicle  Roll:-4.633273 degrees
2023-03-30T19:53:23.625Z,1680206003.625 [IBIT](IMPORTANT): Vehicle  Heading:192.407410 degrees
2023-03-30T19:53:24.072Z,1680206004.072 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-03-30T19:53:24.073Z,1680206004.073 [IBIT](IMPORTANT): buoyancyNeutral: 336.456146 cc
2023-03-30T19:53:24.073Z,1680206004.073 [IBIT](IMPORTANT): massDefault: -1.415717 cm
2023-03-30T19:53:24.073Z,1680206004.073 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-03-30T19:53:24.073Z,1680206004.073 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-03-30T19:53:24.074Z,1680206004.074 [IBIT](IMPORTANT): IBIT PASSED
2023-03-30T19:53:55.524Z,1680206035.524 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-30T19:55:48.270Z,1680206148.270 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T19:55:48.270Z,1680206148.270 [Default:CheckIn:C.Wait] Stopped
2023-03-30T19:55:48.270Z,1680206148.270 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T19:55:48.270Z,1680206148.270 [Default:CheckIn:D] Running Loop=1
2023-03-30T19:55:48.683Z,1680206148.683 [Default:CheckIn:D] Stopped
2023-03-30T19:55:48.683Z,1680206148.683 [Default:CheckIn:E] Running Loop=1
2023-03-30T19:55:49.094Z,1680206149.094 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.891964 min 
2023-03-30T19:55:49.094Z,1680206149.094 [Default:CheckIn:E] Stopped
2023-03-30T19:55:49.095Z,1680206149.095 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T19:55:49.095Z,1680206149.095 [Default:CheckIn] Stopped
2023-03-30T19:55:49.095Z,1680206149.095 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T19:55:49.095Z,1680206149.095 [Default:CheckIn](INFO): Running loop #6
2023-03-30T19:55:49.095Z,1680206149.095 [Default:CheckIn] Running Loop=6
2023-03-30T19:55:49.095Z,1680206149.095 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T19:55:49.095Z,1680206149.095 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T19:55:51.093Z,1680206151.093 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195550.00,A,3648.17912,N,12147.28462,W,0.019,81.44,300323,,,A*4F
2023-03-30T19:55:51.095Z,1680206151.095 [NAL9602](INFO): GPS fix at 20230330T195550: (36.802985, -121.788077)
2023-03-30T19:55:51.106Z,1680206151.106 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T19:55:51.106Z,1680206151.106 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T19:55:59.037Z,1680206159.037 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230330T190033/Courier0025.lzma
2023-03-30T19:56:00.039Z,1680206160.039 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0025.lzma.bak
2023-03-30T19:56:00.039Z,1680206160.039 [DataOverHttps](INFO): SBD MOMSN=17829844
2023-03-30T19:56:15.838Z,1680206175.838 [DataOverHttps](INFO): Sending 788 bytes from file Logs/20230330T190033/Express0026.lzma
2023-03-30T19:56:16.839Z,1680206176.839 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0026.lzma.bak
2023-03-30T19:56:16.840Z,1680206176.840 [DataOverHttps](INFO): SBD MOMSN=17829847
2023-03-30T19:56:35.577Z,1680206195.577 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230330T190033/Express0029.lzma
2023-03-30T19:56:36.579Z,1680206196.579 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0029.lzma.bak
2023-03-30T19:56:36.579Z,1680206196.579 [DataOverHttps](INFO): SBD MOMSN=17829874
2023-03-30T19:56:38.026Z,1680206198.026 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T19:56:38.026Z,1680206198.026 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T19:56:38.026Z,1680206198.026 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T19:56:44.878Z,1680206204.878 [NAL9602](INFO): SBD MO Status=0, MOMSN=39560, MT Status=0, MTMSN=0
2023-03-30T19:56:44.879Z,1680206204.879 [NAL9602](INFO): No messages in MT queue
2023-03-30T19:57:15.583Z,1680206235.583 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-30T20:01:38.641Z,1680206498.641 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T20:01:38.641Z,1680206498.641 [Default:CheckIn:C.Wait] Stopped
2023-03-30T20:01:38.642Z,1680206498.642 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T20:01:38.642Z,1680206498.642 [Default:CheckIn:D] Running Loop=1
2023-03-30T20:01:39.042Z,1680206499.042 [Default:CheckIn:D] Stopped
2023-03-30T20:01:39.042Z,1680206499.042 [Default:CheckIn:E] Running Loop=1
2023-03-30T20:01:39.447Z,1680206499.447 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.731283 min 
2023-03-30T20:01:39.447Z,1680206499.447 [Default:CheckIn:E] Stopped
2023-03-30T20:01:39.447Z,1680206499.447 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T20:01:39.447Z,1680206499.447 [Default:CheckIn] Stopped
2023-03-30T20:01:39.447Z,1680206499.447 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T20:01:39.447Z,1680206499.447 [Default:CheckIn](INFO): Running loop #7
2023-03-30T20:01:39.448Z,1680206499.448 [Default:CheckIn] Running Loop=7
2023-03-30T20:01:39.448Z,1680206499.448 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T20:01:39.448Z,1680206499.448 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T20:01:41.456Z,1680206501.456 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200140.00,A,3648.17887,N,12147.28542,W,0.117,81.44,300323,,,D*41
2023-03-30T20:01:41.459Z,1680206501.459 [NAL9602](INFO): GPS fix at 20230330T200140: (36.802981, -121.788090)
2023-03-30T20:01:41.492Z,1680206501.492 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T20:01:41.492Z,1680206501.492 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T20:01:48.897Z,1680206508.897 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230330T190033/Courier0031.lzma
2023-03-30T20:01:49.899Z,1680206509.899 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0031.lzma.bak
2023-03-30T20:01:49.899Z,1680206509.899 [DataOverHttps](INFO): SBD MOMSN=17830260
2023-03-30T20:01:58.425Z,1680206518.425 [NAL9602](INFO): SBD MO Status=0, MOMSN=39561, MT Status=0, MTMSN=0
2023-03-30T20:01:58.425Z,1680206518.425 [NAL9602](INFO): No messages in MT queue
2023-03-30T20:02:05.681Z,1680206525.681 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230330T190033/Express0032.lzma
2023-03-30T20:02:06.683Z,1680206526.683 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0032.lzma.bak
2023-03-30T20:02:06.683Z,1680206526.683 [DataOverHttps](INFO): SBD MOMSN=17830263
2023-03-30T20:02:07.752Z,1680206527.752 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T20:02:07.752Z,1680206527.752 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T20:02:07.752Z,1680206527.752 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T20:02:29.131Z,1680206549.131 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-30T20:07:08.358Z,1680206828.358 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T20:07:08.358Z,1680206828.358 [Default:CheckIn:C.Wait] Stopped
2023-03-30T20:07:08.358Z,1680206828.358 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T20:07:08.358Z,1680206828.358 [Default:CheckIn:D] Running Loop=1
2023-03-30T20:07:08.746Z,1680206828.746 [Default:CheckIn:D] Stopped
2023-03-30T20:07:08.746Z,1680206828.746 [Default:CheckIn:E] Running Loop=1
2023-03-30T20:07:09.145Z,1680206829.145 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.226347 min 
2023-03-30T20:07:09.145Z,1680206829.145 [Default:CheckIn:E] Stopped
2023-03-30T20:07:09.146Z,1680206829.146 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T20:07:09.146Z,1680206829.146 [Default:CheckIn] Stopped
2023-03-30T20:07:09.146Z,1680206829.146 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T20:07:09.146Z,1680206829.146 [Default:CheckIn](INFO): Running loop #8
2023-03-30T20:07:09.146Z,1680206829.146 [Default:CheckIn] Running Loop=8
2023-03-30T20:07:09.146Z,1680206829.146 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T20:07:09.146Z,1680206829.146 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T20:07:11.164Z,1680206831.164 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200710.00,A,3648.17737,N,12147.28452,W,0.039,81.44,300323,,,D*4B
2023-03-30T20:07:11.167Z,1680206831.167 [NAL9602](INFO): GPS fix at 20230330T200710: (36.802956, -121.788075)
2023-03-30T20:07:11.179Z,1680206831.179 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T20:07:11.179Z,1680206831.179 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T20:07:11.978Z,1680206831.978 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T20:07:11.978Z,1680206831.978 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T20:07:11.978Z,1680206831.978 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T20:07:24.089Z,1680206844.089 [NAL9602](INFO): SBD MO Status=0, MOMSN=39562, MT Status=0, MTMSN=0
2023-03-30T20:07:24.089Z,1680206844.089 [NAL9602](INFO): No messages in MT queue
2023-03-30T20:07:54.791Z,1680206874.791 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-30T20:10:24.607Z,1680207024.607 [DataOverHttps](IMPORTANT): SBD MTMSN=20230330T201023
2023-03-30T20:10:32.501Z,1680207032.501 [DataOverHttps](INFO): Received command: sched asap "stop"
2023-03-30T20:10:32.543Z,1680207032.543 [CommandExec](IMPORTANT): got command schedule asap "stop"
2023-03-30T20:10:32.543Z,1680207032.543 [CommandExec](FAULT): Incomplete syntax. Try: help schedule
2023-03-30T20:12:12.662Z,1680207132.662 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T20:12:12.663Z,1680207132.663 [Default:CheckIn:C.Wait] Stopped
2023-03-30T20:12:12.663Z,1680207132.663 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T20:12:12.663Z,1680207132.663 [Default:CheckIn:D] Running Loop=1
2023-03-30T20:12:13.059Z,1680207133.059 [Default:CheckIn:D] Stopped
2023-03-30T20:12:13.059Z,1680207133.059 [Default:CheckIn:E] Running Loop=1
2023-03-30T20:12:13.458Z,1680207133.458 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.298242 min 
2023-03-30T20:12:13.458Z,1680207133.458 [Default:CheckIn:E] Stopped
2023-03-30T20:12:13.458Z,1680207133.458 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T20:12:13.458Z,1680207133.458 [Default:CheckIn] Stopped
2023-03-30T20:12:13.458Z,1680207133.458 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T20:12:13.458Z,1680207133.458 [Default:CheckIn](INFO): Running loop #9
2023-03-30T20:12:13.459Z,1680207133.459 [Default:CheckIn] Running Loop=9
2023-03-30T20:12:13.459Z,1680207133.459 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T20:12:13.459Z,1680207133.459 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T20:12:15.485Z,1680207135.485 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201214.00,A,3648.17647,N,12147.28455,W,0.019,81.44,300323,,,D*48
2023-03-30T20:12:15.487Z,1680207135.487 [NAL9602](INFO): GPS fix at 20230330T201214: (36.802941, -121.788076)
2023-03-30T20:12:15.498Z,1680207135.498 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T20:12:15.498Z,1680207135.498 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T20:12:25.756Z,1680207145.756 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230330T190033/Courier0034.lzma
2023-03-30T20:12:26.755Z,1680207146.755 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0034.lzma.bak
2023-03-30T20:12:26.755Z,1680207146.755 [DataOverHttps](INFO): SBD MOMSN=17830654
2023-03-30T20:12:34.505Z,1680207154.505 [NAL9602](INFO): SBD MO Status=0, MOMSN=39563, MT Status=0, MTMSN=0
2023-03-30T20:12:34.505Z,1680207154.505 [NAL9602](INFO): No messages in MT queue
2023-03-30T20:12:42.600Z,1680207162.600 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230330T190033/Courier0037.lzma
2023-03-30T20:12:43.599Z,1680207163.599 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0037.lzma.bak
2023-03-30T20:12:43.599Z,1680207163.599 [DataOverHttps](INFO): SBD MOMSN=17830657
2023-03-30T20:13:03.133Z,1680207183.133 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230330T190033/Express0035.lzma
2023-03-30T20:13:04.135Z,1680207184.135 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0035.lzma.bak
2023-03-30T20:13:04.135Z,1680207184.135 [DataOverHttps](INFO): SBD MOMSN=17830660
2023-03-30T20:13:05.258Z,1680207185.258 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-30T20:13:20.121Z,1680207200.121 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20230330T190033/Express0038.lzma
2023-03-30T20:13:21.123Z,1680207201.123 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0038.lzma.bak
2023-03-30T20:13:21.123Z,1680207201.123 [DataOverHttps](INFO): SBD MOMSN=17830663
2023-03-30T20:13:22.317Z,1680207202.317 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T20:13:22.317Z,1680207202.317 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T20:13:22.317Z,1680207202.317 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T20:15:39.983Z,1680207339.983 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T20:15:54.932Z,1680207354.932 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T20:16:10.283Z,1680207370.283 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T20:16:25.232Z,1680207385.232 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T20:16:40.180Z,1680207400.180 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T20:16:55.531Z,1680207415.531 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T20:17:10.480Z,1680207430.480 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T20:17:25.427Z,1680207445.427 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T20:17:40.780Z,1680207460.780 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-30T20:18:00.179Z,1680207480.179 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 5.
2023-03-30T20:18:00.182Z,1680207480.182 [BPC1](INFO): Received data from all battery sticks.
2023-03-30T20:18:22.817Z,1680207502.817 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T20:18:22.818Z,1680207502.818 [Default:CheckIn:C.Wait] Stopped
2023-03-30T20:18:22.818Z,1680207502.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T20:18:22.818Z,1680207502.818 [Default:CheckIn:D] Running Loop=1
2023-03-30T20:18:23.229Z,1680207503.229 [Default:CheckIn:D] Stopped
2023-03-30T20:18:23.229Z,1680207503.229 [Default:CheckIn:E] Running Loop=1
2023-03-30T20:18:23.642Z,1680207503.642 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.467733 min 
2023-03-30T20:18:23.657Z,1680207503.657 [Default:CheckIn:E] Stopped
2023-03-30T20:18:23.658Z,1680207503.658 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T20:18:23.658Z,1680207503.658 [Default:CheckIn] Stopped
2023-03-30T20:18:23.658Z,1680207503.658 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T20:18:23.658Z,1680207503.658 [Default:CheckIn](INFO): Running loop #10
2023-03-30T20:18:23.658Z,1680207503.658 [Default:CheckIn] Running Loop=10
2023-03-30T20:18:23.658Z,1680207503.658 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T20:18:23.658Z,1680207503.658 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T20:18:25.635Z,1680207505.635 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201824.00,A,3648.17673,N,12147.28470,W,0.058,0.00,300323,,,D*7D
2023-03-30T20:18:25.650Z,1680207505.650 [NAL9602](INFO): GPS fix at 20230330T201824: (36.802945, -121.788078)
2023-03-30T20:18:25.668Z,1680207505.668 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T20:18:25.668Z,1680207505.668 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T20:18:33.321Z,1680207513.321 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20230330T190033/Courier0043.lzma
2023-03-30T20:18:34.325Z,1680207514.325 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0043.lzma.bak
2023-03-30T20:18:34.325Z,1680207514.325 [DataOverHttps](INFO): SBD MOMSN=17830680
2023-03-30T20:18:49.914Z,1680207529.914 [NAL9602](INFO): SBD MO Status=0, MOMSN=39564, MT Status=0, MTMSN=0
2023-03-30T20:18:49.914Z,1680207529.914 [NAL9602](INFO): No messages in MT queue
2023-03-30T20:18:52.417Z,1680207532.417 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230330T190033/Express0044.lzma
2023-03-30T20:18:53.419Z,1680207533.419 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0044.lzma.bak
2023-03-30T20:18:53.420Z,1680207533.420 [DataOverHttps](INFO): SBD MOMSN=17830687
2023-03-30T20:18:54.794Z,1680207534.794 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T20:18:54.794Z,1680207534.794 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T20:18:54.795Z,1680207534.795 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T20:19:20.612Z,1680207560.612 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-30T20:23:55.343Z,1680207835.343 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-30T20:23:55.343Z,1680207835.343 [Default:CheckIn:C.Wait] Stopped
2023-03-30T20:23:55.343Z,1680207835.343 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T20:23:55.343Z,1680207835.343 [Default:CheckIn:D] Running Loop=1
2023-03-30T20:23:55.754Z,1680207835.754 [Default:CheckIn:D] Stopped
2023-03-30T20:23:55.754Z,1680207835.754 [Default:CheckIn:E] Running Loop=1
2023-03-30T20:23:56.148Z,1680207836.148 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.009823 min 
2023-03-30T20:23:56.148Z,1680207836.148 [Default:CheckIn:E] Stopped
2023-03-30T20:23:56.148Z,1680207836.148 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-30T20:23:56.148Z,1680207836.148 [Default:CheckIn] Stopped
2023-03-30T20:23:56.148Z,1680207836.148 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T20:23:56.149Z,1680207836.149 [Default:CheckIn](INFO): Running loop #11
2023-03-30T20:23:56.149Z,1680207836.149 [Default:CheckIn] Running Loop=11
2023-03-30T20:23:56.149Z,1680207836.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-30T20:23:56.149Z,1680207836.149 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-30T20:23:58.165Z,1680207838.165 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202357.00,A,3648.17725,N,12147.28438,W,0.000,0.00,300323,,,D*72
2023-03-30T20:23:58.167Z,1680207838.167 [NAL9602](INFO): GPS fix at 20230330T202357: (36.802954, -121.788073)
2023-03-30T20:23:58.179Z,1680207838.179 [Default:CheckIn:Read_GPS] Stopped
2023-03-30T20:23:58.179Z,1680207838.179 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-30T20:24:05.901Z,1680207845.901 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230330T190033/Courier0046.lzma
2023-03-30T20:24:06.903Z,1680207846.903 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Courier0046.lzma.bak
2023-03-30T20:24:06.903Z,1680207846.903 [DataOverHttps](INFO): SBD MOMSN=17831069
2023-03-30T20:24:17.956Z,1680207857.956 [NAL9602](INFO): SBD MO Status=0, MOMSN=39565, MT Status=0, MTMSN=0
2023-03-30T20:24:17.956Z,1680207857.956 [NAL9602](INFO): No messages in MT queue
2023-03-30T20:24:25.513Z,1680207865.513 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230330T190033/Express0047.lzma
2023-03-30T20:24:26.515Z,1680207866.515 [DataOverHttps](INFO): Moved sent file to Logs/20230330T190033/Express0047.lzma.bak
2023-03-30T20:24:26.515Z,1680207866.515 [DataOverHttps](INFO): SBD MOMSN=17831072
2023-03-30T20:24:27.709Z,1680207867.709 [Default:CheckIn:Read_Iridium] Stopped
2023-03-30T20:24:27.709Z,1680207867.709 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-30T20:24:27.710Z,1680207867.710 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-30T20:24:48.728Z,1680207888.728 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-30T20:28:31.574Z,1680208111.574 [CommandExec](IMPORTANT): got command quit
2023-03-30T20:28:32.577Z,1680208112.577 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:32.578Z,1680208112.578 [CommandExec](INFO): Uninitializing the command executive.
2023-03-30T20:28:32.578Z,1680208112.578 [CommandExec](INFO): Uninitializing the command scheduler.
2023-03-30T20:28:32.578Z,1680208112.578 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:32.613Z,1680208112.613 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-03-30T20:28:32.613Z,1680208112.613 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-03-30T20:28:32.614Z,1680208112.614 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:32.614Z,1680208112.614 [NavChartDb](INFO): Join timeout helper Thread ID is 2121
2023-03-30T20:28:32.793Z,1680208112.793 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:32.794Z,1680208112.794 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:32.813Z,1680208112.813 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-03-30T20:28:32.813Z,1680208112.813 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:32.814Z,1680208112.814 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2122
2023-03-30T20:28:33.033Z,1680208113.033 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:33.034Z,1680208113.034 [WetLabsBB2FL](INFO): Powering down
2023-03-30T20:28:33.034Z,1680208113.034 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:33.044Z,1680208113.044 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-03-30T20:28:33.044Z,1680208113.044 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:33.044Z,1680208113.044 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2123
2023-03-30T20:28:33.685Z,1680208113.685 [CTD_Seabird](INFO): Powering down
2023-03-30T20:28:33.697Z,1680208113.697 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:33.698Z,1680208113.698 [CTD_Seabird](INFO): Powering down
2023-03-30T20:28:33.709Z,1680208113.709 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:33.717Z,1680208113.717 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-03-30T20:28:33.717Z,1680208113.717 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:33.718Z,1680208113.718 [Radio_Surface](INFO): Join timeout helper Thread ID is 2124
2023-03-30T20:28:34.077Z,1680208114.077 [Radio_Surface](INFO): Powering down
2023-03-30T20:28:34.078Z,1680208114.078 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:34.079Z,1680208114.079 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:34.085Z,1680208114.085 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-03-30T20:28:34.085Z,1680208114.085 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:34.086Z,1680208114.086 [Onboard](INFO): Join timeout helper Thread ID is 2125
2023-03-30T20:28:37.485Z,1680208117.485 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:37.486Z,1680208117.486 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:37.503Z,1680208117.503 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-03-30T20:28:37.503Z,1680208117.503 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:37.504Z,1680208117.504 [DataOverHttps](INFO): Join timeout helper Thread ID is 2126
2023-03-30T20:28:37.913Z,1680208117.913 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:37.917Z,1680208117.917 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:37.930Z,1680208117.930 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-03-30T20:28:37.930Z,1680208117.930 [logger ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:37.931Z,1680208117.931 [logger](INFO): Join timeout helper Thread ID is 2127
2023-03-30T20:28:37.957Z,1680208117.957 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:37.958Z,1680208117.958 [logger ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:37.970Z,1680208117.970 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-03-30T20:28:37.970Z,1680208117.970 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:37.970Z,1680208117.970 [CommandLine](INFO): Join timeout helper Thread ID is 2128
2023-03-30T20:28:37.981Z,1680208117.981 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:37.982Z,1680208117.982 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:37.990Z,1680208117.990 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-03-30T20:28:37.990Z,1680208117.990 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:37.990Z,1680208117.990 [CommandExec](INFO): Join timeout helper Thread ID is 2129
2023-03-30T20:28:37.992Z,1680208117.992 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-03-30T20:28:37.992Z,1680208117.992 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:37.992Z,1680208117.992 [controlThread](INFO): Join timeout helper Thread ID is 2130
2023-03-30T20:28:38.169Z,1680208118.169 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-30T20:28:38.170Z,1680208118.170 [controlThread](DEBUG): Uninitializing ControlThread
2023-03-30T20:28:38.170Z,1680208118.170 [AHRS_M2](INFO): Powering down
2023-03-30T20:28:38.242Z,1680208118.242 [MultiRay](INFO): Powering down.
2023-03-30T20:28:38.243Z,1680208118.243 [NAL9602](INFO): Powering down
2023-03-30T20:28:38.246Z,1680208118.246 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-03-30T20:28:38.247Z,1680208118.247 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-03-30T20:28:38.247Z,1680208118.247 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-03-30T20:28:38.248Z,1680208118.248 [MissionManager](INFO): Uninitializing Mission Default
2023-03-30T20:28:38.248Z,1680208118.248 [Default] Stopped
2023-03-30T20:28:38.248Z,1680208118.248 [Default](DEBUG): Aggregate::uninitialize Default
2023-03-30T20:28:38.248Z,1680208118.248 [Default:B.GoToSurface] Stopped
2023-03-30T20:28:38.248Z,1680208118.248 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-30T20:28:38.248Z,1680208118.248 [Default:CheckIn] Stopped
2023-03-30T20:28:38.248Z,1680208118.248 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-30T20:28:38.248Z,1680208118.248 [Default:CheckIn:C.Wait] Stopped
2023-03-30T20:28:38.248Z,1680208118.248 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-30T20:28:38.251Z,1680208118.251 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-03-30T20:28:38.252Z,1680208118.252 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-03-30T20:28:38.252Z,1680208118.252 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-03-30T20:28:38.252Z,1680208118.252 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-03-30T20:28:38.252Z,1680208118.252 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-03-30T20:28:38.253Z,1680208118.253 [BuoyancyServo](INFO): Powering down
2023-03-30T20:28:38.265Z,1680208118.265 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-03-30T20:28:38.266Z,1680208118.266 [ElevatorServo](INFO): Powering down
2023-03-30T20:28:38.266Z,1680208118.266 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-03-30T20:28:38.266Z,1680208118.266 [MassServo](INFO): Powering down
2023-03-30T20:28:38.267Z,1680208118.267 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-03-30T20:28:38.267Z,1680208118.267 [RudderServo](INFO): Powering down
2023-03-30T20:28:38.268Z,1680208118.268 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-03-30T20:28:38.268Z,1680208118.268 [ThrusterServo](INFO): Powering down
2023-03-30T20:28:38.269Z,1680208118.269 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-03-30T20:28:38.270Z,1680208118.270 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-03-30T20:28:38.270Z,1680208118.270 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-03-30T20:28:38.270Z,1680208118.270 [CBIT](DEBUG): Powering off loads.
2023-03-30T20:28:38.281Z,1680208118.281 [CBIT](DEBUG): Disabling WDT.
2023-03-30T20:28:38.293Z,1680208118.293 [CBIT](DEBUG): Opening all GF detection circuits.
2023-03-30T20:28:38.294Z,1680208118.294 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:38.347Z,1680208118.347 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:38.349Z,1680208118.349 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:38.356Z,1680208118.356 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:38.429Z,1680208118.429 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:38.433Z,1680208118.433 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:38.472Z,1680208118.472 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-30T20:28:38.542Z,1680208118.542 [logger ThreadHandler](INFO): Thread cancelled.