2023-02-13T16:33:38.322Z,1676306018.322 [Supervisor](DEBUG): Initializing supervisor.
2023-02-13T16:33:38.326Z,1676306018.326 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-02-13T16:33:38.326Z,1676306018.326 [SyncHandler](INFO): Protected caller Thread ID is 835
2023-02-13T16:33:38.327Z,1676306018.327 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-02-13T16:33:38.328Z,1676306018.328 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-02-13T16:33:38.328Z,1676306018.328 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2023-02-13T16:33:38.332Z,1676306018.332 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-02-13T16:33:38.349Z,1676306018.349 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-02-13T16:33:38.350Z,1676306018.350 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-02-13T16:33:38.351Z,1676306018.351 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2023-02-13T16:33:38.355Z,1676306018.355 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-02-13T16:33:38.356Z,1676306018.356 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-02-13T16:33:38.356Z,1676306018.356 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2023-02-13T16:33:38.358Z,1676306018.358 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-02-13T16:33:38.359Z,1676306018.359 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-02-13T16:33:38.359Z,1676306018.359 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2023-02-13T16:33:38.363Z,1676306018.363 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-02-13T16:33:38.363Z,1676306018.363 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-02-13T16:33:38.368Z,1676306018.368 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-02-13T16:33:38.640Z,1676306018.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-02-13T16:33:38.641Z,1676306018.641 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-02-13T16:33:38.723Z,1676306018.723 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-02-13T16:33:39.200Z,1676306019.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-02-13T16:33:39.203Z,1676306019.203 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-02-13T16:33:39.530Z,1676306019.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-02-13T16:33:39.531Z,1676306019.531 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-02-13T16:33:39.627Z,1676306019.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-02-13T16:33:39.628Z,1676306019.628 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-02-13T16:33:40.032Z,1676306020.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-02-13T16:33:40.034Z,1676306020.034 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-02-13T16:33:40.244Z,1676306020.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-02-13T16:33:40.245Z,1676306020.245 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-02-13T16:33:40.753Z,1676306020.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-02-13T16:33:40.755Z,1676306020.755 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-02-13T16:33:40.892Z,1676306020.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-02-13T16:33:40.893Z,1676306020.893 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-02-13T16:33:40.973Z,1676306020.973 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-02-13T16:33:41.716Z,1676306021.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-02-13T16:33:41.842Z,1676306021.842 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-02-13T16:33:42.541Z,1676306022.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-02-13T16:33:42.608Z,1676306022.608 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-02-13T16:33:42.802Z,1676306022.802 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-02-13T16:33:42.803Z,1676306022.803 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-02-13T16:33:42.914Z,1676306022.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-02-13T16:33:42.915Z,1676306022.915 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-02-13T16:33:43.139Z,1676306023.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-02-13T16:33:43.141Z,1676306023.141 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-02-13T16:33:43.380Z,1676306023.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-02-13T16:33:43.382Z,1676306023.382 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-02-13T16:33:43.386Z,1676306023.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-02-13T16:33:43.477Z,1676306023.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-02-13T16:33:43.563Z,1676306023.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-02-13T16:33:43.678Z,1676306023.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-02-13T16:33:43.761Z,1676306023.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-02-13T16:33:43.857Z,1676306023.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-02-13T16:33:43.959Z,1676306023.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-02-13T16:33:44.144Z,1676306024.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-02-13T16:33:44.221Z,1676306024.221 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-02-13T16:33:44.368Z,1676306024.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-02-13T16:33:44.505Z,1676306024.505 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-02-13T16:33:44.622Z,1676306024.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-02-13T16:33:44.886Z,1676306024.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-02-13T16:33:44.886Z,1676306024.886 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-02-13T16:33:44.904Z,1676306024.904 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-02-13T16:33:44.973Z,1676306024.973 [VerticalControl](DEBUG): Construct VerticalControl.
2023-02-13T16:33:45.033Z,1676306025.033 [VerticalControl] Loaded
2023-02-13T16:33:45.033Z,1676306025.033 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-02-13T16:33:45.036Z,1676306025.036 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-02-13T16:33:45.077Z,1676306025.077 [HorizontalControl] Loaded
2023-02-13T16:33:45.077Z,1676306025.077 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-02-13T16:33:45.080Z,1676306025.080 [SpeedControl](DEBUG): Construct SpeedControl.
2023-02-13T16:33:45.083Z,1676306025.083 [SpeedControl] Loaded
2023-02-13T16:33:45.083Z,1676306025.083 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-02-13T16:33:45.086Z,1676306025.086 [LoopControl](DEBUG): Construct LoopControl.
2023-02-13T16:33:45.086Z,1676306025.086 [LoopControl] Loaded
2023-02-13T16:33:45.087Z,1676306025.087 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-02-13T16:33:45.087Z,1676306025.087 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-02-13T16:33:45.089Z,1676306025.089 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-02-13T16:33:45.104Z,1676306025.104 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-02-13T16:33:45.105Z,1676306025.105 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-02-13T16:33:45.293Z,1676306025.293 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-02-13T16:33:45.293Z,1676306025.293 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-02-13T16:33:45.481Z,1676306025.481 [BuoyancyServo] Loaded
2023-02-13T16:33:45.481Z,1676306025.481 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-02-13T16:33:45.501Z,1676306025.501 [ElevatorServo] Loaded
2023-02-13T16:33:45.501Z,1676306025.501 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-02-13T16:33:45.520Z,1676306025.520 [MassServo] Loaded
2023-02-13T16:33:45.520Z,1676306025.520 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-02-13T16:33:45.539Z,1676306025.539 [RudderServo] Loaded
2023-02-13T16:33:45.539Z,1676306025.539 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-02-13T16:33:45.556Z,1676306025.556 [ThrusterServo] Loaded
2023-02-13T16:33:45.556Z,1676306025.556 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-02-13T16:33:45.557Z,1676306025.557 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-02-13T16:33:45.557Z,1676306025.557 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-02-13T16:33:45.696Z,1676306025.696 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-02-13T16:33:45.696Z,1676306025.696 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-02-13T16:33:45.710Z,1676306025.710 [NavChart] Loaded
2023-02-13T16:33:45.710Z,1676306025.710 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-02-13T16:33:45.716Z,1676306025.716 [UniversalFixResidualReporter] Loaded
2023-02-13T16:33:45.716Z,1676306025.716 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-02-13T16:33:45.717Z,1676306025.717 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-02-13T16:33:45.717Z,1676306025.717 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-02-13T16:33:45.801Z,1676306025.801 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-02-13T16:33:45.801Z,1676306025.801 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-02-13T16:33:46.547Z,1676306026.547 [AHRS_M2] Loaded
2023-02-13T16:33:46.548Z,1676306026.548 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-02-13T16:33:47.898Z,1676306027.898 [BPC1] Loaded
2023-02-13T16:33:47.898Z,1676306027.898 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-02-13T16:33:48.123Z,1676306028.123 [DataOverHttps] Loaded
2023-02-13T16:33:48.123Z,1676306028.123 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-02-13T16:33:48.124Z,1676306028.124 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-02-13T16:33:48.124Z,1676306028.124 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2023-02-13T16:33:48.146Z,1676306028.146 [Depth_Keller] Loaded
2023-02-13T16:33:48.146Z,1676306028.146 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-02-13T16:33:48.151Z,1676306028.151 [DropWeight] Loaded
2023-02-13T16:33:48.151Z,1676306028.151 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-02-13T16:33:48.170Z,1676306028.170 [MultiRay] Loaded
2023-02-13T16:33:48.170Z,1676306028.170 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-02-13T16:33:48.232Z,1676306028.232 [NAL9602] Loaded
2023-02-13T16:33:48.232Z,1676306028.232 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-02-13T16:33:48.262Z,1676306028.262 [Onboard] Loaded
2023-02-13T16:33:48.262Z,1676306028.262 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-02-13T16:33:48.263Z,1676306028.263 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2023-02-13T16:33:48.263Z,1676306028.263 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2023-02-13T16:33:48.277Z,1676306028.277 [Power24vConverter] Loaded
2023-02-13T16:33:48.277Z,1676306028.277 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-02-13T16:33:48.290Z,1676306028.290 [Radio_Surface] Loaded
2023-02-13T16:33:48.291Z,1676306028.291 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-02-13T16:33:48.292Z,1676306028.292 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2023-02-13T16:33:48.292Z,1676306028.292 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2023-02-13T16:33:48.293Z,1676306028.293 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-02-13T16:33:48.295Z,1676306028.295 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-02-13T16:33:48.368Z,1676306028.368 [DepthRateCalculator] Loaded
2023-02-13T16:33:48.368Z,1676306028.368 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-02-13T16:33:48.373Z,1676306028.373 [PitchRateCalculator] Loaded
2023-02-13T16:33:48.373Z,1676306028.373 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-02-13T16:33:48.382Z,1676306028.382 [SpeedCalculator] Loaded
2023-02-13T16:33:48.383Z,1676306028.383 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-02-13T16:33:48.387Z,1676306028.387 [YawRateCalculator] Loaded
2023-02-13T16:33:48.387Z,1676306028.387 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-02-13T16:33:48.406Z,1676306028.406 [ElevatorOffsetCalculator] Loaded
2023-02-13T16:33:48.406Z,1676306028.406 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-02-13T16:33:48.407Z,1676306028.407 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-02-13T16:33:48.407Z,1676306028.407 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-02-13T16:33:48.586Z,1676306028.586 [CANONSampler] Loaded
2023-02-13T16:33:48.587Z,1676306028.587 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-02-13T16:33:48.646Z,1676306028.646 [CTD_Seabird] Loaded
2023-02-13T16:33:48.647Z,1676306028.647 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-02-13T16:33:48.648Z,1676306028.648 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2023-02-13T16:33:48.648Z,1676306028.648 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2023-02-13T16:33:48.668Z,1676306028.668 [PAR_Licor] Loaded
2023-02-13T16:33:48.669Z,1676306028.669 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-02-13T16:33:48.700Z,1676306028.700 [WetLabsBB2FL] Loaded
2023-02-13T16:33:48.700Z,1676306028.700 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-02-13T16:33:48.701Z,1676306028.701 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2023-02-13T16:33:48.701Z,1676306028.701 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2023-02-13T16:33:48.702Z,1676306028.702 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-02-13T16:33:48.703Z,1676306028.703 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-02-13T16:33:48.745Z,1676306028.745 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-02-13T16:33:48.746Z,1676306028.746 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-02-13T16:33:49.117Z,1676306029.117 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-02-13T16:33:49.118Z,1676306029.118 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-02-13T16:33:49.289Z,1676306029.289 [SBIT](DEBUG): Construct Startup Built In Test.
2023-02-13T16:33:49.298Z,1676306029.298 [SBIT] Loaded
2023-02-13T16:33:49.298Z,1676306029.298 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-02-13T16:33:49.301Z,1676306029.301 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-02-13T16:33:49.314Z,1676306029.314 [IBIT] Loaded
2023-02-13T16:33:49.314Z,1676306029.314 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-02-13T16:33:49.320Z,1676306029.320 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-02-13T16:33:49.426Z,1676306029.426 [CBIT] Loaded
2023-02-13T16:33:49.427Z,1676306029.427 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-02-13T16:33:49.427Z,1676306029.427 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-02-13T16:33:49.428Z,1676306029.428 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-02-13T16:33:49.554Z,1676306029.554 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-02-13T16:33:49.560Z,1676306029.560 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-02-13T16:33:49.563Z,1676306029.563 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-02-13T16:33:49.574Z,1676306029.574 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-02-13T16:33:49.575Z,1676306029.575 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B7E4E0
2023-02-13T16:33:49.576Z,1676306029.576 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2023-02-13T16:33:49.580Z,1676306029.580 [Supervisor](INFO): Main Thread ID is 830
2023-02-13T16:33:49.580Z,1676306029.580 [Supervisor](DEBUG): Running supervisor.
2023-02-13T16:33:49.581Z,1676306029.581 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2023-02-13T16:33:49.582Z,1676306029.582 [CommandExec](INFO): Initializing the command executive.
2023-02-13T16:33:49.583Z,1676306029.583 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2023-02-13T16:33:49.585Z,1676306029.585 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2023-02-13T16:33:49.586Z,1676306029.586 [controlThread](DEBUG): Initializing ControlThread
2023-02-13T16:33:49.587Z,1676306029.587 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-02-13T16:33:49.588Z,1676306029.588 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-02-13T16:33:49.589Z,1676306029.589 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-02-13T16:33:49.590Z,1676306029.590 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-02-13T16:33:49.591Z,1676306029.591 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-02-13T16:33:49.592Z,1676306029.592 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-02-13T16:33:49.595Z,1676306029.595 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-02-13T16:33:49.596Z,1676306029.596 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-02-13T16:33:49.596Z,1676306029.596 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-02-13T16:33:49.596Z,1676306029.596 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-02-13T16:33:49.596Z,1676306029.596 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-02-13T16:33:49.601Z,1676306029.601 [SBIT](INFO): Initialize SBIT Component.
2023-02-13T16:33:49.602Z,1676306029.602 [SBIT](IMPORTANT): git: 2023-02-10
2023-02-13T16:33:49.602Z,1676306029.602 [SBIT](INFO): git hash: c406eb0a7e3d06ad2f4805df3abb847da8df30bc
2023-02-13T16:33:49.602Z,1676306029.602 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-02-13T16:33:49.603Z,1676306029.603 [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-02-13T16:33:49.604Z,1676306029.604 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2023-02-13T16:33:49.605Z,1676306029.605 [IBIT](INFO): Initialize IBIT Component.
2023-02-13T16:33:49.606Z,1676306029.606 [CBIT](DEBUG): Initialize CBIT Component.
2023-02-13T16:33:49.607Z,1676306029.607 [logger ThreadHandler](INFO): Handler Thread ID is 933
2023-02-13T16:33:49.617Z,1676306029.617 [CBIT](DEBUG): Initialized mux pins.
2023-02-13T16:33:49.617Z,1676306029.617 [CBIT](DEBUG): Initializing the watchdog timer.
2023-02-13T16:33:49.625Z,1676306029.625 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2023-02-13T16:33:49.627Z,1676306029.627 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-02-13T16:33:49.637Z,1676306029.637 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2023-02-13T16:33:49.642Z,1676306029.642 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-02-13T16:33:49.642Z,1676306029.642 [CBIT](DEBUG): Initializing heartbeat.
2023-02-13T16:33:49.660Z,1676306029.660 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2023-02-13T16:33:49.677Z,1676306029.677 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 937
2023-02-13T16:33:49.678Z,1676306029.678 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-02-13T16:33:49.680Z,1676306029.680 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 939
2023-02-13T16:33:49.687Z,1676306029.687 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941
2023-02-13T16:33:49.690Z,1676306029.690 [WetLabsBB2FL](INFO): Powering up
2023-02-13T16:33:49.694Z,1676306029.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-02-13T16:33:49.695Z,1676306029.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-02-13T16:33:49.695Z,1676306029.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-02-13T16:33:49.695Z,1676306029.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-02-13T16:33:49.695Z,1676306029.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-02-13T16:33:49.695Z,1676306029.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-02-13T16:33:49.696Z,1676306029.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-02-13T16:33:49.696Z,1676306029.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-02-13T16:33:49.717Z,1676306029.717 [CBIT](DEBUG): Deactivating GF circuits.
2023-02-13T16:33:49.717Z,1676306029.717 [CBIT](DEBUG): Deactivating emergency mode.
2023-02-13T16:33:49.753Z,1676306029.753 [CBIT](DEBUG): Backplane powered.
2023-02-13T16:33:49.754Z,1676306029.754 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-02-13T16:33:49.780Z,1676306029.780 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-13T16:33:49.807Z,1676306029.807 [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-02-13T16:33:49.808Z,1676306029.808 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-02-13T16:33:49.882Z,1676306029.882 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-02-13T16:33:49.884Z,1676306029.884 [Default:A.Wait](DEBUG): Construct Wait.
2023-02-13T16:33:49.886Z,1676306029.886 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-13T16:33:49.921Z,1676306029.921 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-02-13T16:33:49.924Z,1676306029.924 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-02-13T16:33:49.946Z,1676306029.946 [Default:E.Execute](DEBUG): Construct Execute.
2023-02-13T16:33:49.965Z,1676306029.965 [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-02-13T16:33:49.970Z,1676306029.970 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-02-13T16:33:49.993Z,1676306029.993 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-02-13T16:33:50.061Z,1676306030.061 [Radio_Surface](INFO): Powering up
2023-02-13T16:33:50.131Z,1676306030.131 [MultiRay](INFO): Powering up MultiRay Lights
2023-02-13T16:33:50.170Z,1676306030.170 [Power24vConverter](INFO): Powering up.
2023-02-13T16:33:50.170Z,1676306030.170 [CANONSampler](INFO): Powering down
2023-02-13T16:33:50.240Z,1676306030.240 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-02-13T16:33:50.245Z,1676306030.245 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-02-13T16:33:50.246Z,1676306030.246 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-02-13T16:33:50.254Z,1676306030.254 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-02-13T16:33:50.255Z,1676306030.255 [MassServo](DEBUG): Initializing EZServoServo.
2023-02-13T16:33:50.261Z,1676306030.261 [MassServo](DEBUG): Initializing MassServo.
2023-02-13T16:33:50.262Z,1676306030.262 [RudderServo](DEBUG): Initializing EZServoServo.
2023-02-13T16:33:50.269Z,1676306030.269 [RudderServo](DEBUG): Initializing RudderServo.
2023-02-13T16:33:50.270Z,1676306030.270 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-02-13T16:33:50.277Z,1676306030.277 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-02-13T16:33:51.125Z,1676306031.125 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-02-13T16:33:51.125Z,1676306031.125 [RudderServo](FAULT): Rudder failed to initialize
2023-02-13T16:33:51.125Z,1676306031.125 [RudderServo] Communications Fault, FailCount= 1
2023-02-13T16:33:51.125Z,1676306031.125 [RudderServo](ERROR): Communications Fault
2023-02-13T16:33:51.225Z,1676306031.225 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-02-13T16:33:51.646Z,1676306031.646 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-02-13T16:33:51.646Z,1676306031.646 [RudderServo](INFO): Powering down
2023-02-13T16:33:52.098Z,1676306032.098 [RudderServo](DEBUG): Initializing EZServoServo.
2023-02-13T16:33:52.218Z,1676306032.218 [RudderServo](DEBUG): Initializing RudderServo.
2023-02-13T16:33:52.222Z,1676306032.222 [CBIT](INFO): Clearing failed state for component RudderServo
2023-02-13T16:33:52.222Z,1676306032.222 [RudderServo] No Fault, FailCount= 1
2023-02-13T16:33:52.293Z,1676306032.293 [WetLabsBB2FL](INFO): Powering down
2023-02-13T16:34:18.339Z,1676306058.339 [NAL9602](INFO): Powering up NAL9602
2023-02-13T16:34:29.243Z,1676306069.243 [NAL9602](INFO): NAL9602 initialized
2023-02-13T16:34:42.242Z,1676306082.242 [SBIT](IMPORTANT): Beginning Startup BIT
2023-02-13T16:34:42.246Z,1676306082.246 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T16:34:53.532Z,1676306093.532 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-02-13T16:34:53.922Z,1676306093.922 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T16:35:04.934Z,1676306104.934 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-02-13T16:35:05.302Z,1676306105.302 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T16:35:16.182Z,1676306116.182 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-02-13T16:35:16.182Z,1676306116.182 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-02-13T16:35:36.360Z,1676306136.360 [SBIT](IMPORTANT): SBIT PASSED
2023-02-13T16:35:36.361Z,1676306136.361 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-02-13T16:35:36.362Z,1676306136.362 [SBIT](IMPORTANT): AHRS_M2.readAccelerations=0 bool;

2023-02-13T16:35:36.362Z,1676306136.362 [SBIT](IMPORTANT): AHRS_M2.readAngularVelocities=0 bool;

2023-02-13T16:35:36.362Z,1676306136.362 [SBIT](IMPORTANT): AHRS_M2.readMagnetics=0 bool;

2023-02-13T16:35:36.362Z,1676306136.362 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-02-13T16:35:36.362Z,1676306136.362 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;

2023-02-13T16:35:36.362Z,1676306136.362 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-02-13T16:35:36.362Z,1676306136.362 [SBIT](IMPORTANT): CANONSampler.sampleTimeout=10 minute;

2023-02-13T16:35:36.362Z,1676306136.362 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-02-13T16:35:36.363Z,1676306136.363 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2023-02-13T16:35:36.363Z,1676306136.363 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2023-02-13T16:35:36.363Z,1676306136.363 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=169.635298 cubic_centimeter;

2023-02-13T16:35:36.363Z,1676306136.363 [SBIT](IMPORTANT): VerticalControl.massDefault=-8.141334 millimeter;

2023-02-13T16:35:36.731Z,1676306136.731 [MissionManager](IMPORTANT): Started mission Startup
2023-02-13T16:35:36.732Z,1676306136.732 [Startup] Running Loop=1
2023-02-13T16:35:36.732Z,1676306136.732 [Startup](DEBUG): Aggregate::initialize Startup
2023-02-13T16:35:36.732Z,1676306136.732 [Startup:A.GoToSurface] Running Loop=1
2023-02-13T16:35:36.732Z,1676306136.732 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-13T16:35:36.733Z,1676306136.733 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-13T16:35:36.733Z,1676306136.733 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-13T16:35:36.734Z,1676306136.734 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-13T16:35:36.734Z,1676306136.734 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-13T16:35:36.734Z,1676306136.734 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-13T16:35:36.736Z,1676306136.736 [Startup:StartupSatComms] Running Loop=1
2023-02-13T16:35:36.736Z,1676306136.736 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-02-13T16:35:36.736Z,1676306136.736 [Startup:StartupSatComms:A] Running Loop=1
2023-02-13T16:35:37.248Z,1676306137.248 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-02-13T16:36:17.572Z,1676306177.572 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005057
2023-02-13T16:36:36.903Z,1676306196.903 [Startup:StartupSatComms:A](INFO): Timed out from 2023-02-13T16:35:36.7Z
2023-02-13T16:36:36.903Z,1676306196.903 [Startup:StartupSatComms:A] Stopped
2023-02-13T16:36:36.903Z,1676306196.903 [Startup:StartupSatComms:B] Running Loop=1
2023-02-13T16:36:37.312Z,1676306197.312 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-02-13T16:36:44.588Z,1676306204.588 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230213T163149/Courier0000.lzma
2023-02-13T16:36:49.848Z,1676306209.848 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-02-13T16:36:49.848Z,1676306209.848 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T16:36:49.867Z,1676306209.867 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T16:36:50.267Z,1676306210.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T16:36:50.267Z,1676306210.267 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-02-13T16:36:52.819Z,1676306212.819 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230213T163149/Courier0000.lzma
2023-02-13T16:36:53.821Z,1676306213.821 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163149/Courier0000.lzma.bak
2023-02-13T16:36:53.822Z,1676306213.822 [DataOverHttps](INFO): SBD MOMSN=17464648
2023-02-13T16:36:58.700Z,1676306218.700 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T16:37:09.319Z,1676306229.319 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230213T163338/Courier0000.lzma
2023-02-13T16:37:10.321Z,1676306230.321 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0000.lzma.bak
2023-02-13T16:37:10.321Z,1676306230.321 [DataOverHttps](INFO): SBD MOMSN=17464651
2023-02-13T16:37:13.648Z,1676306233.648 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T16:37:25.801Z,1676306245.801 [DataOverHttps](INFO): Sending 352 bytes from file Logs/20230213T163149/Express0001.lzma
2023-02-13T16:37:26.801Z,1676306246.801 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163149/Express0001.lzma.bak
2023-02-13T16:37:26.801Z,1676306246.801 [DataOverHttps](INFO): SBD MOMSN=17464654
2023-02-13T16:37:29.002Z,1676306249.002 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T16:37:37.092Z,1676306257.092 [Startup:StartupSatComms:B](INFO): Timed out from 2023-02-13T16:36:36.9Z
2023-02-13T16:37:37.093Z,1676306257.093 [Startup:StartupSatComms:B] Stopped
2023-02-13T16:37:37.093Z,1676306257.093 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-02-13T16:37:37.093Z,1676306257.093 [Startup:StartupSatComms] Stopped
2023-02-13T16:37:37.093Z,1676306257.093 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-02-13T16:37:37.095Z,1676306257.095 [Startup](INFO): Completed Startup
2023-02-13T16:37:37.096Z,1676306257.096 [MissionManager](INFO): Startup is completed.
2023-02-13T16:37:37.096Z,1676306257.096 [MissionManager](INFO): Uninitializing Mission Startup
2023-02-13T16:37:37.096Z,1676306257.096 [Startup] Stopped
2023-02-13T16:37:37.096Z,1676306257.096 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-02-13T16:37:37.096Z,1676306257.096 [Startup:A.GoToSurface] Stopped
2023-02-13T16:37:37.096Z,1676306257.096 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-02-13T16:37:37.516Z,1676306257.516 [MissionManager](IMPORTANT): Started mission Default
2023-02-13T16:37:37.516Z,1676306257.516 [Default] Running Loop=1
2023-02-13T16:37:37.516Z,1676306257.516 [Default](DEBUG): Aggregate::initialize Default
2023-02-13T16:37:37.516Z,1676306257.516 [Default:B.GoToSurface] Running Loop=1
2023-02-13T16:37:37.516Z,1676306257.516 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-13T16:37:37.516Z,1676306257.516 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-13T16:37:37.517Z,1676306257.517 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-13T16:37:37.517Z,1676306257.517 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-13T16:37:37.517Z,1676306257.517 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-13T16:37:37.518Z,1676306257.518 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-13T16:37:37.518Z,1676306257.518 [Default:A.Wait] Running Loop=1
2023-02-13T16:37:37.518Z,1676306257.518 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:37:42.233Z,1676306262.233 [DataOverHttps](INFO): Sending 797 bytes from file Logs/20230213T163338/Express0001.lzma
2023-02-13T16:37:43.233Z,1676306263.233 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0001.lzma.bak
2023-02-13T16:37:43.233Z,1676306263.233 [DataOverHttps](INFO): SBD MOMSN=17464661
2023-02-13T16:37:43.948Z,1676306263.948 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T16:37:49.724Z,1676306269.724 [CommandExec](IMPORTANT): got command failComponent
2023-02-13T16:37:49.724Z,1676306269.724 [CommandExec](IMPORTANT): Failed components:
2023-02-13T16:37:49.725Z,1676306269.725 [CommandExec](IMPORTANT): No failed Components.
2023-02-13T16:37:50.839Z,1676306270.839 [Default:A.Wait](INFO): Done Waiting.
2023-02-13T16:37:50.839Z,1676306270.839 [Default:A.Wait] Stopped
2023-02-13T16:37:50.839Z,1676306270.839 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:37:51.253Z,1676306271.253 [Default:CheckIn] Running Loop=1
2023-02-13T16:37:51.253Z,1676306271.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:37:51.253Z,1676306271.253 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:37:51.641Z,1676306271.641 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-02-13T16:37:58.897Z,1676306278.897 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T16:38:14.254Z,1676306294.254 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T16:38:28.792Z,1676306308.792 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T16:38:43.740Z,1676306323.740 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T16:38:59.092Z,1676306339.092 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T16:39:13.642Z,1676306353.642 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-02-13T16:39:20.121Z,1676306360.121 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2023-02-13T16:39:32.637Z,1676306372.637 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-13T16:39:50.893Z,1676306390.893 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-02-13T16:39:50.893Z,1676306390.893 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T16:39:50.904Z,1676306390.904 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T16:39:51.274Z,1676306391.274 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T16:39:51.274Z,1676306391.274 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-02-13T16:40:02.877Z,1676306402.877 [CommandLine](INFO): End of History
2023-02-13T16:40:03.156Z,1676306403.156 [CommandLine](INFO): End of History
2023-02-13T16:40:04.045Z,1676306404.045 [CommandLine](INFO): End of History
2023-02-13T16:40:05.973Z,1676306405.973 [CommandLine](INFO): End of History
2023-02-13T16:41:54.893Z,1676306514.893 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2023-02-13T16:41:54.897Z,1676306514.897 [BPC1](INFO): Received data from all battery sticks.
2023-02-13T16:42:51.486Z,1676306571.486 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-13T16:37:51.3Z
2023-02-13T16:42:51.486Z,1676306571.486 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:42:51.486Z,1676306571.486 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:42:51.869Z,1676306571.869 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-02-13T16:42:51.869Z,1676306571.869 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T16:42:51.871Z,1676306571.871 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-02-13T16:42:51.929Z,1676306571.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T16:42:52.395Z,1676306572.395 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T16:42:52.395Z,1676306572.395 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-02-13T16:42:52.815Z,1676306572.815 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230213T163338/Courier0004.lzma
2023-02-13T16:42:53.805Z,1676306573.805 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0004.lzma.bak
2023-02-13T16:42:53.805Z,1676306573.805 [DataOverHttps](INFO): SBD MOMSN=17464704
2023-02-13T16:42:59.298Z,1676306579.298 [CommandExec](IMPORTANT): got command ! /etc/rc.d/init.d/settime restart
2023-02-13T16:43:02.270Z,1676306582.270 [CommandExec](IMPORTANT): settime stop or restart
  Setting hardware clock from system clock
  Mon Feb 13 16:42:59 2023  0.000000 seconds
  settime start or restart
  Setting clock frequency and tick values from nonvolatile memory.
      mode:         16386
  -o  offset:       0
  -f  frequency:    886544
      maxerror:     16000000
      esterror:     16000000
      status:       65 (PLL | UNSYNC)
  -p  timeconstant: 7
      precision:    1
      tolerance:    32768000
  -t  tick:         10000
      time.tv_sec:  1676306579
      time.tv_usec: 836747
      return value: 5 (clock not synchronized)
  ping returned from NTP server: 216.239.35.4
  44968 60181.056   43276.0      0.0    1977.4     76.3    886544
  Time set from NTP server: 216.239.35.4
  Setting hardware clock from system clock
  Mon Feb 13 16:43:02 2023  0.000000 seconds
  NTPD already running.
  
2023-02-13T16:43:09.560Z,1676306589.560 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20230213T163338/Express0005.lzma
2023-02-13T16:43:10.562Z,1676306590.562 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0005.lzma.bak
2023-02-13T16:43:10.562Z,1676306590.562 [DataOverHttps](INFO): SBD MOMSN=17464706
2023-02-13T16:43:11.721Z,1676306591.721 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:43:11.721Z,1676306591.721 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:43:11.721Z,1676306591.721 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:43:50.906Z,1676306630.906 [Power24vConverter](INFO): Powering down.
2023-02-13T16:44:31.302Z,1676306671.302 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-13T16:44:31.302Z,1676306671.302 [NAL9602] Data Fault, FailCount= 1
2023-02-13T16:44:31.302Z,1676306671.302 [NAL9602](ERROR): Data Fault
2023-02-13T16:44:31.347Z,1676306671.347 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-13T16:44:31.702Z,1676306671.702 [NAL9602](INFO): Powering down
2023-02-13T16:44:32.540Z,1676306672.540 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-13T16:44:32.540Z,1676306672.540 [NAL9602] No Fault, FailCount= 1
2023-02-13T16:45:02.006Z,1676306702.006 [NAL9602](INFO): Powering up NAL9602
2023-02-13T16:45:12.921Z,1676306712.921 [NAL9602](INFO): NAL9602 initialized
2023-02-13T16:45:52.916Z,1676306752.916 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-02-13T16:45:52.917Z,1676306752.917 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T16:45:52.955Z,1676306752.955 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T16:45:53.326Z,1676306753.326 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T16:45:53.326Z,1676306753.326 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-02-13T16:47:24.769Z,1676306844.769 [CommandExec](IMPORTANT): got command burn on
2023-02-13T16:47:24.769Z,1676306844.769 [CommandExec](IMPORTANT): Activating dropweight wire
2023-02-13T16:48:12.307Z,1676306892.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:48:12.307Z,1676306892.307 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:48:12.307Z,1676306892.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:48:12.308Z,1676306892.308 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:48:12.695Z,1676306892.695 [Default:CheckIn:D] Stopped
2023-02-13T16:48:12.695Z,1676306892.695 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:48:13.103Z,1676306893.103 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.586315 min 
2023-02-13T16:48:13.103Z,1676306893.103 [Default:CheckIn:E] Stopped
2023-02-13T16:48:13.103Z,1676306893.103 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:48:13.103Z,1676306893.103 [Default:CheckIn] Stopped
2023-02-13T16:48:13.103Z,1676306893.103 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:48:13.104Z,1676306893.104 [Default:CheckIn](INFO): Running loop #2
2023-02-13T16:48:13.104Z,1676306893.104 [Default:CheckIn] Running Loop=2
2023-02-13T16:48:13.104Z,1676306893.104 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:48:13.104Z,1676306893.104 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:48:46.733Z,1676306926.733 [CommandExec](IMPORTANT): got command burn off
2023-02-13T16:48:46.733Z,1676306926.733 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-02-13T16:48:53.917Z,1676306933.917 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-02-13T16:48:53.917Z,1676306933.917 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T16:48:53.927Z,1676306933.927 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T16:48:54.347Z,1676306934.347 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T16:48:54.347Z,1676306934.347 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-02-13T16:48:57.457Z,1676306937.457 [CommandExec](IMPORTANT): got command failComponent
2023-02-13T16:48:57.457Z,1676306937.457 [CommandExec](IMPORTANT): Failed components:
2023-02-13T16:48:57.457Z,1676306937.457 [CommandExec](IMPORTANT): No failed Components.
2023-02-13T16:50:42.141Z,1676307042.141 [CommandExec](IMPORTANT): got command failComponent
2023-02-13T16:50:42.141Z,1676307042.141 [CommandExec](IMPORTANT): Failed components:
2023-02-13T16:50:42.141Z,1676307042.141 [CommandExec](IMPORTANT): No failed Components.
2023-02-13T16:51:54.934Z,1676307114.934 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-02-13T16:51:54.934Z,1676307114.934 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T16:51:54.944Z,1676307114.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T16:51:55.339Z,1676307115.339 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T16:51:55.339Z,1676307115.339 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-02-13T16:53:13.320Z,1676307193.320 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-13T16:48:13.1Z
2023-02-13T16:53:13.320Z,1676307193.320 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:53:13.320Z,1676307193.320 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:53:20.491Z,1676307200.491 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230213T163338/Courier0007.lzma
2023-02-13T16:53:21.494Z,1676307201.494 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0007.lzma.bak
2023-02-13T16:53:21.494Z,1676307201.494 [DataOverHttps](INFO): SBD MOMSN=17464735
2023-02-13T16:53:36.865Z,1676307216.865 [DataOverHttps](INFO): Sending 726 bytes from file Logs/20230213T163338/Express0008.lzma
2023-02-13T16:53:37.866Z,1676307217.866 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0008.lzma.bak
2023-02-13T16:53:37.866Z,1676307217.866 [DataOverHttps](INFO): SBD MOMSN=17464738
2023-02-13T16:53:39.151Z,1676307219.151 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:53:39.151Z,1676307219.151 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:53:39.151Z,1676307219.151 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:54:55.949Z,1676307295.949 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-02-13T16:54:55.949Z,1676307295.949 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T16:54:55.968Z,1676307295.968 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T16:54:56.379Z,1676307296.379 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T16:54:56.379Z,1676307296.379 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-02-13T16:55:13.724Z,1676307313.724 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-13T16:55:13.724Z,1676307313.724 [NAL9602] Data Fault, FailCount= 2
2023-02-13T16:55:13.724Z,1676307313.724 [NAL9602](ERROR): Data Fault
2023-02-13T16:55:13.759Z,1676307313.759 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-13T16:55:14.122Z,1676307314.122 [NAL9602](INFO): Powering down
2023-02-13T16:55:14.984Z,1676307314.984 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-13T16:55:14.985Z,1676307314.985 [NAL9602] No Fault, FailCount= 2
2023-02-13T16:55:44.422Z,1676307344.422 [NAL9602](INFO): Powering up NAL9602
2023-02-13T16:55:55.353Z,1676307355.353 [NAL9602](INFO): NAL9602 initialized
2023-02-13T16:57:56.938Z,1676307476.938 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-02-13T16:57:56.938Z,1676307476.938 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T16:57:56.948Z,1676307476.948 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T16:57:57.363Z,1676307477.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T16:57:57.363Z,1676307477.363 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-02-13T16:58:39.778Z,1676307519.778 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:58:39.778Z,1676307519.778 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:58:39.778Z,1676307519.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:58:39.778Z,1676307519.778 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:58:40.202Z,1676307520.202 [Default:CheckIn:D] Stopped
2023-02-13T16:58:40.202Z,1676307520.202 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:58:40.619Z,1676307520.619 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.044771 min 
2023-02-13T16:58:40.619Z,1676307520.619 [Default:CheckIn:E] Stopped
2023-02-13T16:58:40.619Z,1676307520.619 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:58:40.619Z,1676307520.619 [Default:CheckIn] Stopped
2023-02-13T16:58:40.619Z,1676307520.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:58:40.619Z,1676307520.619 [Default:CheckIn](INFO): Running loop #3
2023-02-13T16:58:40.619Z,1676307520.619 [Default:CheckIn] Running Loop=3
2023-02-13T16:58:40.619Z,1676307520.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:58:40.619Z,1676307520.619 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:00:58.019Z,1676307658.019 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-02-13T17:00:58.019Z,1676307658.019 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T17:00:58.030Z,1676307658.030 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T17:00:58.443Z,1676307658.443 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T17:00:58.443Z,1676307658.443 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-02-13T17:03:40.945Z,1676307820.945 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-13T16:58:40.6Z
2023-02-13T17:03:40.945Z,1676307820.945 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:03:40.945Z,1676307820.945 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:03:47.727Z,1676307827.727 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230213T163338/Courier0010.lzma
2023-02-13T17:03:48.730Z,1676307828.730 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0010.lzma.bak
2023-02-13T17:03:48.730Z,1676307828.730 [DataOverHttps](INFO): SBD MOMSN=17464769
2023-02-13T17:03:59.101Z,1676307839.101 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-02-13T17:03:59.101Z,1676307839.101 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T17:03:59.111Z,1676307839.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T17:03:59.554Z,1676307839.554 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T17:03:59.554Z,1676307839.554 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-02-13T17:04:04.083Z,1676307844.083 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230213T163338/Express0011.lzma
2023-02-13T17:04:05.086Z,1676307845.086 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0011.lzma.bak
2023-02-13T17:04:05.086Z,1676307845.086 [DataOverHttps](INFO): SBD MOMSN=17464773
2023-02-13T17:04:06.382Z,1676307846.382 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:04:06.382Z,1676307846.382 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:04:06.382Z,1676307846.382 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:05:57.470Z,1676307957.470 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-13T17:05:57.470Z,1676307957.470 [NAL9602] Data Fault, FailCount= 3
2023-02-13T17:05:57.470Z,1676307957.470 [NAL9602](ERROR): Data Fault
2023-02-13T17:05:57.515Z,1676307957.515 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-13T17:05:57.871Z,1676307957.871 [NAL9602](INFO): Powering down
2023-02-13T17:05:58.704Z,1676307958.704 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-13T17:05:58.704Z,1676307958.704 [NAL9602] No Fault, FailCount= 3
2023-02-13T17:06:28.182Z,1676307988.182 [NAL9602](INFO): Powering up NAL9602
2023-02-13T17:06:39.086Z,1676307999.086 [NAL9602](INFO): NAL9602 initialized
2023-02-13T17:07:00.097Z,1676308020.097 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-02-13T17:07:00.097Z,1676308020.097 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T17:07:00.132Z,1676308020.132 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T17:07:00.542Z,1676308020.542 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T17:07:00.542Z,1676308020.542 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-02-13T17:07:34.032Z,1676308054.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170732.00,A,3648.14164,N,12147.23334,W,0.311,76.53,130223,,,A*42
2023-02-13T17:07:34.035Z,1676308054.035 [NAL9602](INFO): GPS fix at 20230213T170732: (36.802361, -121.787222)
2023-02-13T17:08:06.348Z,1676308086.348 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:09:06.957Z,1676308146.957 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:09:06.957Z,1676308146.957 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:09:06.957Z,1676308146.957 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:09:06.958Z,1676308146.958 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:09:07.362Z,1676308147.362 [Default:CheckIn:D] Stopped
2023-02-13T17:09:07.362Z,1676308147.362 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:09:07.785Z,1676308147.785 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.497441 min 
2023-02-13T17:09:07.785Z,1676308147.785 [Default:CheckIn:E] Stopped
2023-02-13T17:09:07.785Z,1676308147.785 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:09:07.785Z,1676308147.785 [Default:CheckIn] Stopped
2023-02-13T17:09:07.785Z,1676308147.785 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:09:07.785Z,1676308147.785 [Default:CheckIn](INFO): Running loop #4
2023-02-13T17:09:07.785Z,1676308147.785 [Default:CheckIn] Running Loop=4
2023-02-13T17:09:07.785Z,1676308147.785 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:09:07.786Z,1676308147.786 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:09:09.778Z,1676308149.778 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170908.00,A,3648.14278,N,12147.22919,W,0.039,130.79,130223,,,D*78
2023-02-13T17:09:09.792Z,1676308149.792 [NAL9602](INFO): GPS fix at 20230213T170908: (36.802380, -121.787153)
2023-02-13T17:09:09.802Z,1676308149.802 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:09:09.803Z,1676308149.803 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:09:15.928Z,1676308155.928 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230213T163338/Courier0013.lzma
2023-02-13T17:09:16.930Z,1676308156.930 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0013.lzma.bak
2023-02-13T17:09:16.930Z,1676308156.930 [DataOverHttps](INFO): SBD MOMSN=17464782
2023-02-13T17:09:29.171Z,1676308169.171 [NAL9602](INFO): SBD MO Status=2, MOMSN=38892, MT Status=2, MTMSN=0
2023-02-13T17:09:29.171Z,1676308169.171 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T17:09:32.704Z,1676308172.704 [DataOverHttps](INFO): Sending 303 bytes from file Logs/20230213T163338/Express0014.lzma
2023-02-13T17:09:33.706Z,1676308173.706 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0014.lzma.bak
2023-02-13T17:09:33.706Z,1676308173.706 [DataOverHttps](INFO): SBD MOMSN=17464785
2023-02-13T17:09:34.834Z,1676308174.834 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:09:34.834Z,1676308174.834 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:09:34.835Z,1676308174.835 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:09:56.650Z,1676308196.650 [NAL9602](INFO): SBD MO Status=2, MOMSN=38892, MT Status=2, MTMSN=0
2023-02-13T17:09:56.650Z,1676308196.650 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T17:10:13.217Z,1676308213.217 [NAL9602](INFO): SBD MO Status=2, MOMSN=38892, MT Status=2, MTMSN=0
2023-02-13T17:10:13.218Z,1676308213.218 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T17:11:04.115Z,1676308264.115 [NAL9602](INFO): SBD MO Status=0, MOMSN=38892, MT Status=0, MTMSN=0
2023-02-13T17:11:04.115Z,1676308264.115 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:11:34.812Z,1676308294.812 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:12:14.649Z,1676308334.649 [DataOverHttps](IMPORTANT): SBD MTMSN=20230213T171213
2023-02-13T17:12:24.524Z,1676308344.524 [DataOverHttps](INFO): Received command: ibit
2023-02-13T17:12:24.526Z,1676308344.526 [CommandExec](IMPORTANT): got command ibit
2023-02-13T17:12:24.612Z,1676308344.612 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-02-13T17:12:24.612Z,1676308344.612 [IBIT](IMPORTANT): Beginning control surface checks.
2023-02-13T17:12:24.615Z,1676308344.615 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T17:12:26.175Z,1676308346.175 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171225.00,A,3648.14137,N,12147.22985,W,0.097,130.79,130223,,,D*74
2023-02-13T17:12:26.178Z,1676308346.178 [NAL9602](INFO): GPS fix at 20230213T171225: (36.802356, -121.787164)
2023-02-13T17:12:27.569Z,1676308347.569 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-02-13T17:12:35.515Z,1676308355.515 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-02-13T17:12:35.896Z,1676308355.896 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T17:12:38.724Z,1676308358.724 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-02-13T17:12:43.555Z,1676308363.555 [NAL9602](INFO): SBD MO Status=0, MOMSN=38893, MT Status=0, MTMSN=0
2023-02-13T17:12:43.555Z,1676308363.555 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:12:44.762Z,1676308364.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171244.00,A,3648.14090,N,12147.22988,W,0.117,130.79,130223,,,D*7B
2023-02-13T17:12:44.764Z,1676308364.764 [NAL9602](INFO): GPS fix at 20230213T171244: (36.802348, -121.787165)
2023-02-13T17:12:46.881Z,1676308366.881 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-02-13T17:12:47.329Z,1676308367.329 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T17:12:47.994Z,1676308367.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171247.00,A,3648.14099,N,12147.23020,W,0.156,130.79,130223,,,D*7E
2023-02-13T17:12:47.996Z,1676308367.996 [NAL9602](INFO): GPS fix at 20230213T171247: (36.802350, -121.787170)
2023-02-13T17:12:50.190Z,1676308370.190 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-02-13T17:12:50.868Z,1676308370.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171250.00,A,3648.14117,N,12147.23060,W,0.019,130.79,130223,,,D*71
2023-02-13T17:12:50.870Z,1676308370.870 [NAL9602](INFO): GPS fix at 20230213T171250: (36.802353, -121.787177)
2023-02-13T17:12:54.138Z,1676308374.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171253.00,A,3648.14130,N,12147.23084,W,0.058,130.79,130223,,,D*78
2023-02-13T17:12:54.140Z,1676308374.140 [NAL9602](INFO): GPS fix at 20230213T171253: (36.802355, -121.787181)
2023-02-13T17:12:56.966Z,1676308376.966 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171256.00,A,3648.14138,N,12147.23091,W,0.039,130.79,130223,,,D*76
2023-02-13T17:12:56.969Z,1676308376.969 [NAL9602](INFO): GPS fix at 20230213T171256: (36.802356, -121.787182)
2023-02-13T17:12:58.317Z,1676308378.317 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-02-13T17:12:58.317Z,1676308378.317 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-02-13T17:12:59.803Z,1676308379.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171259.00,A,3648.14146,N,12147.23096,W,0.058,130.79,130223,,,D*70
2023-02-13T17:12:59.813Z,1676308379.813 [NAL9602](INFO): GPS fix at 20230213T171259: (36.802358, -121.787183)
2023-02-13T17:13:03.036Z,1676308383.036 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171302.00,A,3648.14161,N,12147.23110,W,0.039,130.79,130223,,,D*72
2023-02-13T17:13:03.038Z,1676308383.038 [NAL9602](INFO): GPS fix at 20230213T171302: (36.802360, -121.787185)
2023-02-13T17:13:05.862Z,1676308385.862 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171305.00,A,3648.14169,N,12147.23121,W,0.039,130.79,130223,,,D*7F
2023-02-13T17:13:05.864Z,1676308385.864 [NAL9602](INFO): GPS fix at 20230213T171305: (36.802362, -121.787187)
2023-02-13T17:13:09.095Z,1676308389.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171308.00,A,3648.14162,N,12147.23121,W,0.058,130.79,130223,,,D*7E
2023-02-13T17:13:09.098Z,1676308389.098 [NAL9602](INFO): GPS fix at 20230213T171308: (36.802360, -121.787187)
2023-02-13T17:13:10.773Z,1676308390.773 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802361 Longitude: -121.787186
2023-02-13T17:13:11.129Z,1676308391.129 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.572001
2023-02-13T17:13:11.130Z,1676308391.130 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-02-13T17:13:11.130Z,1676308391.130 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-02-13T17:13:11.539Z,1676308391.539 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-02-13T17:13:11.540Z,1676308391.540 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-02-13T17:13:11.540Z,1676308391.540 [IBIT](IMPORTANT): Pressure:9.851913 PSI
2023-02-13T17:13:11.541Z,1676308391.541 [IBIT](IMPORTANT): Humidity:0.317422 %
2023-02-13T17:13:11.955Z,1676308391.955 [IBIT](IMPORTANT): Vehicle Pitch:-0.202204 degrees
2023-02-13T17:13:11.955Z,1676308391.955 [IBIT](IMPORTANT): Vehicle  Roll:-4.452862 degrees
2023-02-13T17:13:11.955Z,1676308391.955 [IBIT](IMPORTANT): Vehicle  Heading:3.751031 degrees
2023-02-13T17:13:12.340Z,1676308392.340 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-02-13T17:13:12.340Z,1676308392.340 [IBIT](IMPORTANT): buoyancyNeutral: 169.635300 cc
2023-02-13T17:13:12.341Z,1676308392.341 [IBIT](IMPORTANT): massDefault: -0.814133 cm
2023-02-13T17:13:12.341Z,1676308392.341 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-02-13T17:13:12.341Z,1676308392.341 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-02-13T17:13:12.341Z,1676308392.341 [IBIT](IMPORTANT): IBIT PASSED
2023-02-13T17:13:41.417Z,1676308421.417 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:14:35.619Z,1676308475.619 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:14:35.619Z,1676308475.619 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:14:35.619Z,1676308475.619 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:14:35.619Z,1676308475.619 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:14:36.018Z,1676308476.018 [Default:CheckIn:D] Stopped
2023-02-13T17:14:36.018Z,1676308476.018 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:14:36.441Z,1676308476.441 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.975041 min 
2023-02-13T17:14:36.441Z,1676308476.441 [Default:CheckIn:E] Stopped
2023-02-13T17:14:36.441Z,1676308476.441 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:14:36.441Z,1676308476.441 [Default:CheckIn] Stopped
2023-02-13T17:14:36.441Z,1676308476.441 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:14:36.441Z,1676308476.441 [Default:CheckIn](INFO): Running loop #5
2023-02-13T17:14:36.441Z,1676308476.441 [Default:CheckIn] Running Loop=5
2023-02-13T17:14:36.442Z,1676308476.442 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:14:36.442Z,1676308476.442 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:14:38.439Z,1676308478.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171437.00,A,3648.14159,N,12147.22907,W,0.058,130.79,130223,,,D*70
2023-02-13T17:14:38.442Z,1676308478.442 [NAL9602](INFO): GPS fix at 20230213T171437: (36.802360, -121.787151)
2023-02-13T17:14:38.472Z,1676308478.472 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:14:38.472Z,1676308478.472 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:14:46.175Z,1676308486.175 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T163338/Courier0016.lzma
2023-02-13T17:14:47.178Z,1676308487.178 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0016.lzma.bak
2023-02-13T17:14:47.178Z,1676308487.178 [DataOverHttps](INFO): SBD MOMSN=17464808
2023-02-13T17:14:47.727Z,1676308487.727 [NAL9602](INFO): SBD MO Status=0, MOMSN=38894, MT Status=0, MTMSN=0
2023-02-13T17:14:47.727Z,1676308487.727 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:15:05.449Z,1676308505.449 [DataOverHttps](INFO): Sending 756 bytes from file Logs/20230213T163338/Express0017.lzma
2023-02-13T17:15:06.450Z,1676308506.450 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0017.lzma.bak
2023-02-13T17:15:06.450Z,1676308506.450 [DataOverHttps](INFO): SBD MOMSN=17464811
2023-02-13T17:15:18.470Z,1676308518.470 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:15:22.383Z,1676308522.383 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230213T163338/Express0020.lzma
2023-02-13T17:15:23.386Z,1676308523.386 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0020.lzma.bak
2023-02-13T17:15:23.386Z,1676308523.386 [DataOverHttps](INFO): SBD MOMSN=17464843
2023-02-13T17:15:24.539Z,1676308524.539 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:15:24.539Z,1676308524.539 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:15:24.539Z,1676308524.539 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:20:25.146Z,1676308825.146 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:20:25.146Z,1676308825.146 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:20:25.146Z,1676308825.146 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:20:25.146Z,1676308825.146 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:20:25.537Z,1676308825.537 [Default:CheckIn:D] Stopped
2023-02-13T17:20:25.537Z,1676308825.537 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:20:25.941Z,1676308825.941 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.800350 min 
2023-02-13T17:20:25.941Z,1676308825.941 [Default:CheckIn:E] Stopped
2023-02-13T17:20:25.941Z,1676308825.941 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:20:25.941Z,1676308825.941 [Default:CheckIn] Stopped
2023-02-13T17:20:25.941Z,1676308825.941 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:20:25.941Z,1676308825.941 [Default:CheckIn](INFO): Running loop #6
2023-02-13T17:20:25.941Z,1676308825.941 [Default:CheckIn] Running Loop=6
2023-02-13T17:20:25.941Z,1676308825.941 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:20:25.941Z,1676308825.941 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:20:27.947Z,1676308827.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172027.00,A,3648.14125,N,12147.22965,W,0.019,130.79,130223,,,D*7C
2023-02-13T17:20:27.950Z,1676308827.950 [NAL9602](INFO): GPS fix at 20230213T172027: (36.802354, -121.787161)
2023-02-13T17:20:27.960Z,1676308827.960 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:20:27.960Z,1676308827.960 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:20:35.767Z,1676308835.767 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T163338/Courier0022.lzma
2023-02-13T17:20:36.770Z,1676308836.770 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0022.lzma.bak
2023-02-13T17:20:36.770Z,1676308836.770 [DataOverHttps](INFO): SBD MOMSN=17464852
2023-02-13T17:20:55.456Z,1676308855.456 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230213T163338/Express0023.lzma
2023-02-13T17:20:56.458Z,1676308856.458 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0023.lzma.bak
2023-02-13T17:20:56.458Z,1676308856.458 [DataOverHttps](INFO): SBD MOMSN=17464863
2023-02-13T17:20:57.552Z,1676308857.552 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:20:57.552Z,1676308857.552 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:20:57.552Z,1676308857.552 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:21:46.801Z,1676308906.801 [NAL9602](INFO): SBD MO Status=0, MOMSN=38895, MT Status=0, MTMSN=0
2023-02-13T17:21:46.801Z,1676308906.801 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:22:17.485Z,1676308937.485 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:25:58.109Z,1676309158.109 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:25:58.109Z,1676309158.109 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:25:58.109Z,1676309158.109 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:25:58.109Z,1676309158.109 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:25:58.514Z,1676309158.514 [Default:CheckIn:D] Stopped
2023-02-13T17:25:58.514Z,1676309158.514 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:25:58.929Z,1676309158.929 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.349967 min 
2023-02-13T17:25:58.929Z,1676309158.929 [Default:CheckIn:E] Stopped
2023-02-13T17:25:58.929Z,1676309158.929 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:25:58.929Z,1676309158.929 [Default:CheckIn] Stopped
2023-02-13T17:25:58.929Z,1676309158.929 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:25:58.929Z,1676309158.929 [Default:CheckIn](INFO): Running loop #7
2023-02-13T17:25:58.929Z,1676309158.929 [Default:CheckIn] Running Loop=7
2023-02-13T17:25:58.930Z,1676309158.930 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:25:58.930Z,1676309158.930 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:26:00.935Z,1676309160.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172600.00,A,3648.13988,N,12147.22992,W,0.039,130.79,130223,,,D*7D
2023-02-13T17:26:00.947Z,1676309160.947 [NAL9602](INFO): GPS fix at 20230213T172600: (36.802331, -121.787165)
2023-02-13T17:26:00.957Z,1676309160.957 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:26:00.957Z,1676309160.957 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:26:08.755Z,1676309168.755 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T163338/Courier0025.lzma
2023-02-13T17:26:09.758Z,1676309169.758 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0025.lzma.bak
2023-02-13T17:26:09.758Z,1676309169.758 [DataOverHttps](INFO): SBD MOMSN=17464876
2023-02-13T17:26:16.687Z,1676309176.687 [NAL9602](INFO): SBD MO Status=0, MOMSN=38896, MT Status=0, MTMSN=0
2023-02-13T17:26:16.687Z,1676309176.687 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:26:28.388Z,1676309188.388 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230213T163338/Express0026.lzma
2023-02-13T17:26:29.390Z,1676309189.390 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0026.lzma.bak
2023-02-13T17:26:29.390Z,1676309189.390 [DataOverHttps](INFO): SBD MOMSN=17464882
2023-02-13T17:26:30.495Z,1676309190.495 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:26:30.495Z,1676309190.495 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:26:30.495Z,1676309190.495 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:26:47.449Z,1676309207.449 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:31:31.091Z,1676309491.091 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:31:31.091Z,1676309491.091 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:31:31.091Z,1676309491.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:31:31.091Z,1676309491.091 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:31:31.473Z,1676309491.473 [Default:CheckIn:D] Stopped
2023-02-13T17:31:31.473Z,1676309491.473 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:31:31.884Z,1676309491.884 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.899292 min 
2023-02-13T17:31:31.884Z,1676309491.884 [Default:CheckIn:E] Stopped
2023-02-13T17:31:31.885Z,1676309491.885 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:31:31.885Z,1676309491.885 [Default:CheckIn] Stopped
2023-02-13T17:31:31.885Z,1676309491.885 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:31:31.885Z,1676309491.885 [Default:CheckIn](INFO): Running loop #8
2023-02-13T17:31:31.885Z,1676309491.885 [Default:CheckIn] Running Loop=8
2023-02-13T17:31:31.885Z,1676309491.885 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:31:31.885Z,1676309491.885 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:31:33.896Z,1676309493.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173133.00,A,3648.14065,N,12147.23005,W,0.019,130.79,130223,,,D*72
2023-02-13T17:31:33.898Z,1676309493.898 [NAL9602](INFO): GPS fix at 20230213T173133: (36.802344, -121.787167)
2023-02-13T17:31:33.908Z,1676309493.908 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:31:33.908Z,1676309493.908 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:31:40.900Z,1676309500.900 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T163338/Courier0028.lzma
2023-02-13T17:31:41.902Z,1676309501.902 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0028.lzma.bak
2023-02-13T17:31:41.902Z,1676309501.902 [DataOverHttps](INFO): SBD MOMSN=17464905
2023-02-13T17:31:47.223Z,1676309507.223 [NAL9602](INFO): SBD MO Status=0, MOMSN=38897, MT Status=0, MTMSN=0
2023-02-13T17:31:47.223Z,1676309507.223 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:31:57.712Z,1676309517.712 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230213T163338/Express0029.lzma
2023-02-13T17:31:58.714Z,1676309518.714 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0029.lzma.bak
2023-02-13T17:31:58.714Z,1676309518.714 [DataOverHttps](INFO): SBD MOMSN=17464911
2023-02-13T17:31:59.761Z,1676309519.761 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:31:59.761Z,1676309519.761 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:31:59.761Z,1676309519.761 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:32:17.928Z,1676309537.928 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:33:38.417Z,1676309618.417 [DataOverHttps](IMPORTANT): SBD MTMSN=20230213T173337
2023-02-13T17:33:45.732Z,1676309625.732 [DataOverHttps](INFO): Received command: gfscan
2023-02-13T17:33:45.805Z,1676309625.805 [CommandExec](IMPORTANT): got command gfscan
2023-02-13T17:33:46.040Z,1676309626.040 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T17:33:48.865Z,1676309628.865 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-02-13T17:33:56.932Z,1676309636.932 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002455
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002579
CHAN A3 (5V): -0.001307
CHAN B0 (3.3V): -0.000513
CHAN B1 (3.15aV): -0.000039
CHAN B2 (3.15bV): -0.000626
CHAN B3 (GND): -0.000188
OPEN: -0.000563
Full Scale: +/- 1 mA
2023-02-13T17:37:00.353Z,1676309820.353 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:37:00.353Z,1676309820.353 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:37:00.353Z,1676309820.353 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:37:00.353Z,1676309820.353 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:37:00.755Z,1676309820.755 [Default:CheckIn:D] Stopped
2023-02-13T17:37:00.755Z,1676309820.755 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:37:01.170Z,1676309821.170 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.387321 min 
2023-02-13T17:37:01.170Z,1676309821.170 [Default:CheckIn:E] Stopped
2023-02-13T17:37:01.170Z,1676309821.170 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:37:01.170Z,1676309821.170 [Default:CheckIn] Stopped
2023-02-13T17:37:01.170Z,1676309821.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:37:01.170Z,1676309821.170 [Default:CheckIn](INFO): Running loop #9
2023-02-13T17:37:01.170Z,1676309821.170 [Default:CheckIn] Running Loop=9
2023-02-13T17:37:01.171Z,1676309821.171 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:37:01.171Z,1676309821.171 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:37:03.175Z,1676309823.175 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173702.00,A,3648.14221,N,12147.23141,W,0.058,130.79,130223,,,D*70
2023-02-13T17:37:03.178Z,1676309823.178 [NAL9602](INFO): GPS fix at 20230213T173702: (36.802370, -121.787190)
2023-02-13T17:37:03.188Z,1676309823.188 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:37:03.188Z,1676309823.188 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:37:10.355Z,1676309830.355 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T163338/Courier0031.lzma
2023-02-13T17:37:11.358Z,1676309831.358 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0031.lzma.bak
2023-02-13T17:37:11.358Z,1676309831.358 [DataOverHttps](INFO): SBD MOMSN=17464924
2023-02-13T17:37:13.674Z,1676309833.674 [NAL9602](INFO): SBD MO Status=0, MOMSN=38898, MT Status=0, MTMSN=0
2023-02-13T17:37:13.674Z,1676309833.674 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:37:27.132Z,1676309847.132 [DataOverHttps](INFO): Sending 363 bytes from file Logs/20230213T163338/Express0032.lzma
2023-02-13T17:37:28.134Z,1676309848.134 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0032.lzma.bak
2023-02-13T17:37:28.134Z,1676309848.134 [DataOverHttps](INFO): SBD MOMSN=17464930
2023-02-13T17:37:29.438Z,1676309849.438 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:37:29.438Z,1676309849.438 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:37:29.438Z,1676309849.438 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:37:44.381Z,1676309864.381 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:38:54.881Z,1676309934.881 [CommandExec](IMPORTANT): got command failComponent
2023-02-13T17:38:54.881Z,1676309934.881 [CommandExec](IMPORTANT): Failed components:
2023-02-13T17:38:54.881Z,1676309934.881 [CommandExec](IMPORTANT): No failed Components.
2023-02-13T17:42:30.080Z,1676310150.080 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:42:30.080Z,1676310150.080 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:42:30.080Z,1676310150.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:42:30.080Z,1676310150.080 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:42:30.475Z,1676310150.475 [Default:CheckIn:D] Stopped
2023-02-13T17:42:30.475Z,1676310150.475 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:42:30.874Z,1676310150.874 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.882654 min 
2023-02-13T17:42:30.874Z,1676310150.874 [Default:CheckIn:E] Stopped
2023-02-13T17:42:30.874Z,1676310150.874 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:42:30.874Z,1676310150.874 [Default:CheckIn] Stopped
2023-02-13T17:42:30.874Z,1676310150.874 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:42:30.875Z,1676310150.875 [Default:CheckIn](INFO): Running loop #10
2023-02-13T17:42:30.875Z,1676310150.875 [Default:CheckIn] Running Loop=10
2023-02-13T17:42:30.875Z,1676310150.875 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:42:30.875Z,1676310150.875 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:42:32.895Z,1676310152.895 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174232.00,A,3648.14139,N,12147.22979,W,0.019,130.79,130223,,,D*7C
2023-02-13T17:42:32.898Z,1676310152.898 [NAL9602](INFO): GPS fix at 20230213T174232: (36.802357, -121.787163)
2023-02-13T17:42:32.908Z,1676310152.908 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:42:32.908Z,1676310152.908 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:42:40.439Z,1676310160.439 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230213T163338/Courier0034.lzma
2023-02-13T17:42:41.444Z,1676310161.444 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0034.lzma.bak
2023-02-13T17:42:41.444Z,1676310161.444 [DataOverHttps](INFO): SBD MOMSN=17464951
2023-02-13T17:42:51.087Z,1676310171.087 [NAL9602](INFO): SBD MO Status=0, MOMSN=38899, MT Status=0, MTMSN=0
2023-02-13T17:42:51.088Z,1676310171.088 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:43:00.428Z,1676310180.428 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20230213T163338/Express0036.lzma
2023-02-13T17:43:01.430Z,1676310181.430 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0036.lzma.bak
2023-02-13T17:43:01.430Z,1676310181.430 [DataOverHttps](INFO): SBD MOMSN=17464957
2023-02-13T17:43:02.472Z,1676310182.472 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:43:02.472Z,1676310182.472 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:43:02.472Z,1676310182.472 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:43:21.820Z,1676310201.820 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:44:10.914Z,1676310250.914 [DataOverHttps](IMPORTANT): SBD MTMSN=20230213T174410
2023-02-13T17:44:19.232Z,1676310259.232 [DataOverHttps](INFO): Received command: strobe off
2023-02-13T17:44:19.281Z,1676310259.281 [CommandExec](IMPORTANT): got command strobe off
2023-02-13T17:44:19.282Z,1676310259.282 [CommandExec](IMPORTANT): Deactivating strobe
2023-02-13T17:46:52.883Z,1676310412.883 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2023-02-13T17:46:53.208Z,1676310413.208 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T17:46:56.439Z,1676310416.439 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-13T17:47:07.346Z,1676310427.346 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-13T17:47:08.561Z,1676310428.561 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T17:47:18.659Z,1676310438.659 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-13T17:47:23.506Z,1676310443.506 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T17:47:29.568Z,1676310449.568 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-13T17:47:38.050Z,1676310458.050 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T17:47:53.403Z,1676310473.403 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T17:48:03.110Z,1676310483.110 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:48:03.110Z,1676310483.110 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:48:03.110Z,1676310483.110 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:48:03.110Z,1676310483.110 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:48:03.530Z,1676310483.530 [Default:CheckIn:D] Stopped
2023-02-13T17:48:03.530Z,1676310483.530 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:48:03.919Z,1676310483.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.433569 min 
2023-02-13T17:48:03.920Z,1676310483.920 [Default:CheckIn:E] Stopped
2023-02-13T17:48:03.920Z,1676310483.920 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:48:03.936Z,1676310483.936 [Default:CheckIn] Stopped
2023-02-13T17:48:03.936Z,1676310483.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:48:03.936Z,1676310483.936 [Default:CheckIn](INFO): Running loop #11
2023-02-13T17:48:03.936Z,1676310483.936 [Default:CheckIn] Running Loop=11
2023-02-13T17:48:03.937Z,1676310483.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:48:03.937Z,1676310483.937 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:48:05.934Z,1676310485.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174805.00,A,3648.14169,N,12147.23090,W,0.019,0.00,130223,,,D*74
2023-02-13T17:48:05.937Z,1676310485.937 [NAL9602](INFO): GPS fix at 20230213T174805: (36.802362, -121.787182)
2023-02-13T17:48:05.947Z,1676310485.947 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:48:05.947Z,1676310485.947 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:48:09.161Z,1676310489.161 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T17:48:13.255Z,1676310493.255 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T163338/Courier0040.lzma
2023-02-13T17:48:14.258Z,1676310494.258 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0040.lzma.bak
2023-02-13T17:48:14.258Z,1676310494.258 [DataOverHttps](INFO): SBD MOMSN=17464979
2023-02-13T17:48:20.519Z,1676310500.519 [NAL9602](INFO): SBD MO Status=0, MOMSN=38900, MT Status=0, MTMSN=0
2023-02-13T17:48:20.519Z,1676310500.519 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:48:24.162Z,1676310504.162 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T17:48:33.152Z,1676310513.152 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230213T163338/Express0041.lzma
2023-02-13T17:48:34.154Z,1676310514.154 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0041.lzma.bak
2023-02-13T17:48:34.154Z,1676310514.154 [DataOverHttps](INFO): SBD MOMSN=17464985
2023-02-13T17:48:39.521Z,1676310519.521 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T17:48:51.270Z,1676310531.270 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:48:51.695Z,1676310531.695 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230213T163338/Express0044.lzma
2023-02-13T17:48:52.698Z,1676310532.698 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0044.lzma.bak
2023-02-13T17:48:52.698Z,1676310532.698 [DataOverHttps](INFO): SBD MOMSN=17464999
2023-02-13T17:48:53.737Z,1676310533.737 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:48:53.737Z,1676310533.737 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:48:53.737Z,1676310533.737 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:48:55.306Z,1676310535.306 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-13T17:49:09.866Z,1676310549.866 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-02-13T17:49:19.570Z,1676310559.570 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2023-02-13T17:49:19.573Z,1676310559.573 [BPC1](INFO): Received data from all battery sticks.
2023-02-13T17:53:54.300Z,1676310834.300 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:53:54.301Z,1676310834.301 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:53:54.301Z,1676310834.301 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:53:54.301Z,1676310834.301 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:53:54.698Z,1676310834.698 [Default:CheckIn:D] Stopped
2023-02-13T17:53:54.698Z,1676310834.698 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:53:55.104Z,1676310835.104 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.286361 min 
2023-02-13T17:53:55.104Z,1676310835.104 [Default:CheckIn:E] Stopped
2023-02-13T17:53:55.104Z,1676310835.104 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:53:55.104Z,1676310835.104 [Default:CheckIn] Stopped
2023-02-13T17:53:55.105Z,1676310835.105 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:53:55.105Z,1676310835.105 [Default:CheckIn](INFO): Running loop #12
2023-02-13T17:53:55.105Z,1676310835.105 [Default:CheckIn] Running Loop=12
2023-02-13T17:53:55.105Z,1676310835.105 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:53:55.105Z,1676310835.105 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:53:57.115Z,1676310837.115 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175356.00,A,3648.14957,N,12147.22389,W,1.497,29.02,130223,,,D*4D
2023-02-13T17:53:57.118Z,1676310837.118 [NAL9602](INFO): GPS fix at 20230213T175356: (36.802493, -121.787065)
2023-02-13T17:53:57.147Z,1676310837.147 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:53:57.147Z,1676310837.147 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:54:04.439Z,1676310844.439 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20230213T163338/Courier0046.lzma
2023-02-13T17:54:05.442Z,1676310845.442 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0046.lzma.bak
2023-02-13T17:54:05.442Z,1676310845.442 [DataOverHttps](INFO): SBD MOMSN=17465014
2023-02-13T17:54:13.274Z,1676310853.274 [NAL9602](INFO): SBD MO Status=0, MOMSN=38901, MT Status=0, MTMSN=0
2023-02-13T17:54:13.274Z,1676310853.274 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:54:23.912Z,1676310863.912 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230213T163338/Express0047.lzma
2023-02-13T17:54:24.914Z,1676310864.914 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0047.lzma.bak
2023-02-13T17:54:24.914Z,1676310864.914 [DataOverHttps](INFO): SBD MOMSN=17465021
2023-02-13T17:54:26.254Z,1676310866.254 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:54:26.254Z,1676310866.254 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:54:26.254Z,1676310866.254 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:54:44.020Z,1676310884.020 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:59:26.846Z,1676311166.846 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:59:26.846Z,1676311166.846 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:59:26.846Z,1676311166.846 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:59:26.846Z,1676311166.846 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:59:27.252Z,1676311167.252 [Default:CheckIn:D] Stopped
2023-02-13T17:59:27.252Z,1676311167.252 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:59:27.669Z,1676311167.669 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.828939 min 
2023-02-13T17:59:27.669Z,1676311167.669 [Default:CheckIn:E] Stopped
2023-02-13T17:59:27.669Z,1676311167.669 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:59:27.669Z,1676311167.669 [Default:CheckIn] Stopped
2023-02-13T17:59:27.669Z,1676311167.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:59:27.670Z,1676311167.670 [Default:CheckIn](INFO): Running loop #13
2023-02-13T17:59:27.670Z,1676311167.670 [Default:CheckIn] Running Loop=13
2023-02-13T17:59:27.670Z,1676311167.670 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:59:27.670Z,1676311167.670 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:59:29.671Z,1676311169.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175928.00,A,3648.15443,N,12147.22008,W,0.078,52.87,130223,,,D*48
2023-02-13T17:59:29.674Z,1676311169.674 [NAL9602](INFO): GPS fix at 20230213T175928: (36.802574, -121.787001)
2023-02-13T17:59:29.684Z,1676311169.684 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:59:29.684Z,1676311169.684 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:59:37.075Z,1676311177.075 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T163338/Courier0049.lzma
2023-02-13T17:59:38.078Z,1676311178.078 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0049.lzma.bak
2023-02-13T17:59:38.078Z,1676311178.078 [DataOverHttps](INFO): SBD MOMSN=17465058
2023-02-13T17:59:53.908Z,1676311193.908 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230213T163338/Express0050.lzma
2023-02-13T17:59:54.910Z,1676311194.910 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0050.lzma.bak
2023-02-13T17:59:54.910Z,1676311194.910 [DataOverHttps](INFO): SBD MOMSN=17465061
2023-02-13T17:59:55.951Z,1676311195.951 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:59:55.951Z,1676311195.951 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:59:55.951Z,1676311195.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:00:06.435Z,1676311206.435 [NAL9602](INFO): SBD MO Status=0, MOMSN=38902, MT Status=0, MTMSN=0
2023-02-13T18:00:06.435Z,1676311206.435 [NAL9602](INFO): No messages in MT queue
2023-02-13T18:00:37.132Z,1676311237.132 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:04:56.535Z,1676311496.535 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:04:56.535Z,1676311496.535 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:04:56.535Z,1676311496.535 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:04:56.535Z,1676311496.535 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:04:56.934Z,1676311496.934 [Default:CheckIn:D] Stopped
2023-02-13T18:04:56.934Z,1676311496.934 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:04:57.339Z,1676311497.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.323625 min 
2023-02-13T18:04:57.339Z,1676311497.339 [Default:CheckIn:E] Stopped
2023-02-13T18:04:57.340Z,1676311497.340 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:04:57.340Z,1676311497.340 [Default:CheckIn] Stopped
2023-02-13T18:04:57.340Z,1676311497.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:04:57.340Z,1676311497.340 [Default:CheckIn](INFO): Running loop #14
2023-02-13T18:04:57.340Z,1676311497.340 [Default:CheckIn] Running Loop=14
2023-02-13T18:04:57.340Z,1676311497.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:04:57.340Z,1676311497.340 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:04:59.355Z,1676311499.355 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180458.00,A,3648.31235,N,12147.12563,W,5.773,2.46,130223,,,D*73
2023-02-13T18:04:59.367Z,1676311499.367 [NAL9602](INFO): GPS fix at 20230213T180458: (36.805206, -121.785427)
2023-02-13T18:04:59.377Z,1676311499.377 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:04:59.378Z,1676311499.378 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:05:07.311Z,1676311507.311 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T163338/Courier0052.lzma
2023-02-13T18:05:08.314Z,1676311508.314 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0052.lzma.bak
2023-02-13T18:05:08.314Z,1676311508.314 [DataOverHttps](INFO): SBD MOMSN=17465082
2023-02-13T18:05:09.864Z,1676311509.864 [NAL9602](INFO): SBD MO Status=0, MOMSN=38903, MT Status=0, MTMSN=0
2023-02-13T18:05:09.864Z,1676311509.864 [NAL9602](INFO): No messages in MT queue
2023-02-13T18:05:24.160Z,1676311524.160 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20230213T163338/Express0053.lzma
2023-02-13T18:05:25.162Z,1676311525.162 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0053.lzma.bak
2023-02-13T18:05:25.163Z,1676311525.163 [DataOverHttps](INFO): SBD MOMSN=17465085
2023-02-13T18:05:26.431Z,1676311526.431 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:05:26.431Z,1676311526.431 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:05:26.432Z,1676311526.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:05:40.557Z,1676311540.557 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:10:27.007Z,1676311827.007 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:10:27.007Z,1676311827.007 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:10:27.007Z,1676311827.007 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:10:27.008Z,1676311827.008 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:10:27.418Z,1676311827.418 [Default:CheckIn:D] Stopped
2023-02-13T18:10:27.418Z,1676311827.418 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:10:27.814Z,1676311827.814 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.831698 min 
2023-02-13T18:10:27.814Z,1676311827.814 [Default:CheckIn:E] Stopped
2023-02-13T18:10:27.814Z,1676311827.814 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:10:27.814Z,1676311827.814 [Default:CheckIn] Stopped
2023-02-13T18:10:27.815Z,1676311827.815 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:10:27.815Z,1676311827.815 [Default:CheckIn](INFO): Running loop #15
2023-02-13T18:10:27.815Z,1676311827.815 [Default:CheckIn] Running Loop=15
2023-02-13T18:10:27.815Z,1676311827.815 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:10:27.815Z,1676311827.815 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:10:29.834Z,1676311829.834 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181029.00,A,3648.32788,N,12147.59513,W,8.786,266.24,130223,,,D*7B
2023-02-13T18:10:29.837Z,1676311829.837 [NAL9602](INFO): GPS fix at 20230213T181029: (36.805465, -121.793252)
2023-02-13T18:10:29.847Z,1676311829.847 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:10:29.847Z,1676311829.847 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:10:37.155Z,1676311837.155 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T163338/Courier0055.lzma
2023-02-13T18:10:38.158Z,1676311838.158 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0055.lzma.bak
2023-02-13T18:10:38.158Z,1676311838.158 [DataOverHttps](INFO): SBD MOMSN=17465096
2023-02-13T18:10:45.183Z,1676311845.183 [NAL9602](INFO): SBD MO Status=0, MOMSN=38904, MT Status=0, MTMSN=0
2023-02-13T18:10:45.183Z,1676311845.183 [NAL9602](INFO): No messages in MT queue
2023-02-13T18:10:56.636Z,1676311856.636 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230213T163338/Express0056.lzma
2023-02-13T18:10:57.638Z,1676311857.638 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0056.lzma.bak
2023-02-13T18:10:57.638Z,1676311857.638 [DataOverHttps](INFO): SBD MOMSN=17465099
2023-02-13T18:10:58.989Z,1676311858.989 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:10:58.989Z,1676311858.989 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:10:58.989Z,1676311858.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:11:15.942Z,1676311875.942 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:14:31.500Z,1676312071.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:15:33.045Z,1676312133.045 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:15:59.557Z,1676312159.557 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:15:59.557Z,1676312159.557 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:15:59.557Z,1676312159.557 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:15:59.557Z,1676312159.557 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:15:59.970Z,1676312159.970 [Default:CheckIn:D] Stopped
2023-02-13T18:15:59.970Z,1676312159.970 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:16:00.391Z,1676312160.391 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.374227 min 
2023-02-13T18:16:00.391Z,1676312160.391 [Default:CheckIn:E] Stopped
2023-02-13T18:16:00.391Z,1676312160.391 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:16:00.391Z,1676312160.391 [Default:CheckIn] Stopped
2023-02-13T18:16:00.391Z,1676312160.391 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:16:00.391Z,1676312160.391 [Default:CheckIn](INFO): Running loop #16
2023-02-13T18:16:00.391Z,1676312160.391 [Default:CheckIn] Running Loop=16
2023-02-13T18:16:00.392Z,1676312160.392 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:16:00.392Z,1676312160.392 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:16:02.379Z,1676312162.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181601.00,A,3648.54851,N,12148.92479,W,11.119,276.48,130223,,,D*4A
2023-02-13T18:16:02.382Z,1676312162.382 [NAL9602](INFO): GPS fix at 20230213T181601: (36.809142, -121.815413)
2023-02-13T18:16:02.392Z,1676312162.392 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:16:02.393Z,1676312162.393 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:16:04.172Z,1676312164.172 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:16:22.175Z,1676312182.175 [NAL9602](INFO): SBD MO Status=0, MOMSN=38905, MT Status=0, MTMSN=0
2023-02-13T18:16:22.175Z,1676312182.175 [NAL9602](INFO): No messages in MT queue
2023-02-13T18:16:35.296Z,1676312195.296 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:16:52.878Z,1676312212.878 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:17:06.421Z,1676312226.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:17:37.544Z,1676312257.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:17:48.589Z,1676312268.589 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739319
2023-02-13T18:18:18.979Z,1676312298.979 [NAL9602](INFO): SBD MO Status=2, MOMSN=38906, MT Status=2, MTMSN=0
2023-02-13T18:18:18.980Z,1676312298.980 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T18:18:19.728Z,1676312299.728 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:18:35.183Z,1676312315.183 [NAL9602](INFO): SBD MO Status=1, MOMSN=38906, MT Status=0, MTMSN=0
2023-02-13T18:18:35.232Z,1676312315.232 [NAL9602](INFO): Sent 73 bytes from file Logs/20230213T163338/Courier0058.lzma
2023-02-13T18:18:35.233Z,1676312315.233 [NAL9602](INFO): Packets left to send: 0
2023-02-13T18:18:44.111Z,1676312324.111 [NAL9602](INFO): SBD MO Status=1, MOMSN=38907, MT Status=0, MTMSN=0
2023-02-13T18:18:44.160Z,1676312324.160 [NAL9602](INFO): Sent 145 bytes from file Logs/20230213T163338/Express0059.lzma
2023-02-13T18:18:44.161Z,1676312324.161 [NAL9602](INFO): Packets left to send: 0
2023-02-13T18:18:50.852Z,1676312330.852 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:18:53.803Z,1676312333.803 [NAL9602](INFO): SBD MO Status=0, MOMSN=38908, MT Status=0, MTMSN=0
2023-02-13T18:18:53.945Z,1676312333.945 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:18:53.945Z,1676312333.945 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:18:53.945Z,1676312333.945 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:19:00.121Z,1676312340.121 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003229
2023-02-13T18:19:24.506Z,1676312364.506 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:21:23.737Z,1676312483.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:21:54.877Z,1676312514.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:22:26.000Z,1676312546.000 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:23:54.499Z,1676312634.499 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:23:54.499Z,1676312634.499 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:23:54.499Z,1676312634.499 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:23:54.499Z,1676312634.499 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:23:54.911Z,1676312634.911 [Default:CheckIn:D] Stopped
2023-02-13T18:23:54.911Z,1676312634.911 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:23:55.318Z,1676312635.318 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.289925 min 
2023-02-13T18:23:55.318Z,1676312635.318 [Default:CheckIn:E] Stopped
2023-02-13T18:23:55.319Z,1676312635.319 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:23:55.319Z,1676312635.319 [Default:CheckIn] Stopped
2023-02-13T18:23:55.319Z,1676312635.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:23:55.319Z,1676312635.319 [Default:CheckIn](INFO): Running loop #17
2023-02-13T18:23:55.319Z,1676312635.319 [Default:CheckIn] Running Loop=17
2023-02-13T18:23:55.319Z,1676312635.319 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:23:55.319Z,1676312635.319 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:23:57.315Z,1676312637.315 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182356.00,A,3648.25799,N,12149.74326,W,1.341,76.20,130223,,,D*45
2023-02-13T18:23:57.317Z,1676312637.317 [NAL9602](INFO): GPS fix at 20230213T182356: (36.804300, -121.829054)
2023-02-13T18:23:57.327Z,1676312637.327 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:23:57.327Z,1676312637.327 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:23:59.284Z,1676312639.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:24:29.291Z,1676312669.291 [NAL9602](INFO): SBD MO Status=0, MOMSN=38909, MT Status=0, MTMSN=0
2023-02-13T18:24:29.291Z,1676312669.291 [NAL9602](INFO): No messages in MT queue
2023-02-13T18:24:38.231Z,1676312678.231 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T163338/Courier0061.lzma
2023-02-13T18:24:39.234Z,1676312679.234 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Courier0061.lzma.bak
2023-02-13T18:24:39.234Z,1676312679.234 [DataOverHttps](INFO): SBD MOMSN=17465146
2023-02-13T18:24:57.764Z,1676312697.764 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230213T163338/Express0062.lzma
2023-02-13T18:24:58.772Z,1676312698.772 [DataOverHttps](INFO): Moved sent file to Logs/20230213T163338/Express0062.lzma.bak
2023-02-13T18:24:58.772Z,1676312698.772 [DataOverHttps](IMPORTANT): SBD MOMSN=17465151, MTMSN=20230213T182457
2023-02-13T18:25:00.113Z,1676312700.113 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:25:00.187Z,1676312700.187 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:25:00.187Z,1676312700.187 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:25:00.187Z,1676312700.187 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:25:06.252Z,1676312706.252 [DataOverHttps](INFO): Received command: restart logs