2022-02-23T17:25:45.090Z,1645637145.090 [Supervisor](DEBUG): Initializing supervisor.
2022-02-23T17:25:45.095Z,1645637145.095 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-02-23T17:25:45.095Z,1645637145.095 [SyncHandler](INFO): Protected caller Thread ID is 835
2022-02-23T17:25:45.096Z,1645637145.096 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-02-23T17:25:45.097Z,1645637145.097 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-02-23T17:25:45.097Z,1645637145.097 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2022-02-23T17:25:45.101Z,1645637145.101 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-02-23T17:25:45.120Z,1645637145.120 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-02-23T17:25:45.121Z,1645637145.121 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-02-23T17:25:45.121Z,1645637145.121 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2022-02-23T17:25:45.126Z,1645637145.126 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-02-23T17:25:45.127Z,1645637145.127 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-02-23T17:25:45.127Z,1645637145.127 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2022-02-23T17:25:45.129Z,1645637145.129 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-02-23T17:25:45.130Z,1645637145.130 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-02-23T17:25:45.131Z,1645637145.131 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2022-02-23T17:25:45.134Z,1645637145.134 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-02-23T17:25:45.135Z,1645637145.135 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-02-23T17:25:45.139Z,1645637145.139 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-02-23T17:25:45.366Z,1645637145.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-02-23T17:25:45.368Z,1645637145.368 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-02-23T17:25:45.448Z,1645637145.448 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-02-23T17:25:45.960Z,1645637145.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-02-23T17:25:45.961Z,1645637145.961 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-02-23T17:25:46.282Z,1645637146.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-02-23T17:25:46.283Z,1645637146.283 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-02-23T17:25:46.379Z,1645637146.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-02-23T17:25:46.381Z,1645637146.381 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-02-23T17:25:46.741Z,1645637146.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-02-23T17:25:46.743Z,1645637146.743 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-02-23T17:25:46.945Z,1645637146.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-02-23T17:25:46.946Z,1645637146.946 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-02-23T17:25:47.399Z,1645637147.399 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-02-23T17:25:47.400Z,1645637147.400 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-02-23T17:25:47.528Z,1645637147.528 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-02-23T17:25:47.529Z,1645637147.529 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-02-23T17:25:47.609Z,1645637147.609 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-02-23T17:25:48.941Z,1645637148.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-02-23T17:25:48.943Z,1645637148.943 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-02-23T17:25:49.300Z,1645637149.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-02-23T17:25:49.301Z,1645637149.301 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-02-23T17:25:49.488Z,1645637149.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-02-23T17:25:49.490Z,1645637149.490 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-02-23T17:25:49.599Z,1645637149.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-02-23T17:25:49.601Z,1645637149.601 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-02-23T17:25:49.751Z,1645637149.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-02-23T17:25:49.753Z,1645637149.753 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-02-23T17:25:49.978Z,1645637149.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-02-23T17:25:49.980Z,1645637149.980 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2022-02-23T17:25:49.983Z,1645637149.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2022-02-23T17:25:50.073Z,1645637150.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2022-02-23T17:25:50.159Z,1645637150.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2022-02-23T17:25:50.260Z,1645637150.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2022-02-23T17:25:50.343Z,1645637150.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2022-02-23T17:25:50.438Z,1645637150.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2022-02-23T17:25:50.541Z,1645637150.541 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2022-02-23T17:25:50.729Z,1645637150.729 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2022-02-23T17:25:50.806Z,1645637150.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2022-02-23T17:25:50.952Z,1645637150.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2022-02-23T17:25:51.089Z,1645637151.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2022-02-23T17:25:51.204Z,1645637151.204 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2022-02-23T17:25:51.470Z,1645637151.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-02-23T17:25:51.470Z,1645637151.470 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-02-23T17:25:51.484Z,1645637151.484 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-02-23T17:25:51.558Z,1645637151.558 [VerticalControl](DEBUG): Construct VerticalControl.
2022-02-23T17:25:51.623Z,1645637151.622 [VerticalControl] Loaded
2022-02-23T17:25:51.623Z,1645637151.623 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-02-23T17:25:51.626Z,1645637151.626 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-02-23T17:25:51.666Z,1645637151.666 [HorizontalControl] Loaded
2022-02-23T17:25:51.666Z,1645637151.666 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-02-23T17:25:51.668Z,1645637151.668 [SpeedControl](DEBUG): Construct SpeedControl.
2022-02-23T17:25:51.672Z,1645637151.672 [SpeedControl] Loaded
2022-02-23T17:25:51.672Z,1645637151.672 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-02-23T17:25:51.674Z,1645637151.674 [LoopControl](DEBUG): Construct LoopControl.
2022-02-23T17:25:51.675Z,1645637151.675 [LoopControl] Loaded
2022-02-23T17:25:51.675Z,1645637151.675 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-02-23T17:25:51.676Z,1645637151.676 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-02-23T17:25:51.678Z,1645637151.678 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-02-23T17:25:51.694Z,1645637151.694 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-02-23T17:25:51.695Z,1645637151.695 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-02-23T17:25:51.831Z,1645637151.831 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-02-23T17:25:51.832Z,1645637151.832 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-02-23T17:25:52.024Z,1645637152.024 [BuoyancyServo] Loaded
2022-02-23T17:25:52.024Z,1645637152.024 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-02-23T17:25:52.044Z,1645637152.044 [ElevatorServo] Loaded
2022-02-23T17:25:52.044Z,1645637152.044 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-02-23T17:25:52.063Z,1645637152.063 [MassServo] Loaded
2022-02-23T17:25:52.063Z,1645637152.063 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-02-23T17:25:52.081Z,1645637152.081 [RudderServo] Loaded
2022-02-23T17:25:52.082Z,1645637152.082 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-02-23T17:25:52.100Z,1645637152.100 [ThrusterServo] Loaded
2022-02-23T17:25:52.100Z,1645637152.100 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-02-23T17:25:52.100Z,1645637152.100 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-02-23T17:25:52.101Z,1645637152.101 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-02-23T17:25:52.203Z,1645637152.203 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-02-23T17:25:52.204Z,1645637152.204 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-02-23T17:25:52.217Z,1645637152.217 [NavChart] Loaded
2022-02-23T17:25:52.218Z,1645637152.218 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-02-23T17:25:52.224Z,1645637152.224 [UniversalFixResidualReporter] Loaded
2022-02-23T17:25:52.224Z,1645637152.224 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-02-23T17:25:52.224Z,1645637152.224 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-02-23T17:25:52.225Z,1645637152.225 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-02-23T17:25:52.310Z,1645637152.310 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-02-23T17:25:52.310Z,1645637152.310 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-02-23T17:25:53.017Z,1645637153.017 [AHRS_M2] Loaded
2022-02-23T17:25:53.018Z,1645637153.018 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-02-23T17:25:53.838Z,1645637153.838 [BPC1] Loaded
2022-02-23T17:25:53.838Z,1645637153.838 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-02-23T17:25:54.045Z,1645637154.045 [DataOverHttps] Loaded
2022-02-23T17:25:54.045Z,1645637154.045 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-02-23T17:25:54.055Z,1645637154.055 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2022-02-23T17:25:54.055Z,1645637154.055 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2022-02-23T17:25:54.085Z,1645637154.085 [Depth_Keller] Loaded
2022-02-23T17:25:54.085Z,1645637154.085 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-02-23T17:25:54.114Z,1645637154.114 [DropWeight] Loaded
2022-02-23T17:25:54.114Z,1645637154.114 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-02-23T17:25:54.202Z,1645637154.202 [NAL9602] Loaded
2022-02-23T17:25:54.203Z,1645637154.203 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-02-23T17:25:54.232Z,1645637154.232 [Onboard] Loaded
2022-02-23T17:25:54.233Z,1645637154.233 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-02-23T17:25:54.234Z,1645637154.234 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2022-02-23T17:25:54.234Z,1645637154.234 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927
2022-02-23T17:25:54.247Z,1645637154.247 [Power24vConverter] Loaded
2022-02-23T17:25:54.248Z,1645637154.248 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-02-23T17:25:54.261Z,1645637154.261 [Radio_Surface] Loaded
2022-02-23T17:25:54.261Z,1645637154.261 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-02-23T17:25:54.262Z,1645637154.262 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409264E0
2022-02-23T17:25:54.263Z,1645637154.263 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928
2022-02-23T17:25:54.264Z,1645637154.264 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-02-23T17:25:54.264Z,1645637154.264 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-02-23T17:25:54.485Z,1645637154.485 [DepthRateCalculator] Loaded
2022-02-23T17:25:54.485Z,1645637154.485 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-02-23T17:25:54.490Z,1645637154.490 [PitchRateCalculator] Loaded
2022-02-23T17:25:54.491Z,1645637154.491 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-02-23T17:25:54.522Z,1645637154.522 [SpeedCalculator] Loaded
2022-02-23T17:25:54.531Z,1645637154.531 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-02-23T17:25:54.609Z,1645637154.609 [TempGradientCalculator] Loaded
2022-02-23T17:25:54.609Z,1645637154.609 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-02-23T17:25:54.634Z,1645637154.634 [YawRateCalculator] Loaded
2022-02-23T17:25:54.634Z,1645637154.634 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-02-23T17:25:54.718Z,1645637154.718 [ElevatorOffsetCalculator] Loaded
2022-02-23T17:25:54.718Z,1645637154.718 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-02-23T17:25:54.719Z,1645637154.719 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-02-23T17:25:54.719Z,1645637154.719 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-02-23T17:25:55.440Z,1645637155.440 [CANONSampler] Loaded
2022-02-23T17:25:55.440Z,1645637155.440 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2022-02-23T17:25:55.501Z,1645637155.501 [CTD_Seabird] Loaded
2022-02-23T17:25:55.501Z,1645637155.501 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-02-23T17:25:55.502Z,1645637155.502 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2022-02-23T17:25:55.503Z,1645637155.503 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 929
2022-02-23T17:25:55.523Z,1645637155.523 [PAR_Licor] Loaded
2022-02-23T17:25:55.524Z,1645637155.524 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-02-23T17:25:55.557Z,1645637155.557 [WetLabsBB2FL] Loaded
2022-02-23T17:25:55.557Z,1645637155.557 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-02-23T17:25:55.558Z,1645637155.558 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0
2022-02-23T17:25:55.559Z,1645637155.559 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 930
2022-02-23T17:25:55.560Z,1645637155.560 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-02-23T17:25:55.560Z,1645637155.560 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-02-23T17:25:55.603Z,1645637155.603 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-02-23T17:25:55.604Z,1645637155.604 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-02-23T17:25:55.991Z,1645637155.991 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-02-23T17:25:55.991Z,1645637155.991 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-02-23T17:25:56.172Z,1645637156.172 [SBIT](DEBUG): Construct Startup Built In Test.
2022-02-23T17:25:56.181Z,1645637156.181 [SBIT] Loaded
2022-02-23T17:25:56.182Z,1645637156.182 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-02-23T17:25:56.185Z,1645637156.185 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-02-23T17:25:56.198Z,1645637156.198 [IBIT] Loaded
2022-02-23T17:25:56.198Z,1645637156.198 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-02-23T17:25:56.204Z,1645637156.204 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-02-23T17:25:56.311Z,1645637156.311 [CBIT] Loaded
2022-02-23T17:25:56.311Z,1645637156.311 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-02-23T17:25:56.311Z,1645637156.311 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-02-23T17:25:56.312Z,1645637156.312 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-02-23T17:25:56.435Z,1645637156.435 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-02-23T17:25:56.441Z,1645637156.441 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-02-23T17:25:56.444Z,1645637156.444 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-02-23T17:25:56.455Z,1645637156.455 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-02-23T17:25:56.456Z,1645637156.456 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B414E0
2022-02-23T17:25:56.456Z,1645637156.456 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931
2022-02-23T17:25:56.461Z,1645637156.461 [Supervisor](INFO): Main Thread ID is 831
2022-02-23T17:25:56.461Z,1645637156.461 [Supervisor](DEBUG): Running supervisor.
2022-02-23T17:25:56.461Z,1645637156.461 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932
2022-02-23T17:25:56.462Z,1645637156.462 [CommandExec](INFO): Initializing the command executive.
2022-02-23T17:25:56.464Z,1645637156.464 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933
2022-02-23T17:25:56.466Z,1645637156.466 [controlThread ThreadHandler](INFO): Handler Thread ID is 934
2022-02-23T17:25:56.466Z,1645637156.466 [controlThread](DEBUG): Initializing ControlThread
2022-02-23T17:25:56.467Z,1645637156.467 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-02-23T17:25:56.469Z,1645637156.469 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-02-23T17:25:56.470Z,1645637156.470 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-02-23T17:25:56.470Z,1645637156.470 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-02-23T17:25:56.472Z,1645637156.472 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-02-23T17:25:56.473Z,1645637156.473 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-02-23T17:25:56.477Z,1645637156.477 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-02-23T17:25:56.477Z,1645637156.477 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-02-23T17:25:56.478Z,1645637156.478 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-02-23T17:25:56.478Z,1645637156.478 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-02-23T17:25:56.478Z,1645637156.478 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-02-23T17:25:56.479Z,1645637156.479 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-02-23T17:25:56.483Z,1645637156.483 [SBIT](INFO): Initialize SBIT Component.
2022-02-23T17:25:56.484Z,1645637156.484 [SBIT](IMPORTANT): git: 2022-01-05
2022-02-23T17:25:56.484Z,1645637156.484 [SBIT](INFO): git hash: 4648efb7c0362dd4a6331fa887af857d1a6abffc
2022-02-23T17:25:56.485Z,1645637156.485 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-02-23T17:25:56.486Z,1645637156.486 [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
2022-02-23T17:25:56.487Z,1645637156.487 [SBIT](INFO): Beginning SBIT in 73.000000 seconds.
2022-02-23T17:25:56.488Z,1645637156.488 [IBIT](INFO): Initialize IBIT Component.
2022-02-23T17:25:56.489Z,1645637156.489 [CBIT](DEBUG): Initialize CBIT Component.
2022-02-23T17:25:56.490Z,1645637156.490 [logger ThreadHandler](INFO): Handler Thread ID is 935
2022-02-23T17:25:56.503Z,1645637156.503 [CBIT](DEBUG): Initialized mux pins.
2022-02-23T17:25:56.503Z,1645637156.503 [CBIT](DEBUG): Initializing the watchdog timer.
2022-02-23T17:25:56.508Z,1645637156.508 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936
2022-02-23T17:25:56.509Z,1645637156.509 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-02-23T17:25:56.516Z,1645637156.516 [Onboard ThreadHandler](INFO): Handler Thread ID is 937
2022-02-23T17:25:56.527Z,1645637156.527 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-02-23T17:25:56.528Z,1645637156.528 [CBIT](DEBUG): Initializing heartbeat.
2022-02-23T17:25:56.540Z,1645637156.540 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938
2022-02-23T17:25:56.552Z,1645637156.552 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2022-02-23T17:25:56.552Z,1645637156.552 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-02-23T17:25:56.557Z,1645637156.557 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2022-02-23T17:25:56.560Z,1645637156.560 [WetLabsBB2FL](INFO): Powering up
2022-02-23T17:25:56.561Z,1645637156.561 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2022-02-23T17:25:56.570Z,1645637156.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-02-23T17:25:56.570Z,1645637156.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-02-23T17:25:56.570Z,1645637156.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-02-23T17:25:56.570Z,1645637156.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-02-23T17:25:56.571Z,1645637156.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-02-23T17:25:56.571Z,1645637156.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-02-23T17:25:56.571Z,1645637156.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-02-23T17:25:56.571Z,1645637156.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-02-23T17:25:56.572Z,1645637156.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-02-23T17:25:56.572Z,1645637156.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-02-23T17:25:56.572Z,1645637156.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-02-23T17:25:56.572Z,1645637156.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-02-23T17:25:56.573Z,1645637156.573 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-02-23T17:25:56.573Z,1645637156.573 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-02-23T17:25:56.573Z,1645637156.573 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-02-23T17:25:56.573Z,1645637156.573 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-02-23T17:25:56.599Z,1645637156.599 [CBIT](DEBUG): Deactivating GF circuits.
2022-02-23T17:25:56.599Z,1645637156.599 [CBIT](DEBUG): Deactivating emergency mode.
2022-02-23T17:25:56.635Z,1645637156.635 [CBIT](DEBUG): Backplane powered.
2022-02-23T17:25:56.636Z,1645637156.636 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-02-23T17:25:56.662Z,1645637156.662 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-02-23T17:25:56.691Z,1645637156.691 [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>


2022-02-23T17:25:56.692Z,1645637156.692 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-02-23T17:25:56.777Z,1645637156.777 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-02-23T17:25:56.778Z,1645637156.778 [Default:A.Wait](DEBUG): Construct Wait.
2022-02-23T17:25:56.781Z,1645637156.781 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-02-23T17:25:56.824Z,1645637156.824 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-02-23T17:25:56.827Z,1645637156.827 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-02-23T17:25:56.872Z,1645637156.872 [Default:E.Execute](DEBUG): Construct Execute.
2022-02-23T17:25:56.876Z,1645637156.876 [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="P2H">
                <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>


2022-02-23T17:25:56.884Z,1645637156.884 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-02-23T17:25:56.903Z,1645637156.903 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-02-23T17:25:56.947Z,1645637156.947 [Radio_Surface](INFO): Powering up
2022-02-23T17:25:57.068Z,1645637157.068 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2022-02-23T17:25:57.069Z,1645637157.069 [Power24vConverter](INFO): Powering up.
2022-02-23T17:25:57.070Z,1645637157.070 [CANONSampler](INFO): Powering down
2022-02-23T17:25:57.074Z,1645637157.074 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-02-23T17:25:57.110Z,1645637157.110 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-02-23T17:25:57.115Z,1645637157.115 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-02-23T17:25:57.117Z,1645637157.117 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-02-23T17:25:57.124Z,1645637157.124 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-02-23T17:25:57.125Z,1645637157.125 [MassServo](DEBUG): Initializing EZServoServo.
2022-02-23T17:25:57.132Z,1645637157.132 [MassServo](DEBUG): Initializing MassServo.
2022-02-23T17:25:57.133Z,1645637157.133 [RudderServo](DEBUG): Initializing EZServoServo.
2022-02-23T17:25:57.140Z,1645637157.140 [RudderServo](DEBUG): Initializing RudderServo.
2022-02-23T17:25:57.141Z,1645637157.141 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-02-23T17:25:57.151Z,1645637157.151 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-02-23T17:25:58.023Z,1645637158.023 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-02-23T17:25:58.023Z,1645637158.023 [RudderServo](FAULT): Rudder failed to initialize
2022-02-23T17:25:58.023Z,1645637158.023 [RudderServo] Communications Fault, FailCount= 1
2022-02-23T17:25:58.023Z,1645637158.023 [RudderServo](ERROR): Communications Fault
2022-02-23T17:25:58.123Z,1645637158.123 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-02-23T17:25:58.292Z,1645637158.292 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-02-23T17:25:58.292Z,1645637158.292 [RudderServo](INFO): Powering down
2022-02-23T17:25:58.667Z,1645637158.667 [WetLabsBB2FL](INFO): Powering down
2022-02-23T17:25:58.960Z,1645637158.960 [RudderServo](DEBUG): Initializing EZServoServo.
2022-02-23T17:25:59.080Z,1645637159.080 [RudderServo](DEBUG): Initializing RudderServo.
2022-02-23T17:25:59.084Z,1645637159.084 [CBIT](INFO): Clearing failed state for component RudderServo
2022-02-23T17:25:59.084Z,1645637159.084 [RudderServo] No Fault, FailCount= 1
2022-02-23T17:26:24.397Z,1645637184.397 [NAL9602](INFO): Powering up NAL9602
2022-02-23T17:26:35.305Z,1645637195.305 [NAL9602](INFO): NAL9602 initialized
2022-02-23T17:27:10.090Z,1645637230.090 [SBIT](IMPORTANT): Beginning Startup BIT
2022-02-23T17:27:10.095Z,1645637230.095 [CBIT](IMPORTANT): Beginning ground fault scan
2022-02-23T17:27:20.996Z,1645637240.996 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-02-23T17:27:21.398Z,1645637241.398 [CBIT](IMPORTANT): Beginning ground fault scan
2022-02-23T17:27:32.416Z,1645637252.416 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-02-23T17:27:32.784Z,1645637252.784 [CBIT](IMPORTANT): Beginning ground fault scan
2022-02-23T17:27:43.685Z,1645637263.685 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-02-23T17:27:43.685Z,1645637263.685 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2022-02-23T17:28:04.203Z,1645637284.203 [SBIT](IMPORTANT): SBIT PASSED
2022-02-23T17:28:04.219Z,1645637284.219 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-02-23T17:28:04.220Z,1645637284.220 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-02-23T17:28:04.221Z,1645637284.221 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-02-23T17:28:04.221Z,1645637284.221 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-02-23T17:28:04.221Z,1645637284.221 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-02-23T17:28:04.228Z,1645637284.228 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=230.078199 cubic_centimeter;

2022-02-23T17:28:04.231Z,1645637284.231 [SBIT](IMPORTANT): VerticalControl.massDefault=-13.451902 millimeter;

2022-02-23T17:28:04.606Z,1645637284.606 [MissionManager](IMPORTANT): Started mission Startup
2022-02-23T17:28:04.606Z,1645637284.606 [Startup] Running Loop=1
2022-02-23T17:28:04.606Z,1645637284.606 [Startup](DEBUG): Aggregate::initialize Startup
2022-02-23T17:28:04.606Z,1645637284.606 [Startup:A.GoToSurface] Running Loop=1
2022-02-23T17:28:04.606Z,1645637284.606 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-02-23T17:28:04.607Z,1645637284.607 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-02-23T17:28:04.608Z,1645637284.608 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-02-23T17:28:04.608Z,1645637284.608 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-02-23T17:28:04.608Z,1645637284.608 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-02-23T17:28:04.609Z,1645637284.609 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-02-23T17:28:04.610Z,1645637284.610 [Startup:StartupSatComms] Running Loop=1
2022-02-23T17:28:04.610Z,1645637284.610 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-02-23T17:28:04.610Z,1645637284.610 [Startup:StartupSatComms:A] Running Loop=1
2022-02-23T17:28:05.010Z,1645637285.010 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-02-23T17:28:25.422Z,1645637305.422 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005143
2022-02-23T17:28:48.997Z,1645637328.997 [CommandExec](IMPORTANT): got command gfscan
2022-02-23T17:28:49.278Z,1645637329.278 [CBIT](IMPORTANT): Beginning ground fault scan
2022-02-23T17:28:56.566Z,1645637336.566 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-02-23T17:28:56.566Z,1645637336.566 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-02-23T17:28:56.577Z,1645637336.577 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-02-23T17:28:56.980Z,1645637336.980 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-02-23T17:28:56.981Z,1645637336.981 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-02-23T17:29:00.179Z,1645637340.179 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001492
CHAN A1 (24V): 0.000383
CHAN A2 (12V): -0.002091
CHAN A3 (5V): -0.001460
CHAN B0 (3.3V): 0.000163
CHAN B1 (3.15aV): 0.000077
CHAN B2 (3.15bV): -0.000361
CHAN B3 (GND): -0.000024
OPEN: -0.000111
Full Scale: +/- 1 mA
2022-02-23T17:29:04.615Z,1645637344.615 [Startup:StartupSatComms:A](INFO): Timed out from 2022-02-23T17:28:04.6Z
2022-02-23T17:29:04.615Z,1645637344.615 [Startup:StartupSatComms:A] Stopped
2022-02-23T17:29:04.615Z,1645637344.615 [Startup:StartupSatComms:B] Running Loop=1
2022-02-23T17:29:05.019Z,1645637345.019 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-02-23T17:29:11.476Z,1645637351.476 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220223T172545/Courier0000.lzma
2022-02-23T17:29:12.478Z,1645637352.478 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0000.lzma.bak
2022-02-23T17:29:12.478Z,1645637352.478 [DataOverHttps](INFO): SBD MOMSN=16512250
2022-02-23T17:29:28.053Z,1645637368.053 [DataOverHttps](INFO): Sending 876 bytes from file Logs/20220223T172545/Express0001.lzma
2022-02-23T17:29:29.054Z,1645637369.054 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0001.lzma.bak
2022-02-23T17:29:29.054Z,1645637369.054 [DataOverHttps](INFO): SBD MOMSN=16512275
2022-02-23T17:29:30.071Z,1645637370.071 [Startup:StartupSatComms:B] Stopped
2022-02-23T17:29:30.072Z,1645637370.072 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-02-23T17:29:30.072Z,1645637370.072 [Startup:StartupSatComms] Stopped
2022-02-23T17:29:30.072Z,1645637370.072 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-02-23T17:29:30.073Z,1645637370.073 [Startup](INFO): Completed Startup
2022-02-23T17:29:30.073Z,1645637370.073 [MissionManager](INFO): Startup is completed.
2022-02-23T17:29:30.073Z,1645637370.073 [MissionManager](INFO): Uninitializing Mission Startup
2022-02-23T17:29:30.073Z,1645637370.073 [Startup] Stopped
2022-02-23T17:29:30.073Z,1645637370.073 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-02-23T17:29:30.074Z,1645637370.074 [Startup:A.GoToSurface] Stopped
2022-02-23T17:29:30.074Z,1645637370.074 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-02-23T17:29:30.479Z,1645637370.479 [MissionManager](IMPORTANT): Started mission Default
2022-02-23T17:29:30.479Z,1645637370.479 [Default] Running Loop=1
2022-02-23T17:29:30.479Z,1645637370.479 [Default](DEBUG): Aggregate::initialize Default
2022-02-23T17:29:30.479Z,1645637370.480 [Default:B.GoToSurface] Running Loop=1
2022-02-23T17:29:30.480Z,1645637370.480 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-02-23T17:29:30.480Z,1645637370.480 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-02-23T17:29:30.480Z,1645637370.480 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-02-23T17:29:30.481Z,1645637370.481 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-02-23T17:29:30.481Z,1645637370.481 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-02-23T17:29:30.482Z,1645637370.482 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-02-23T17:29:30.482Z,1645637370.482 [Default:A.Wait] Running Loop=1
2022-02-23T17:29:30.482Z,1645637370.482 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-02-23T17:29:43.821Z,1645637383.821 [Default:A.Wait](INFO): Done Waiting.
2022-02-23T17:29:43.821Z,1645637383.821 [Default:A.Wait] Stopped
2022-02-23T17:29:43.821Z,1645637383.821 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T17:29:44.227Z,1645637384.227 [Default:CheckIn] Running Loop=1
2022-02-23T17:29:44.227Z,1645637384.227 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T17:29:44.227Z,1645637384.227 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T17:29:44.616Z,1645637384.616 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-02-23T17:30:12.130Z,1645637412.130 [CommandExec](IMPORTANT): got command burn on
2022-02-23T17:30:12.130Z,1645637412.130 [CommandExec](IMPORTANT): Activating dropweight wire
2022-02-23T17:30:23.170Z,1645637423.170 [CommandExec](IMPORTANT): got command burn off
2022-02-23T17:30:23.170Z,1645637423.170 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-02-23T17:31:38.546Z,1645637498.546 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-02-23T17:31:57.566Z,1645637517.566 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-02-23T17:31:57.566Z,1645637517.566 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-02-23T17:31:57.605Z,1645637517.605 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-02-23T17:31:57.970Z,1645637517.970 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-02-23T17:31:57.970Z,1645637517.970 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-02-23T17:32:12.887Z,1645637532.887 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-02-23T17:32:12.890Z,1645637532.890 [BPC1](INFO): Received data from all battery sticks.
2022-02-23T17:34:44.409Z,1645637684.409 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-02-23T17:29:44.2Z
2022-02-23T17:34:44.409Z,1645637684.409 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T17:34:44.409Z,1645637684.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T17:34:44.823Z,1645637684.823 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-02-23T17:34:54.488Z,1645637694.488 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220223T172545/Courier0004.lzma
2022-02-23T17:34:55.490Z,1645637695.490 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0004.lzma.bak
2022-02-23T17:34:55.490Z,1645637695.490 [DataOverHttps](INFO): SBD MOMSN=16512303
2022-02-23T17:34:58.590Z,1645637698.590 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-02-23T17:34:58.591Z,1645637698.591 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-02-23T17:34:58.665Z,1645637698.665 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-02-23T17:34:59.019Z,1645637699.019 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-02-23T17:34:59.019Z,1645637699.019 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-02-23T17:35:11.233Z,1645637711.233 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20220223T172545/Express0005.lzma
2022-02-23T17:35:12.234Z,1645637712.234 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0005.lzma.bak
2022-02-23T17:35:12.234Z,1645637712.234 [DataOverHttps](INFO): SBD MOMSN=16512305
2022-02-23T17:35:13.552Z,1645637713.552 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T17:35:13.552Z,1645637713.552 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T17:35:13.552Z,1645637713.552 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T17:35:57.974Z,1645637757.974 [Power24vConverter](INFO): Powering down.
2022-02-23T17:36:37.576Z,1645637797.576 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-02-23T17:36:37.576Z,1645637797.576 [NAL9602] Data Fault, FailCount= 1
2022-02-23T17:36:37.576Z,1645637797.576 [NAL9602](ERROR): Data Fault
2022-02-23T17:36:37.595Z,1645637797.595 [CBIT](ERROR): Data Fault in component: NAL9602
2022-02-23T17:36:37.986Z,1645637797.986 [NAL9602](INFO): Powering down
2022-02-23T17:36:38.813Z,1645637798.813 [CBIT](INFO): Clearing failed state for component NAL9602
2022-02-23T17:36:38.814Z,1645637798.814 [NAL9602] No Fault, FailCount= 1
2022-02-23T17:37:08.303Z,1645637828.303 [NAL9602](INFO): Powering up NAL9602
2022-02-23T17:37:19.210Z,1645637839.210 [NAL9602](INFO): NAL9602 initialized
2022-02-23T17:37:59.621Z,1645637879.621 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-02-23T17:37:59.621Z,1645637879.621 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-02-23T17:37:59.666Z,1645637879.666 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-02-23T17:38:00.036Z,1645637880.036 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-02-23T17:38:00.036Z,1645637880.036 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-02-23T17:40:14.295Z,1645638014.295 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T17:40:14.295Z,1645638014.295 [Default:CheckIn:C.Wait] Stopped
2022-02-23T17:40:14.295Z,1645638014.295 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T17:40:14.295Z,1645638014.295 [Default:CheckIn:D] Running Loop=1
2022-02-23T17:40:14.692Z,1645638014.692 [Default:CheckIn:D] Stopped
2022-02-23T17:40:14.692Z,1645638014.692 [Default:CheckIn:E] Running Loop=1
2022-02-23T17:40:15.092Z,1645638015.092 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.736875 min 
2022-02-23T17:40:15.092Z,1645638015.092 [Default:CheckIn:E] Stopped
2022-02-23T17:40:15.092Z,1645638015.092 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T17:40:15.092Z,1645638015.092 [Default:CheckIn] Stopped
2022-02-23T17:40:15.093Z,1645638015.093 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T17:40:15.093Z,1645638015.093 [Default:CheckIn](INFO): Running loop #2
2022-02-23T17:40:15.093Z,1645638015.093 [Default:CheckIn] Running Loop=2
2022-02-23T17:40:15.093Z,1645638015.093 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T17:40:15.093Z,1645638015.093 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T17:41:00.442Z,1645638060.442 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-02-23T17:41:00.442Z,1645638060.442 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-02-23T17:41:00.453Z,1645638060.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-02-23T17:41:00.867Z,1645638060.867 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-02-23T17:41:00.867Z,1645638060.867 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-02-23T17:44:01.500Z,1645638241.500 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-02-23T17:44:01.500Z,1645638241.500 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-02-23T17:44:01.512Z,1645638241.512 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-02-23T17:44:01.925Z,1645638241.925 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-02-23T17:44:01.925Z,1645638241.925 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-02-23T17:45:15.431Z,1645638315.431 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-02-23T17:40:15.1Z
2022-02-23T17:45:15.431Z,1645638315.431 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T17:45:15.432Z,1645638315.432 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T17:45:22.852Z,1645638322.852 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220223T172545/Courier0007.lzma
2022-02-23T17:45:23.854Z,1645638323.854 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0007.lzma.bak
2022-02-23T17:45:23.854Z,1645638323.854 [DataOverHttps](INFO): SBD MOMSN=16512313
2022-02-23T17:45:39.337Z,1645638339.337 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20220223T172545/Express0008.lzma
2022-02-23T17:45:40.338Z,1645638340.338 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0008.lzma.bak
2022-02-23T17:45:40.338Z,1645638340.338 [DataOverHttps](INFO): SBD MOMSN=16512315
2022-02-23T17:45:41.691Z,1645638341.691 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T17:45:41.691Z,1645638341.691 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T17:45:41.691Z,1645638341.691 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T17:47:02.502Z,1645638422.502 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-02-23T17:47:02.502Z,1645638422.502 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-02-23T17:47:02.534Z,1645638422.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-02-23T17:47:02.906Z,1645638422.906 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-02-23T17:47:02.906Z,1645638422.906 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-02-23T17:47:22.270Z,1645638442.270 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-02-23T17:47:22.270Z,1645638442.270 [NAL9602] Data Fault, FailCount= 2
2022-02-23T17:47:22.270Z,1645638442.270 [NAL9602](ERROR): Data Fault
2022-02-23T17:47:22.289Z,1645638442.289 [CBIT](ERROR): Data Fault in component: NAL9602
2022-02-23T17:47:22.678Z,1645638442.678 [NAL9602](INFO): Powering down
2022-02-23T17:47:23.497Z,1645638443.497 [CBIT](INFO): Clearing failed state for component NAL9602
2022-02-23T17:47:23.497Z,1645638443.497 [NAL9602] No Fault, FailCount= 2
2022-02-23T17:47:52.972Z,1645638472.972 [NAL9602](INFO): Powering up NAL9602
2022-02-23T17:48:03.890Z,1645638483.890 [NAL9602](INFO): NAL9602 initialized
2022-02-23T17:49:02.128Z,1645638542.128 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174900.00,A,3648.13879,N,12147.22430,W,0.058,0.00,230222,,,A*72
2022-02-23T17:49:02.132Z,1645638542.132 [NAL9602](INFO): GPS fix at 20220223T174900: (36.802313, -121.787072)
2022-02-23T17:49:34.441Z,1645638574.441 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T17:50:42.424Z,1645638642.424 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T17:50:42.424Z,1645638642.424 [Default:CheckIn:C.Wait] Stopped
2022-02-23T17:50:42.424Z,1645638642.424 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T17:50:42.424Z,1645638642.424 [Default:CheckIn:D] Running Loop=1
2022-02-23T17:50:42.808Z,1645638642.808 [Default:CheckIn:D] Stopped
2022-02-23T17:50:42.808Z,1645638642.808 [Default:CheckIn:E] Running Loop=1
2022-02-23T17:50:43.216Z,1645638643.216 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.205477 min 
2022-02-23T17:50:43.216Z,1645638643.216 [Default:CheckIn:E] Stopped
2022-02-23T17:50:43.216Z,1645638643.216 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T17:50:43.216Z,1645638643.216 [Default:CheckIn] Stopped
2022-02-23T17:50:43.216Z,1645638643.216 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T17:50:43.217Z,1645638643.217 [Default:CheckIn](INFO): Running loop #3
2022-02-23T17:50:43.217Z,1645638643.217 [Default:CheckIn] Running Loop=3
2022-02-23T17:50:43.217Z,1645638643.217 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T17:50:43.217Z,1645638643.217 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T17:50:45.225Z,1645638645.225 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175043.00,A,3648.13795,N,12147.22427,W,0.058,0.00,230222,,,D*73
2022-02-23T17:50:45.227Z,1645638645.227 [NAL9602](INFO): GPS fix at 20220223T175043: (36.802299, -121.787071)
2022-02-23T17:50:45.239Z,1645638645.239 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T17:50:45.239Z,1645638645.239 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T17:50:55.796Z,1645638655.796 [NAL9602](INFO): SBD MO Status=0, MOMSN=31443, MT Status=0, MTMSN=0
2022-02-23T17:50:55.796Z,1645638655.796 [NAL9602](INFO): No messages in MT queue
2022-02-23T17:50:56.152Z,1645638656.152 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20220223T172545/Courier0010.lzma
2022-02-23T17:50:57.155Z,1645638657.155 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0010.lzma.bak
2022-02-23T17:50:57.155Z,1645638657.155 [DataOverHttps](INFO): SBD MOMSN=16512326
2022-02-23T17:51:13.204Z,1645638673.204 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20220223T172545/Express0011.lzma
2022-02-23T17:51:14.206Z,1645638674.206 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0011.lzma.bak
2022-02-23T17:51:14.206Z,1645638674.206 [DataOverHttps](INFO): SBD MOMSN=16512329
2022-02-23T17:51:15.216Z,1645638675.216 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T17:51:15.216Z,1645638675.216 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T17:51:15.216Z,1645638675.216 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T17:51:26.498Z,1645638686.498 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T17:56:15.607Z,1645638975.607 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T17:56:15.608Z,1645638975.608 [Default:CheckIn:C.Wait] Stopped
2022-02-23T17:56:15.608Z,1645638975.608 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T17:56:15.608Z,1645638975.608 [Default:CheckIn:D] Running Loop=1
2022-02-23T17:56:16.011Z,1645638976.011 [Default:CheckIn:D] Stopped
2022-02-23T17:56:16.011Z,1645638976.011 [Default:CheckIn:E] Running Loop=1
2022-02-23T17:56:16.412Z,1645638976.412 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.758854 min 
2022-02-23T17:56:16.413Z,1645638976.413 [Default:CheckIn:E] Stopped
2022-02-23T17:56:16.413Z,1645638976.413 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T17:56:16.413Z,1645638976.413 [Default:CheckIn] Stopped
2022-02-23T17:56:16.413Z,1645638976.413 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T17:56:16.413Z,1645638976.413 [Default:CheckIn](INFO): Running loop #4
2022-02-23T17:56:16.413Z,1645638976.413 [Default:CheckIn] Running Loop=4
2022-02-23T17:56:16.413Z,1645638976.413 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T17:56:16.413Z,1645638976.413 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T17:56:18.439Z,1645638978.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175617.00,A,3648.13933,N,12147.22340,W,0.039,0.00,230222,,,D*77
2022-02-23T17:56:18.442Z,1645638978.442 [NAL9602](INFO): GPS fix at 20220223T175617: (36.802322, -121.787057)
2022-02-23T17:56:18.485Z,1645638978.485 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T17:56:18.486Z,1645638978.486 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T17:56:29.384Z,1645638989.384 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0013.lzma
2022-02-23T17:56:30.386Z,1645638990.386 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0013.lzma.bak
2022-02-23T17:56:30.386Z,1645638990.386 [DataOverHttps](INFO): SBD MOMSN=16512337
2022-02-23T17:56:31.866Z,1645638991.866 [NAL9602](INFO): SBD MO Status=0, MOMSN=31444, MT Status=0, MTMSN=0
2022-02-23T17:56:31.866Z,1645638991.866 [NAL9602](INFO): No messages in MT queue
2022-02-23T17:56:46.420Z,1645639006.420 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220223T172545/Express0014.lzma
2022-02-23T17:56:47.423Z,1645639007.423 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0014.lzma.bak
2022-02-23T17:56:47.423Z,1645639007.423 [DataOverHttps](INFO): SBD MOMSN=16512340
2022-02-23T17:56:48.452Z,1645639008.452 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T17:56:48.452Z,1645639008.452 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T17:56:48.452Z,1645639008.452 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T17:57:02.614Z,1645639022.614 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:00:32.206Z,1645639232.206 [DataOverHttps](IMPORTANT): SBD MTMSN=20220223T180031
2022-02-23T18:00:39.620Z,1645639239.620 [DataOverHttps](INFO): Received command: ibit
2022-02-23T18:00:39.661Z,1645639239.661 [CommandExec](IMPORTANT): got command ibit
2022-02-23T18:00:39.735Z,1645639239.735 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-02-23T18:00:39.735Z,1645639239.735 [IBIT](IMPORTANT): Beginning control surface checks.
2022-02-23T18:00:39.738Z,1645639239.738 [CBIT](IMPORTANT): Beginning ground fault scan
2022-02-23T18:00:41.325Z,1645639241.325 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180040.00,A,3648.13958,N,12147.22436,W,0.039,0.00,230222,,,D*72
2022-02-23T18:00:41.327Z,1645639241.327 [NAL9602](INFO): GPS fix at 20220223T180040: (36.802326, -121.787073)
2022-02-23T18:00:50.693Z,1645639250.693 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.002537
CHAN A1 (24V): -0.179554
CHAN A2 (12V): -0.002333
CHAN A3 (5V): -0.001833
CHAN B0 (3.3V): 0.000254
CHAN B1 (3.15aV): -0.000164
CHAN B2 (3.15bV): 0.000043
CHAN B3 (GND): -0.000165
OPEN: -0.000151
Full Scale: +/- 1 mA
2022-02-23T18:01:07.583Z,1645639267.583 [NAL9602](INFO): SBD MO Status=0, MOMSN=31445, MT Status=0, MTMSN=0
2022-02-23T18:01:07.584Z,1645639267.584 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:01:08.796Z,1645639268.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180108.00,A,3648.13955,N,12147.22471,W,0.039,0.00,230222,,,D*71
2022-02-23T18:01:08.810Z,1645639268.810 [NAL9602](INFO): GPS fix at 20220223T180108: (36.802326, -121.787079)
2022-02-23T18:01:12.028Z,1645639272.028 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180111.00,A,3648.13954,N,12147.22470,W,0.039,0.00,230222,,,D*79
2022-02-23T18:01:12.030Z,1645639272.030 [NAL9602](INFO): GPS fix at 20220223T180111: (36.802326, -121.787078)
2022-02-23T18:01:14.855Z,1645639274.855 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180114.00,A,3648.13948,N,12147.22472,W,0.019,0.00,230222,,,D*71
2022-02-23T18:01:14.869Z,1645639274.869 [NAL9602](INFO): GPS fix at 20220223T180114: (36.802325, -121.787079)
2022-02-23T18:01:18.092Z,1645639278.092 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180117.00,A,3648.13943,N,12147.22475,W,0.019,0.00,230222,,,D*7E
2022-02-23T18:01:18.105Z,1645639278.105 [NAL9602](INFO): GPS fix at 20220223T180117: (36.802324, -121.787079)
2022-02-23T18:01:20.920Z,1645639280.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180120.00,A,3648.13935,N,12147.22481,W,0.000,0.00,230222,,,D*78
2022-02-23T18:01:20.923Z,1645639280.923 [NAL9602](INFO): GPS fix at 20220223T180120: (36.802323, -121.787080)
2022-02-23T18:01:24.148Z,1645639284.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180123.00,A,3648.13927,N,12147.22487,W,0.019,0.00,230222,,,D*76
2022-02-23T18:01:24.150Z,1645639284.150 [NAL9602](INFO): GPS fix at 20220223T180123: (36.802321, -121.787081)
2022-02-23T18:01:26.191Z,1645639286.191 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802322 Longitude: -121.787079
2022-02-23T18:01:26.605Z,1645639286.605 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.532000
2022-02-23T18:01:26.605Z,1645639286.605 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2022-02-23T18:01:26.605Z,1645639286.605 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2022-02-23T18:01:26.975Z,1645639286.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180126.00,A,3648.13922,N,12147.22493,W,0.039,0.00,230222,,,D*71
2022-02-23T18:01:26.978Z,1645639286.978 [NAL9602](INFO): GPS fix at 20220223T180126: (36.802320, -121.787082)
2022-02-23T18:01:27.008Z,1645639287.008 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-02-23T18:01:27.008Z,1645639287.008 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-02-23T18:01:27.017Z,1645639287.017 [IBIT](IMPORTANT): Pressure:9.975979 PSI
2022-02-23T18:01:27.017Z,1645639287.017 [IBIT](IMPORTANT): Humidity:8.600904 %
2022-02-23T18:01:27.425Z,1645639287.425 [IBIT](IMPORTANT): Vehicle Pitch:-0.382425 degrees
2022-02-23T18:01:27.425Z,1645639287.425 [IBIT](IMPORTANT): Vehicle  Roll:2.503498 degrees
2022-02-23T18:01:27.425Z,1645639287.425 [IBIT](IMPORTANT): Vehicle  Heading:124.731819 degrees
2022-02-23T18:01:27.803Z,1645639287.803 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-02-23T18:01:27.803Z,1645639287.803 [IBIT](IMPORTANT): buoyancyNeutral: 230.078201 cc
2022-02-23T18:01:27.804Z,1645639287.804 [IBIT](IMPORTANT): massDefault: -1.345190 cm
2022-02-23T18:01:27.804Z,1645639287.804 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2022-02-23T18:01:27.804Z,1645639287.804 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2022-02-23T18:01:27.804Z,1645639287.804 [IBIT](IMPORTANT): IBIT PASSED
2022-02-23T18:01:49.222Z,1645639309.222 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:01:49.222Z,1645639309.222 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:01:49.222Z,1645639309.222 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:01:49.223Z,1645639309.223 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:01:49.608Z,1645639309.608 [Default:CheckIn:D] Stopped
2022-02-23T18:01:49.608Z,1645639309.608 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:01:50.013Z,1645639310.013 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.318805 min 
2022-02-23T18:01:50.013Z,1645639310.013 [Default:CheckIn:E] Stopped
2022-02-23T18:01:50.013Z,1645639310.013 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:01:50.013Z,1645639310.013 [Default:CheckIn] Stopped
2022-02-23T18:01:50.013Z,1645639310.013 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:01:50.014Z,1645639310.014 [Default:CheckIn](INFO): Running loop #5
2022-02-23T18:01:50.014Z,1645639310.014 [Default:CheckIn] Running Loop=5
2022-02-23T18:01:50.014Z,1645639310.014 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:01:50.014Z,1645639310.014 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:01:52.028Z,1645639312.028 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180151.00,A,3648.13877,N,12147.22556,W,0.039,0.00,230222,,,D*78
2022-02-23T18:01:52.030Z,1645639312.030 [NAL9602](INFO): GPS fix at 20220223T180151: (36.802313, -121.787093)
2022-02-23T18:01:52.072Z,1645639312.072 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:01:52.072Z,1645639312.072 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:01:59.448Z,1645639319.448 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0016.lzma
2022-02-23T18:02:00.450Z,1645639320.450 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0016.lzma.bak
2022-02-23T18:02:00.450Z,1645639320.450 [DataOverHttps](INFO): SBD MOMSN=16512346
2022-02-23T18:02:16.361Z,1645639336.361 [DataOverHttps](INFO): Sending 810 bytes from file Logs/20220223T172545/Express0017.lzma
2022-02-23T18:02:17.362Z,1645639337.362 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0017.lzma.bak
2022-02-23T18:02:17.362Z,1645639337.362 [DataOverHttps](INFO): SBD MOMSN=16512349
2022-02-23T18:02:24.394Z,1645639344.394 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:02:36.204Z,1645639356.204 [DataOverHttps](INFO): Sending 65 bytes from file Logs/20220223T172545/Express0020.lzma
2022-02-23T18:02:37.206Z,1645639357.206 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0020.lzma.bak
2022-02-23T18:02:37.206Z,1645639357.206 [DataOverHttps](INFO): SBD MOMSN=16512375
2022-02-23T18:02:38.549Z,1645639358.549 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:02:38.549Z,1645639358.549 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:02:38.549Z,1645639358.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:05:18.362Z,1645639518.362 [DataOverHttps](IMPORTANT): SBD MTMSN=20220223T180517
2022-02-23T18:05:25.792Z,1645639525.792 [DataOverHttps](INFO): Received command: failc
2022-02-23T18:05:25.797Z,1645639525.797 [CommandExec](IMPORTANT): got command failComponent
2022-02-23T18:05:25.797Z,1645639525.797 [CommandExec](IMPORTANT): Failed components:
2022-02-23T18:05:25.798Z,1645639525.798 [CommandExec](IMPORTANT): No failed Components.
2022-02-23T18:07:39.208Z,1645639659.208 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:07:39.208Z,1645639659.208 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:07:39.208Z,1645639659.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:07:39.208Z,1645639659.208 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:07:39.621Z,1645639659.621 [Default:CheckIn:D] Stopped
2022-02-23T18:07:39.622Z,1645639659.622 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:07:40.025Z,1645639660.025 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.152368 min 
2022-02-23T18:07:40.025Z,1645639660.025 [Default:CheckIn:E] Stopped
2022-02-23T18:07:40.025Z,1645639660.025 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:07:40.026Z,1645639660.026 [Default:CheckIn] Stopped
2022-02-23T18:07:40.026Z,1645639660.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:07:40.026Z,1645639660.026 [Default:CheckIn](INFO): Running loop #6
2022-02-23T18:07:40.026Z,1645639660.026 [Default:CheckIn] Running Loop=6
2022-02-23T18:07:40.026Z,1645639660.026 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:07:40.026Z,1645639660.026 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:07:42.035Z,1645639662.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180741.00,A,3648.13811,N,12147.22487,W,0.019,0.00,230222,,,D*70
2022-02-23T18:07:42.037Z,1645639662.037 [NAL9602](INFO): GPS fix at 20220223T180741: (36.802302, -121.787081)
2022-02-23T18:07:42.054Z,1645639662.054 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:07:42.054Z,1645639662.054 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:07:49.864Z,1645639669.864 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0022.lzma
2022-02-23T18:07:50.866Z,1645639670.866 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0022.lzma.bak
2022-02-23T18:07:50.866Z,1645639670.866 [DataOverHttps](INFO): SBD MOMSN=16512400
2022-02-23T18:08:06.812Z,1645639686.812 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20220223T172545/Express0023.lzma
2022-02-23T18:08:07.814Z,1645639687.814 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0023.lzma.bak
2022-02-23T18:08:07.814Z,1645639687.814 [DataOverHttps](INFO): SBD MOMSN=16512406
2022-02-23T18:08:10.711Z,1645639690.711 [NAL9602](INFO): SBD MO Status=0, MOMSN=31446, MT Status=0, MTMSN=0
2022-02-23T18:08:10.711Z,1645639690.711 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:08:26.608Z,1645639706.608 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20220223T172545/Express0026.lzma
2022-02-23T18:08:27.610Z,1645639707.610 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0026.lzma.bak
2022-02-23T18:08:27.611Z,1645639707.611 [DataOverHttps](INFO): SBD MOMSN=16512413
2022-02-23T18:08:28.651Z,1645639708.651 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:08:28.651Z,1645639708.651 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:08:28.651Z,1645639708.651 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:08:41.142Z,1645639721.142 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:13:29.217Z,1645640009.217 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:13:29.218Z,1645640009.218 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:13:29.218Z,1645640009.218 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:13:29.218Z,1645640009.218 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:13:29.622Z,1645640009.622 [Default:CheckIn:D] Stopped
2022-02-23T18:13:29.622Z,1645640009.622 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:13:30.041Z,1645640010.041 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.985701 min 
2022-02-23T18:13:30.041Z,1645640010.041 [Default:CheckIn:E] Stopped
2022-02-23T18:13:30.041Z,1645640010.041 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:13:30.041Z,1645640010.041 [Default:CheckIn] Stopped
2022-02-23T18:13:30.041Z,1645640010.041 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:13:30.041Z,1645640010.041 [Default:CheckIn](INFO): Running loop #7
2022-02-23T18:13:30.042Z,1645640010.042 [Default:CheckIn] Running Loop=7
2022-02-23T18:13:30.042Z,1645640010.042 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:13:30.042Z,1645640010.042 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:13:32.040Z,1645640012.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181331.00,A,3648.13960,N,12147.22404,W,0.058,0.00,230222,,,D*7B
2022-02-23T18:13:32.042Z,1645640012.042 [NAL9602](INFO): GPS fix at 20220223T181331: (36.802327, -121.787067)
2022-02-23T18:13:32.054Z,1645640012.054 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:13:32.054Z,1645640012.054 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:13:39.472Z,1645640019.472 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0028.lzma
2022-02-23T18:13:40.474Z,1645640020.474 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0028.lzma.bak
2022-02-23T18:13:40.474Z,1645640020.474 [DataOverHttps](INFO): SBD MOMSN=16512430
2022-02-23T18:13:56.404Z,1645640036.404 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220223T172545/Express0029.lzma
2022-02-23T18:13:57.406Z,1645640037.406 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0029.lzma.bak
2022-02-23T18:13:57.406Z,1645640037.406 [DataOverHttps](INFO): SBD MOMSN=16512436
2022-02-23T18:13:58.708Z,1645640038.708 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:13:58.708Z,1645640038.708 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:13:58.708Z,1645640038.708 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:14:01.931Z,1645640041.931 [NAL9602](INFO): SBD MO Status=2, MOMSN=31447, MT Status=2, MTMSN=0
2022-02-23T18:14:01.931Z,1645640041.931 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T18:14:21.733Z,1645640061.733 [NAL9602](INFO): SBD MO Status=2, MOMSN=31447, MT Status=2, MTMSN=0
2022-02-23T18:14:21.733Z,1645640061.733 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T18:14:41.991Z,1645640081.991 [NAL9602](INFO): SBD MO Status=0, MOMSN=31447, MT Status=0, MTMSN=0
2022-02-23T18:14:41.992Z,1645640081.992 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:15:12.697Z,1645640112.697 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:18:59.369Z,1645640339.369 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:18:59.369Z,1645640339.369 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:18:59.370Z,1645640339.369 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:18:59.370Z,1645640339.370 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:18:59.786Z,1645640339.786 [Default:CheckIn:D] Stopped
2022-02-23T18:18:59.786Z,1645640339.786 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:19:00.180Z,1645640340.180 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.488440 min 
2022-02-23T18:19:00.180Z,1645640340.180 [Default:CheckIn:E] Stopped
2022-02-23T18:19:00.180Z,1645640340.180 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:19:00.180Z,1645640340.180 [Default:CheckIn] Stopped
2022-02-23T18:19:00.180Z,1645640340.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:19:00.180Z,1645640340.180 [Default:CheckIn](INFO): Running loop #8
2022-02-23T18:19:00.181Z,1645640340.181 [Default:CheckIn] Running Loop=8
2022-02-23T18:19:00.181Z,1645640340.181 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:19:00.181Z,1645640340.181 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:19:02.188Z,1645640342.188 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181901.00,A,3648.13810,N,12147.22657,W,0.019,0.00,230222,,,D*75
2022-02-23T18:19:02.191Z,1645640342.191 [NAL9602](INFO): GPS fix at 20220223T181901: (36.802302, -121.787109)
2022-02-23T18:19:02.206Z,1645640342.206 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:19:02.206Z,1645640342.206 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:19:10.172Z,1645640350.172 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0031.lzma
2022-02-23T18:19:11.174Z,1645640351.174 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0031.lzma.bak
2022-02-23T18:19:11.174Z,1645640351.174 [DataOverHttps](INFO): SBD MOMSN=16512451
2022-02-23T18:19:26.023Z,1645640366.023 [NAL9602](INFO): SBD MO Status=2, MOMSN=31448, MT Status=2, MTMSN=0
2022-02-23T18:19:26.024Z,1645640366.024 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T18:19:27.256Z,1645640367.256 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220223T172545/Express0032.lzma
2022-02-23T18:19:28.258Z,1645640368.258 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0032.lzma.bak
2022-02-23T18:19:28.258Z,1645640368.258 [DataOverHttps](INFO): SBD MOMSN=16512454
2022-02-23T18:19:29.280Z,1645640369.280 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:19:29.280Z,1645640369.280 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:19:29.280Z,1645640369.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:19:35.319Z,1645640375.319 [NAL9602](INFO): SBD MO Status=0, MOMSN=31448, MT Status=0, MTMSN=0
2022-02-23T18:19:35.319Z,1645640375.319 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:20:06.019Z,1645640406.019 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:24:29.889Z,1645640669.889 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:24:29.889Z,1645640669.889 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:24:29.889Z,1645640669.889 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:24:29.889Z,1645640669.889 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:24:30.294Z,1645640670.294 [Default:CheckIn:D] Stopped
2022-02-23T18:24:30.294Z,1645640670.294 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:24:30.717Z,1645640670.717 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.996912 min 
2022-02-23T18:24:30.717Z,1645640670.717 [Default:CheckIn:E] Stopped
2022-02-23T18:24:30.717Z,1645640670.717 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:24:30.717Z,1645640670.717 [Default:CheckIn] Stopped
2022-02-23T18:24:30.717Z,1645640670.717 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:24:30.717Z,1645640670.717 [Default:CheckIn](INFO): Running loop #9
2022-02-23T18:24:30.717Z,1645640670.717 [Default:CheckIn] Running Loop=9
2022-02-23T18:24:30.718Z,1645640670.718 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:24:30.718Z,1645640670.718 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:24:32.712Z,1645640672.712 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182431.00,A,3648.13672,N,12147.22651,W,0.039,0.00,230222,,,D*76
2022-02-23T18:24:32.718Z,1645640672.718 [NAL9602](INFO): GPS fix at 20220223T182431: (36.802279, -121.787109)
2022-02-23T18:24:32.774Z,1645640672.774 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:24:32.774Z,1645640672.774 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:24:40.460Z,1645640680.460 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0034.lzma
2022-02-23T18:24:41.462Z,1645640681.462 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0034.lzma.bak
2022-02-23T18:24:41.462Z,1645640681.462 [DataOverHttps](INFO): SBD MOMSN=16512466
2022-02-23T18:24:57.452Z,1645640697.452 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220223T172545/Express0035.lzma
2022-02-23T18:24:58.454Z,1645640698.454 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0035.lzma.bak
2022-02-23T18:24:58.454Z,1645640698.454 [DataOverHttps](INFO): SBD MOMSN=16512469
2022-02-23T18:24:59.777Z,1645640699.777 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:24:59.777Z,1645640699.777 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:24:59.777Z,1645640699.777 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:25:05.425Z,1645640705.425 [NAL9602](INFO): SBD MO Status=0, MOMSN=31449, MT Status=0, MTMSN=0
2022-02-23T18:25:05.425Z,1645640705.425 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:25:36.126Z,1645640736.126 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:30:00.436Z,1645641000.436 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:30:00.436Z,1645641000.436 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:30:00.436Z,1645641000.436 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:30:00.456Z,1645641000.456 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:30:00.836Z,1645641000.836 [Default:CheckIn:D] Stopped
2022-02-23T18:30:00.836Z,1645641000.836 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:30:01.260Z,1645641001.260 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.505949 min 
2022-02-23T18:30:01.260Z,1645641001.260 [Default:CheckIn:E] Stopped
2022-02-23T18:30:01.261Z,1645641001.261 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:30:01.261Z,1645641001.261 [Default:CheckIn] Stopped
2022-02-23T18:30:01.261Z,1645641001.261 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:30:01.261Z,1645641001.261 [Default:CheckIn](INFO): Running loop #10
2022-02-23T18:30:01.261Z,1645641001.261 [Default:CheckIn] Running Loop=10
2022-02-23T18:30:01.261Z,1645641001.261 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:30:01.261Z,1645641001.261 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:30:03.256Z,1645641003.256 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183002.00,A,3648.14024,N,12147.22181,W,0.058,0.00,230222,,,D*7C
2022-02-23T18:30:03.271Z,1645641003.271 [NAL9602](INFO): GPS fix at 20220223T183002: (36.802337, -121.787030)
2022-02-23T18:30:03.282Z,1645641003.282 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:30:03.282Z,1645641003.282 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:30:11.096Z,1645641011.096 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0037.lzma
2022-02-23T18:30:12.098Z,1645641012.098 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0037.lzma.bak
2022-02-23T18:30:12.098Z,1645641012.098 [DataOverHttps](INFO): SBD MOMSN=16512480
2022-02-23T18:30:30.019Z,1645641030.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=31450, MT Status=2, MTMSN=0
2022-02-23T18:30:30.020Z,1645641030.020 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T18:30:32.356Z,1645641032.356 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220223T172545/Express0038.lzma
2022-02-23T18:30:33.358Z,1645641033.358 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0038.lzma.bak
2022-02-23T18:30:33.358Z,1645641033.358 [DataOverHttps](INFO): SBD MOMSN=16512483
2022-02-23T18:30:34.490Z,1645641034.490 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:30:34.490Z,1645641034.490 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:30:34.490Z,1645641034.490 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:30:49.019Z,1645641049.019 [NAL9602](INFO): SBD MO Status=0, MOMSN=31450, MT Status=0, MTMSN=0
2022-02-23T18:30:49.019Z,1645641049.019 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:31:19.721Z,1645641079.721 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:35:35.071Z,1645641335.071 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:35:35.071Z,1645641335.071 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:35:35.071Z,1645641335.071 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:35:35.072Z,1645641335.072 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:35:35.468Z,1645641335.468 [Default:CheckIn:D] Stopped
2022-02-23T18:35:35.468Z,1645641335.468 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:35:35.866Z,1645641335.866 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.083138 min 
2022-02-23T18:35:35.866Z,1645641335.866 [Default:CheckIn:E] Stopped
2022-02-23T18:35:35.866Z,1645641335.866 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:35:35.867Z,1645641335.867 [Default:CheckIn] Stopped
2022-02-23T18:35:35.867Z,1645641335.867 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:35:35.867Z,1645641335.867 [Default:CheckIn](INFO): Running loop #11
2022-02-23T18:35:35.867Z,1645641335.867 [Default:CheckIn] Running Loop=11
2022-02-23T18:35:35.867Z,1645641335.867 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:35:35.867Z,1645641335.867 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:35:37.884Z,1645641337.884 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183537.00,A,3648.13974,N,12147.22359,W,0.058,0.00,230222,,,D*73
2022-02-23T18:35:37.886Z,1645641337.886 [NAL9602](INFO): GPS fix at 20220223T183537: (36.802329, -121.787060)
2022-02-23T18:35:37.897Z,1645641337.897 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:35:37.897Z,1645641337.897 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:35:45.484Z,1645641345.484 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0040.lzma
2022-02-23T18:35:46.486Z,1645641346.486 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0040.lzma.bak
2022-02-23T18:35:46.486Z,1645641346.486 [DataOverHttps](INFO): SBD MOMSN=16512495
2022-02-23T18:36:02.796Z,1645641362.796 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0041.lzma
2022-02-23T18:36:03.798Z,1645641363.798 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0041.lzma.bak
2022-02-23T18:36:03.799Z,1645641363.799 [DataOverHttps](INFO): SBD MOMSN=16512498
2022-02-23T18:36:04.974Z,1645641364.974 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:36:04.974Z,1645641364.974 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:36:04.974Z,1645641364.974 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:36:30.409Z,1645641390.409 [NAL9602](INFO): SBD MO Status=2, MOMSN=31451, MT Status=2, MTMSN=0
2022-02-23T18:36:30.410Z,1645641390.410 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T18:36:54.682Z,1645641414.682 [NAL9602](INFO): SBD MO Status=0, MOMSN=31451, MT Status=0, MTMSN=0
2022-02-23T18:36:54.682Z,1645641414.682 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:37:25.361Z,1645641445.361 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:39:38.807Z,1645641578.807 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-02-23T18:39:38.810Z,1645641578.810 [BPC1](INFO): Received data from all battery sticks.
2022-02-23T18:41:05.692Z,1645641665.692 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:41:05.692Z,1645641665.692 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:41:05.692Z,1645641665.692 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:41:05.692Z,1645641665.692 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:41:06.088Z,1645641666.088 [Default:CheckIn:D] Stopped
2022-02-23T18:41:06.088Z,1645641666.088 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:41:06.492Z,1645641666.492 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.593473 min 
2022-02-23T18:41:06.492Z,1645641666.492 [Default:CheckIn:E] Stopped
2022-02-23T18:41:06.492Z,1645641666.492 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:41:06.492Z,1645641666.492 [Default:CheckIn] Stopped
2022-02-23T18:41:06.492Z,1645641666.492 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:41:06.493Z,1645641666.493 [Default:CheckIn](INFO): Running loop #12
2022-02-23T18:41:06.493Z,1645641666.493 [Default:CheckIn] Running Loop=12
2022-02-23T18:41:06.493Z,1645641666.493 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:41:06.493Z,1645641666.493 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:41:08.505Z,1645641668.505 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184107.00,A,3648.13911,N,12147.20498,W,0.078,117.87,230222,,,D*72
2022-02-23T18:41:08.508Z,1645641668.508 [NAL9602](INFO): GPS fix at 20220223T184107: (36.802318, -121.786750)
2022-02-23T18:41:08.519Z,1645641668.519 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:41:08.519Z,1645641668.519 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:41:16.244Z,1645641676.244 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20220223T172545/Courier0043.lzma
2022-02-23T18:41:17.246Z,1645641677.246 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0043.lzma.bak
2022-02-23T18:41:17.246Z,1645641677.246 [DataOverHttps](INFO): SBD MOMSN=16512511
2022-02-23T18:41:33.224Z,1645641693.224 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20220223T172545/Express0044.lzma
2022-02-23T18:41:34.226Z,1645641694.226 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0044.lzma.bak
2022-02-23T18:41:34.226Z,1645641694.226 [DataOverHttps](INFO): SBD MOMSN=16512514
2022-02-23T18:41:35.584Z,1645641695.584 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:41:35.584Z,1645641695.584 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:41:35.584Z,1645641695.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:41:53.759Z,1645641713.759 [NAL9602](INFO): SBD MO Status=0, MOMSN=31452, MT Status=0, MTMSN=0
2022-02-23T18:41:53.759Z,1645641713.759 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:42:24.459Z,1645641744.459 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:46:36.219Z,1645641996.219 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:46:36.219Z,1645641996.219 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:46:36.219Z,1645641996.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:46:36.219Z,1645641996.219 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:46:36.624Z,1645641996.624 [Default:CheckIn:D] Stopped
2022-02-23T18:46:36.624Z,1645641996.624 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:46:37.038Z,1645641997.038 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.102409 min 
2022-02-23T18:46:37.038Z,1645641997.038 [Default:CheckIn:E] Stopped
2022-02-23T18:46:37.039Z,1645641997.039 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:46:37.039Z,1645641997.039 [Default:CheckIn] Stopped
2022-02-23T18:46:37.039Z,1645641997.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:46:37.039Z,1645641997.039 [Default:CheckIn](INFO): Running loop #13
2022-02-23T18:46:37.039Z,1645641997.039 [Default:CheckIn] Running Loop=13
2022-02-23T18:46:37.039Z,1645641997.039 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:46:37.039Z,1645641997.039 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:46:39.044Z,1645641999.044 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184638.00,A,3648.13902,N,12147.20637,W,0.078,117.87,230222,,,D*7C
2022-02-23T18:46:39.046Z,1645641999.046 [NAL9602](INFO): GPS fix at 20220223T184638: (36.802317, -121.786773)
2022-02-23T18:46:39.057Z,1645641999.057 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:46:39.057Z,1645641999.057 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:46:46.062Z,1645642006.062 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0046.lzma
2022-02-23T18:46:47.062Z,1645642007.062 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0046.lzma.bak
2022-02-23T18:46:47.062Z,1645642007.062 [DataOverHttps](INFO): SBD MOMSN=16512527
2022-02-23T18:47:02.976Z,1645642022.976 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220223T172545/Express0047.lzma
2022-02-23T18:47:03.683Z,1645642023.683 [NAL9602](INFO): SBD MO Status=0, MOMSN=31453, MT Status=0, MTMSN=0
2022-02-23T18:47:03.683Z,1645642023.683 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:47:03.979Z,1645642023.979 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0047.lzma.bak
2022-02-23T18:47:03.979Z,1645642023.979 [DataOverHttps](INFO): SBD MOMSN=16512530
2022-02-23T18:47:05.308Z,1645642025.308 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:47:05.308Z,1645642025.308 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:47:05.308Z,1645642025.308 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:47:34.385Z,1645642054.385 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T18:52:05.950Z,1645642325.950 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:52:05.950Z,1645642325.950 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:52:05.950Z,1645642325.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:52:05.950Z,1645642325.950 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:52:06.360Z,1645642326.360 [Default:CheckIn:D] Stopped
2022-02-23T18:52:06.360Z,1645642326.360 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:52:06.762Z,1645642326.762 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.598014 min 
2022-02-23T18:52:06.762Z,1645642326.762 [Default:CheckIn:E] Stopped
2022-02-23T18:52:06.762Z,1645642326.762 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:52:06.762Z,1645642326.762 [Default:CheckIn] Stopped
2022-02-23T18:52:06.763Z,1645642326.763 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:52:06.763Z,1645642326.763 [Default:CheckIn](INFO): Running loop #14
2022-02-23T18:52:06.763Z,1645642326.763 [Default:CheckIn] Running Loop=14
2022-02-23T18:52:06.763Z,1645642326.763 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:52:06.763Z,1645642326.763 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:52:08.768Z,1645642328.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185208.00,A,3648.13908,N,12147.20480,W,0.039,117.87,230222,,,A*7E
2022-02-23T18:52:08.770Z,1645642328.770 [NAL9602](INFO): GPS fix at 20220223T185208: (36.802318, -121.786747)
2022-02-23T18:52:08.782Z,1645642328.782 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:52:08.782Z,1645642328.782 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:52:16.104Z,1645642336.104 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220223T172545/Courier0049.lzma
2022-02-23T18:52:17.106Z,1645642337.106 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0049.lzma.bak
2022-02-23T18:52:17.107Z,1645642337.107 [DataOverHttps](INFO): SBD MOMSN=16512540
2022-02-23T18:52:39.296Z,1645642359.296 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220223T172545/Express0050.lzma
2022-02-23T18:52:40.298Z,1645642360.298 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0050.lzma.bak
2022-02-23T18:52:40.299Z,1645642360.299 [DataOverHttps](INFO): SBD MOMSN=16512543
2022-02-23T18:52:41.649Z,1645642361.649 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:52:41.649Z,1645642361.649 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:52:41.649Z,1645642361.649 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:55:14.799Z,1645642514.799 [NAL9602](INFO): SBD MO Status=2, MOMSN=31454, MT Status=2, MTMSN=0
2022-02-23T18:55:14.799Z,1645642514.799 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T18:55:59.239Z,1645642559.239 [NAL9602](INFO): SBD MO Status=2, MOMSN=31454, MT Status=2, MTMSN=0
2022-02-23T18:55:59.240Z,1645642559.240 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T18:56:15.399Z,1645642575.399 [NAL9602](INFO): SBD MO Status=2, MOMSN=31454, MT Status=2, MTMSN=0
2022-02-23T18:56:15.399Z,1645642575.399 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T18:56:47.393Z,1645642607.393 [NAL9602](INFO): SBD MO Status=2, MOMSN=31454, MT Status=2, MTMSN=0
2022-02-23T18:56:47.393Z,1645642607.393 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T18:57:19.711Z,1645642639.711 [NAL9602](INFO): SBD MO Status=0, MOMSN=31454, MT Status=0, MTMSN=0
2022-02-23T18:57:19.711Z,1645642639.711 [NAL9602](INFO): No messages in MT queue
2022-02-23T18:57:42.348Z,1645642662.348 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T18:57:42.348Z,1645642662.348 [Default:CheckIn:C.Wait] Stopped
2022-02-23T18:57:42.348Z,1645642662.348 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T18:57:42.348Z,1645642662.348 [Default:CheckIn:D] Running Loop=1
2022-02-23T18:57:42.744Z,1645642662.744 [Default:CheckIn:D] Stopped
2022-02-23T18:57:42.744Z,1645642662.744 [Default:CheckIn:E] Running Loop=1
2022-02-23T18:57:43.148Z,1645642663.148 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.204411 min 
2022-02-23T18:57:43.148Z,1645642663.148 [Default:CheckIn:E] Stopped
2022-02-23T18:57:43.148Z,1645642663.148 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T18:57:43.149Z,1645642663.149 [Default:CheckIn] Stopped
2022-02-23T18:57:43.149Z,1645642663.149 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T18:57:43.149Z,1645642663.149 [Default:CheckIn](INFO): Running loop #15
2022-02-23T18:57:43.149Z,1645642663.149 [Default:CheckIn] Running Loop=15
2022-02-23T18:57:43.149Z,1645642663.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T18:57:43.149Z,1645642663.149 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T18:57:45.160Z,1645642665.160 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185744.00,A,3648.13955,N,12147.20561,W,0.136,117.87,230222,,,A*7B
2022-02-23T18:57:45.163Z,1645642665.163 [NAL9602](INFO): GPS fix at 20220223T185744: (36.802326, -121.786760)
2022-02-23T18:57:45.195Z,1645642665.195 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T18:57:45.195Z,1645642665.195 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T18:57:52.948Z,1645642672.948 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220223T172545/Courier0052.lzma
2022-02-23T18:57:53.950Z,1645642673.950 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0052.lzma.bak
2022-02-23T18:57:53.950Z,1645642673.950 [DataOverHttps](INFO): SBD MOMSN=16512553
2022-02-23T18:58:09.920Z,1645642689.920 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220223T172545/Express0053.lzma
2022-02-23T18:58:10.922Z,1645642690.922 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0053.lzma.bak
2022-02-23T18:58:10.922Z,1645642690.922 [DataOverHttps](INFO): SBD MOMSN=16512556
2022-02-23T18:58:12.247Z,1645642692.247 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T18:58:12.247Z,1645642692.247 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T18:58:12.247Z,1645642692.247 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T18:58:17.482Z,1645642697.482 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:03:12.919Z,1645642992.919 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:03:12.919Z,1645642992.919 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:03:12.919Z,1645642992.919 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:03:12.920Z,1645642992.920 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:03:13.324Z,1645642993.324 [Default:CheckIn:D] Stopped
2022-02-23T19:03:13.324Z,1645642993.324 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:03:13.742Z,1645642993.742 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.714079 min 
2022-02-23T19:03:13.742Z,1645642993.742 [Default:CheckIn:E] Stopped
2022-02-23T19:03:13.742Z,1645642993.742 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:03:13.742Z,1645642993.742 [Default:CheckIn] Stopped
2022-02-23T19:03:13.742Z,1645642993.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:03:13.742Z,1645642993.742 [Default:CheckIn](INFO): Running loop #16
2022-02-23T19:03:13.742Z,1645642993.742 [Default:CheckIn] Running Loop=16
2022-02-23T19:03:13.743Z,1645642993.743 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:03:13.743Z,1645642993.743 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:03:15.740Z,1645642995.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190315.00,A,3648.14291,N,12147.19682,W,0.564,76.70,230222,,,A*42
2022-02-23T19:03:15.742Z,1645642995.742 [NAL9602](INFO): GPS fix at 20220223T190315: (36.802382, -121.786614)
2022-02-23T19:03:15.782Z,1645642995.782 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:03:15.782Z,1645642995.782 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:03:22.748Z,1645643002.748 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0055.lzma
2022-02-23T19:03:23.750Z,1645643003.750 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0055.lzma.bak
2022-02-23T19:03:23.750Z,1645643003.750 [DataOverHttps](INFO): SBD MOMSN=16512566
2022-02-23T19:03:29.475Z,1645643009.475 [NAL9602](INFO): SBD MO Status=0, MOMSN=31455, MT Status=0, MTMSN=0
2022-02-23T19:03:29.475Z,1645643009.475 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:03:39.688Z,1645643019.688 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220223T172545/Express0056.lzma
2022-02-23T19:03:40.690Z,1645643020.690 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0056.lzma.bak
2022-02-23T19:03:40.690Z,1645643020.690 [DataOverHttps](INFO): SBD MOMSN=16512569
2022-02-23T19:03:42.007Z,1645643022.007 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:03:42.007Z,1645643022.007 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:03:42.007Z,1645643022.007 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:04:00.177Z,1645643040.177 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:08:42.785Z,1645643322.785 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:08:42.786Z,1645643322.786 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:08:42.786Z,1645643322.786 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:08:42.786Z,1645643322.786 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:08:43.168Z,1645643323.168 [Default:CheckIn:D] Stopped
2022-02-23T19:08:43.168Z,1645643323.168 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:08:43.578Z,1645643323.578 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.211475 min 
2022-02-23T19:08:43.579Z,1645643323.579 [Default:CheckIn:E] Stopped
2022-02-23T19:08:43.579Z,1645643323.579 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:08:43.579Z,1645643323.579 [Default:CheckIn] Stopped
2022-02-23T19:08:43.579Z,1645643323.579 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:08:43.579Z,1645643323.579 [Default:CheckIn](INFO): Running loop #17
2022-02-23T19:08:43.579Z,1645643323.579 [Default:CheckIn] Running Loop=17
2022-02-23T19:08:43.579Z,1645643323.579 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:08:43.579Z,1645643323.579 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:08:45.588Z,1645643325.588 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190844.00,A,3648.14400,N,12147.20193,W,0.058,349.69,230222,,,A*73
2022-02-23T19:08:45.600Z,1645643325.600 [NAL9602](INFO): GPS fix at 20220223T190844: (36.802400, -121.786699)
2022-02-23T19:08:45.612Z,1645643325.612 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:08:45.612Z,1645643325.612 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:08:52.496Z,1645643332.496 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220223T172545/Courier0058.lzma
2022-02-23T19:08:53.498Z,1645643333.498 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0058.lzma.bak
2022-02-23T19:08:53.498Z,1645643333.498 [DataOverHttps](INFO): SBD MOMSN=16512582
2022-02-23T19:09:09.532Z,1645643349.532 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0059.lzma
2022-02-23T19:09:10.534Z,1645643350.534 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0059.lzma.bak
2022-02-23T19:09:10.535Z,1645643350.535 [DataOverHttps](INFO): SBD MOMSN=16512585
2022-02-23T19:09:11.861Z,1645643351.861 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:09:11.861Z,1645643351.861 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:09:11.862Z,1645643351.862 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:09:33.663Z,1645643373.663 [NAL9602](INFO): SBD MO Status=2, MOMSN=31456, MT Status=2, MTMSN=0
2022-02-23T19:09:33.663Z,1645643373.663 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T19:10:59.323Z,1645643459.323 [NAL9602](INFO): SBD MO Status=0, MOMSN=31456, MT Status=0, MTMSN=0
2022-02-23T19:10:59.323Z,1645643459.323 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:11:30.030Z,1645643490.030 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:14:12.503Z,1645643652.503 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:14:12.503Z,1645643652.503 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:14:12.503Z,1645643652.503 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:14:12.503Z,1645643652.503 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:14:12.909Z,1645643652.909 [Default:CheckIn:D] Stopped
2022-02-23T19:14:12.909Z,1645643652.909 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:14:13.318Z,1645643653.318 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.707161 min 
2022-02-23T19:14:13.318Z,1645643653.318 [Default:CheckIn:E] Stopped
2022-02-23T19:14:13.319Z,1645643653.319 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:14:13.319Z,1645643653.319 [Default:CheckIn] Stopped
2022-02-23T19:14:13.319Z,1645643653.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:14:13.319Z,1645643653.319 [Default:CheckIn](INFO): Running loop #18
2022-02-23T19:14:13.319Z,1645643653.319 [Default:CheckIn] Running Loop=18
2022-02-23T19:14:13.319Z,1645643653.319 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:14:13.319Z,1645643653.319 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:14:15.330Z,1645643655.330 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191414.00,A,3648.14386,N,12147.20176,W,0.019,349.69,230222,,,D*79
2022-02-23T19:14:15.332Z,1645643655.332 [NAL9602](INFO): GPS fix at 20220223T191414: (36.802398, -121.786696)
2022-02-23T19:14:15.343Z,1645643655.343 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:14:15.343Z,1645643655.343 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:14:25.644Z,1645643665.644 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0061.lzma
2022-02-23T19:14:26.647Z,1645643666.647 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0061.lzma.bak
2022-02-23T19:14:26.647Z,1645643666.647 [DataOverHttps](INFO): SBD MOMSN=16512596
2022-02-23T19:14:27.107Z,1645643667.107 [NAL9602](INFO): SBD MO Status=0, MOMSN=31457, MT Status=0, MTMSN=0
2022-02-23T19:14:27.107Z,1645643667.107 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:14:46.316Z,1645643686.316 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0062.lzma
2022-02-23T19:14:47.318Z,1645643687.318 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0062.lzma.bak
2022-02-23T19:14:47.319Z,1645643687.319 [DataOverHttps](INFO): SBD MOMSN=16512600
2022-02-23T19:14:48.609Z,1645643688.609 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:14:48.609Z,1645643688.609 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:14:48.609Z,1645643688.609 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:14:57.893Z,1645643697.893 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:19:49.374Z,1645643989.374 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:19:49.375Z,1645643989.375 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:19:49.375Z,1645643989.375 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:19:49.375Z,1645643989.375 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:19:49.767Z,1645643989.767 [Default:CheckIn:D] Stopped
2022-02-23T19:19:49.767Z,1645643989.767 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:19:50.176Z,1645643990.176 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.321468 min 
2022-02-23T19:19:50.176Z,1645643990.176 [Default:CheckIn:E] Stopped
2022-02-23T19:19:50.177Z,1645643990.177 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:19:50.177Z,1645643990.177 [Default:CheckIn] Stopped
2022-02-23T19:19:50.177Z,1645643990.177 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:19:50.177Z,1645643990.177 [Default:CheckIn](INFO): Running loop #19
2022-02-23T19:19:50.177Z,1645643990.177 [Default:CheckIn] Running Loop=19
2022-02-23T19:19:50.177Z,1645643990.177 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:19:50.177Z,1645643990.177 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:19:52.192Z,1645643992.192 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191951.00,A,3648.14410,N,12147.20321,W,0.117,349.69,230222,,,D*72
2022-02-23T19:19:52.194Z,1645643992.194 [NAL9602](INFO): GPS fix at 20220223T191951: (36.802402, -121.786720)
2022-02-23T19:19:52.205Z,1645643992.205 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:19:52.205Z,1645643992.205 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:19:59.872Z,1645643999.872 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0064.lzma
2022-02-23T19:20:00.875Z,1645644000.875 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0064.lzma.bak
2022-02-23T19:20:00.875Z,1645644000.875 [DataOverHttps](INFO): SBD MOMSN=16512611
2022-02-23T19:20:09.959Z,1645644009.959 [NAL9602](INFO): SBD MO Status=0, MOMSN=31458, MT Status=0, MTMSN=0
2022-02-23T19:20:09.959Z,1645644009.959 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:20:16.865Z,1645644016.865 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220223T172545/Express0065.lzma
2022-02-23T19:20:17.866Z,1645644017.866 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0065.lzma.bak
2022-02-23T19:20:17.867Z,1645644017.867 [DataOverHttps](INFO): SBD MOMSN=16512617
2022-02-23T19:20:19.267Z,1645644019.267 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:20:19.267Z,1645644019.267 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:20:19.267Z,1645644019.267 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:20:40.722Z,1645644040.722 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:25:20.060Z,1645644320.060 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:25:20.060Z,1645644320.060 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:25:20.060Z,1645644320.060 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:25:20.060Z,1645644320.060 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:25:20.469Z,1645644320.469 [Default:CheckIn:D] Stopped
2022-02-23T19:25:20.470Z,1645644320.470 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:25:20.876Z,1645644320.876 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.833171 min 
2022-02-23T19:25:20.876Z,1645644320.876 [Default:CheckIn:E] Stopped
2022-02-23T19:25:20.876Z,1645644320.876 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:25:20.876Z,1645644320.876 [Default:CheckIn] Stopped
2022-02-23T19:25:20.876Z,1645644320.876 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:25:20.877Z,1645644320.877 [Default:CheckIn](INFO): Running loop #20
2022-02-23T19:25:20.877Z,1645644320.877 [Default:CheckIn] Running Loop=20
2022-02-23T19:25:20.877Z,1645644320.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:25:20.877Z,1645644320.877 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:25:22.891Z,1645644322.891 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192522.00,A,3648.14247,N,12147.20129,W,0.019,349.69,230222,,,D*78
2022-02-23T19:25:22.894Z,1645644322.894 [NAL9602](INFO): GPS fix at 20220223T192522: (36.802374, -121.786688)
2022-02-23T19:25:22.905Z,1645644322.905 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:25:22.905Z,1645644322.905 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:25:29.896Z,1645644329.896 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0067.lzma
2022-02-23T19:25:30.898Z,1645644330.898 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0067.lzma.bak
2022-02-23T19:25:30.898Z,1645644330.898 [DataOverHttps](INFO): SBD MOMSN=16512624
2022-02-23T19:25:35.808Z,1645644335.808 [NAL9602](INFO): SBD MO Status=0, MOMSN=31459, MT Status=0, MTMSN=0
2022-02-23T19:25:35.808Z,1645644335.808 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:25:46.798Z,1645644346.798 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20220223T172545/Express0068.lzma
2022-02-23T19:25:47.798Z,1645644347.798 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0068.lzma.bak
2022-02-23T19:25:47.799Z,1645644347.799 [DataOverHttps](INFO): SBD MOMSN=16512630
2022-02-23T19:25:49.156Z,1645644349.156 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:25:49.156Z,1645644349.156 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:25:49.156Z,1645644349.156 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:26:06.510Z,1645644366.510 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:30:49.565Z,1645644649.565 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:30:49.565Z,1645644649.565 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:30:49.565Z,1645644649.565 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:30:49.565Z,1645644649.565 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:30:49.960Z,1645644649.960 [Default:CheckIn:D] Stopped
2022-02-23T19:30:49.960Z,1645644649.960 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:30:50.369Z,1645644650.369 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.324674 min 
2022-02-23T19:30:50.369Z,1645644650.369 [Default:CheckIn:E] Stopped
2022-02-23T19:30:50.369Z,1645644650.369 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:30:50.369Z,1645644650.369 [Default:CheckIn] Stopped
2022-02-23T19:30:50.369Z,1645644650.369 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:30:50.369Z,1645644650.369 [Default:CheckIn](INFO): Running loop #21
2022-02-23T19:30:50.370Z,1645644650.370 [Default:CheckIn] Running Loop=21
2022-02-23T19:30:50.370Z,1645644650.370 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:30:50.370Z,1645644650.370 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:30:52.364Z,1645644652.364 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193051.00,A,3648.14243,N,12147.20144,W,0.058,0.00,230222,,,D*73
2022-02-23T19:30:52.366Z,1645644652.366 [NAL9602](INFO): GPS fix at 20220223T193051: (36.802374, -121.786691)
2022-02-23T19:30:52.378Z,1645644652.378 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:30:52.378Z,1645644652.378 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:30:59.568Z,1645644659.568 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0070.lzma
2022-02-23T19:31:00.570Z,1645644660.570 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0070.lzma.bak
2022-02-23T19:31:00.571Z,1645644660.571 [DataOverHttps](INFO): SBD MOMSN=16512638
2022-02-23T19:31:15.395Z,1645644675.395 [NAL9602](INFO): SBD MO Status=0, MOMSN=31460, MT Status=0, MTMSN=0
2022-02-23T19:31:15.395Z,1645644675.395 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:31:16.664Z,1645644676.664 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20220223T172545/Express0071.lzma
2022-02-23T19:31:17.666Z,1645644677.666 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0071.lzma.bak
2022-02-23T19:31:17.667Z,1645644677.667 [DataOverHttps](INFO): SBD MOMSN=16512642
2022-02-23T19:31:19.044Z,1645644679.044 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:31:19.044Z,1645644679.044 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:31:19.048Z,1645644679.048 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:31:46.101Z,1645644706.101 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:36:19.757Z,1645644979.757 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:36:19.757Z,1645644979.757 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:36:19.757Z,1645644979.757 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:36:19.757Z,1645644979.757 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:36:20.167Z,1645644980.167 [Default:CheckIn:D] Stopped
2022-02-23T19:36:20.167Z,1645644980.167 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:36:20.574Z,1645644980.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.828125 min 
2022-02-23T19:36:20.574Z,1645644980.574 [Default:CheckIn:E] Stopped
2022-02-23T19:36:20.575Z,1645644980.575 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:36:20.575Z,1645644980.575 [Default:CheckIn] Stopped
2022-02-23T19:36:20.575Z,1645644980.575 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:36:20.576Z,1645644980.576 [Default:CheckIn](INFO): Running loop #22
2022-02-23T19:36:20.576Z,1645644980.576 [Default:CheckIn] Running Loop=22
2022-02-23T19:36:20.577Z,1645644980.577 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:36:20.577Z,1645644980.577 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:36:22.578Z,1645644982.578 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193621.00,A,3648.14242,N,12147.20239,W,0.019,0.00,230222,,,D*7F
2022-02-23T19:36:22.591Z,1645644982.591 [NAL9602](INFO): GPS fix at 20220223T193621: (36.802374, -121.786706)
2022-02-23T19:36:22.602Z,1645644982.602 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:36:22.602Z,1645644982.602 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:36:23.412Z,1645644983.412 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:36:23.412Z,1645644983.412 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:36:23.412Z,1645644983.412 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:37:18.395Z,1645645038.395 [NAL9602](INFO): SBD MO Status=0, MOMSN=31461, MT Status=0, MTMSN=0
2022-02-23T19:37:18.395Z,1645645038.395 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:37:49.093Z,1645645069.093 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:41:24.213Z,1645645284.213 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:41:24.213Z,1645645284.213 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:41:24.213Z,1645645284.213 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:41:24.213Z,1645645284.213 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:41:24.628Z,1645645284.628 [Default:CheckIn:D] Stopped
2022-02-23T19:41:24.628Z,1645645284.628 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:41:25.031Z,1645645285.031 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.902474 min 
2022-02-23T19:41:25.031Z,1645645285.031 [Default:CheckIn:E] Stopped
2022-02-23T19:41:25.032Z,1645645285.032 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:41:25.032Z,1645645285.032 [Default:CheckIn] Stopped
2022-02-23T19:41:25.032Z,1645645285.032 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:41:25.032Z,1645645285.032 [Default:CheckIn](INFO): Running loop #23
2022-02-23T19:41:25.032Z,1645645285.032 [Default:CheckIn] Running Loop=23
2022-02-23T19:41:25.032Z,1645645285.032 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:41:25.032Z,1645645285.032 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:41:27.135Z,1645645287.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194126.00,A,3648.14245,N,12147.20229,W,0.039,0.00,230222,,,D*7C
2022-02-23T19:41:27.138Z,1645645287.138 [NAL9602](INFO): GPS fix at 20220223T194126: (36.802374, -121.786705)
2022-02-23T19:41:27.174Z,1645645287.174 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:41:27.174Z,1645645287.174 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:41:34.112Z,1645645294.112 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220223T172545/Courier0073.lzma
2022-02-23T19:41:35.114Z,1645645295.114 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0073.lzma.bak
2022-02-23T19:41:35.115Z,1645645295.115 [DataOverHttps](INFO): SBD MOMSN=16512659
2022-02-23T19:41:48.447Z,1645645308.447 [NAL9602](INFO): SBD MO Status=0, MOMSN=31462, MT Status=0, MTMSN=0
2022-02-23T19:41:48.447Z,1645645308.447 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:41:51.512Z,1645645311.512 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220223T172545/Courier0076.lzma
2022-02-23T19:41:52.514Z,1645645312.514 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0076.lzma.bak
2022-02-23T19:41:52.515Z,1645645312.515 [DataOverHttps](INFO): SBD MOMSN=16512662
2022-02-23T19:42:08.660Z,1645645328.660 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0074.lzma
2022-02-23T19:42:09.662Z,1645645329.662 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0074.lzma.bak
2022-02-23T19:42:09.663Z,1645645329.663 [DataOverHttps](INFO): SBD MOMSN=16512665
2022-02-23T19:42:19.148Z,1645645339.148 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:42:28.452Z,1645645348.452 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0077.lzma
2022-02-23T19:42:29.455Z,1645645349.455 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0077.lzma.bak
2022-02-23T19:42:29.455Z,1645645349.455 [DataOverHttps](INFO): SBD MOMSN=16512672
2022-02-23T19:42:30.545Z,1645645350.545 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:42:30.545Z,1645645350.545 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:42:30.545Z,1645645350.545 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:47:02.800Z,1645645622.800 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-02-23T19:47:02.803Z,1645645622.803 [BPC1](INFO): Received data from all battery sticks.
2022-02-23T19:47:31.103Z,1645645651.103 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:47:31.103Z,1645645651.103 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:47:31.104Z,1645645651.104 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:47:31.104Z,1645645651.104 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:47:31.487Z,1645645651.487 [Default:CheckIn:D] Stopped
2022-02-23T19:47:31.487Z,1645645651.487 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:47:31.900Z,1645645651.900 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.016797 min 
2022-02-23T19:47:31.900Z,1645645651.900 [Default:CheckIn:E] Stopped
2022-02-23T19:47:31.901Z,1645645651.901 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:47:31.901Z,1645645651.901 [Default:CheckIn] Stopped
2022-02-23T19:47:31.901Z,1645645651.901 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:47:31.901Z,1645645651.901 [Default:CheckIn](INFO): Running loop #24
2022-02-23T19:47:31.901Z,1645645651.901 [Default:CheckIn] Running Loop=24
2022-02-23T19:47:31.901Z,1645645651.901 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:47:31.901Z,1645645651.901 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:47:33.907Z,1645645653.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194733.00,A,3648.14291,N,12147.20163,W,0.058,0.00,230222,,,D*7D
2022-02-23T19:47:33.909Z,1645645653.909 [NAL9602](INFO): GPS fix at 20220223T194733: (36.802382, -121.786694)
2022-02-23T19:47:33.920Z,1645645653.920 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:47:33.920Z,1645645653.920 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:47:41.948Z,1645645661.948 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20220223T172545/Courier0079.lzma
2022-02-23T19:47:42.950Z,1645645662.950 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0079.lzma.bak
2022-02-23T19:47:42.951Z,1645645662.951 [DataOverHttps](INFO): SBD MOMSN=16512679
2022-02-23T19:47:59.020Z,1645645679.020 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0080.lzma
2022-02-23T19:48:00.022Z,1645645680.022 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0080.lzma.bak
2022-02-23T19:48:00.023Z,1645645680.023 [DataOverHttps](INFO): SBD MOMSN=16512685
2022-02-23T19:48:01.386Z,1645645681.386 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:48:01.386Z,1645645681.386 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:48:01.386Z,1645645681.386 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:48:08.651Z,1645645688.651 [NAL9602](INFO): SBD MO Status=0, MOMSN=31463, MT Status=0, MTMSN=0
2022-02-23T19:48:08.652Z,1645645688.652 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:48:39.349Z,1645645719.349 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:53:02.003Z,1645645982.003 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:53:02.004Z,1645645982.004 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:53:02.004Z,1645645982.004 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:53:02.004Z,1645645982.004 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:53:02.415Z,1645645982.415 [Default:CheckIn:D] Stopped
2022-02-23T19:53:02.415Z,1645645982.415 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:53:02.805Z,1645645982.805 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.532259 min 
2022-02-23T19:53:02.805Z,1645645982.805 [Default:CheckIn:E] Stopped
2022-02-23T19:53:02.805Z,1645645982.805 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:53:02.805Z,1645645982.805 [Default:CheckIn] Stopped
2022-02-23T19:53:02.805Z,1645645982.805 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:53:02.805Z,1645645982.805 [Default:CheckIn](INFO): Running loop #25
2022-02-23T19:53:02.805Z,1645645982.805 [Default:CheckIn] Running Loop=25
2022-02-23T19:53:02.806Z,1645645982.806 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:53:02.806Z,1645645982.806 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:53:04.816Z,1645645984.816 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195304.00,A,3648.14322,N,12147.20184,W,0.058,0.00,230222,,,D*7C
2022-02-23T19:53:04.818Z,1645645984.818 [NAL9602](INFO): GPS fix at 20220223T195304: (36.802387, -121.786697)
2022-02-23T19:53:04.829Z,1645645984.829 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:53:04.829Z,1645645984.829 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:53:12.724Z,1645645992.724 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220223T172545/Courier0082.lzma
2022-02-23T19:53:13.727Z,1645645993.727 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0082.lzma.bak
2022-02-23T19:53:13.727Z,1645645993.727 [DataOverHttps](INFO): SBD MOMSN=16512693
2022-02-23T19:53:26.231Z,1645646006.231 [NAL9602](INFO): SBD MO Status=0, MOMSN=31464, MT Status=0, MTMSN=0
2022-02-23T19:53:26.231Z,1645646006.231 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:53:29.620Z,1645646009.620 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220223T172545/Express0083.lzma
2022-02-23T19:53:30.622Z,1645646010.622 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0083.lzma.bak
2022-02-23T19:53:30.623Z,1645646010.623 [DataOverHttps](INFO): SBD MOMSN=16512699
2022-02-23T19:53:31.895Z,1645646011.895 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:53:31.895Z,1645646011.895 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:53:31.896Z,1645646011.896 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:53:56.934Z,1645646036.934 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T19:58:32.530Z,1645646312.530 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T19:58:32.530Z,1645646312.530 [Default:CheckIn:C.Wait] Stopped
2022-02-23T19:58:32.531Z,1645646312.531 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T19:58:32.531Z,1645646312.531 [Default:CheckIn:D] Running Loop=1
2022-02-23T19:58:32.910Z,1645646312.910 [Default:CheckIn:D] Stopped
2022-02-23T19:58:32.910Z,1645646312.910 [Default:CheckIn:E] Running Loop=1
2022-02-23T19:58:33.311Z,1645646313.311 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.040511 min 
2022-02-23T19:58:33.311Z,1645646313.311 [Default:CheckIn:E] Stopped
2022-02-23T19:58:33.311Z,1645646313.311 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T19:58:33.311Z,1645646313.311 [Default:CheckIn] Stopped
2022-02-23T19:58:33.312Z,1645646313.312 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T19:58:33.312Z,1645646313.312 [Default:CheckIn](INFO): Running loop #26
2022-02-23T19:58:33.312Z,1645646313.312 [Default:CheckIn] Running Loop=26
2022-02-23T19:58:33.312Z,1645646313.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T19:58:33.312Z,1645646313.312 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T19:58:35.319Z,1645646315.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195834.00,A,3648.14292,N,12147.20141,W,0.097,0.00,230222,,,D*74
2022-02-23T19:58:35.322Z,1645646315.322 [NAL9602](INFO): GPS fix at 20220223T195834: (36.802382, -121.786690)
2022-02-23T19:58:35.369Z,1645646315.369 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T19:58:35.369Z,1645646315.369 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T19:58:42.744Z,1645646322.744 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0085.lzma
2022-02-23T19:58:43.746Z,1645646323.746 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0085.lzma.bak
2022-02-23T19:58:43.747Z,1645646323.747 [DataOverHttps](INFO): SBD MOMSN=16512706
2022-02-23T19:58:57.143Z,1645646337.143 [NAL9602](INFO): SBD MO Status=0, MOMSN=31465, MT Status=0, MTMSN=0
2022-02-23T19:58:57.143Z,1645646337.143 [NAL9602](INFO): No messages in MT queue
2022-02-23T19:58:59.924Z,1645646339.924 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220223T172545/Express0086.lzma
2022-02-23T19:59:00.927Z,1645646340.927 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0086.lzma.bak
2022-02-23T19:59:00.927Z,1645646340.927 [DataOverHttps](INFO): SBD MOMSN=16512712
2022-02-23T19:59:02.001Z,1645646342.001 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T19:59:02.001Z,1645646342.001 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T19:59:02.001Z,1645646342.001 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T19:59:27.838Z,1645646367.838 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:04:02.590Z,1645646642.590 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:04:02.590Z,1645646642.590 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:04:02.590Z,1645646642.590 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:04:02.590Z,1645646642.590 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:04:02.983Z,1645646642.983 [Default:CheckIn:D] Stopped
2022-02-23T20:04:02.984Z,1645646642.984 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:04:03.392Z,1645646643.392 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.541732 min 
2022-02-23T20:04:03.392Z,1645646643.392 [Default:CheckIn:E] Stopped
2022-02-23T20:04:03.393Z,1645646643.393 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:04:03.393Z,1645646643.393 [Default:CheckIn] Stopped
2022-02-23T20:04:03.393Z,1645646643.393 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:04:03.393Z,1645646643.393 [Default:CheckIn](INFO): Running loop #27
2022-02-23T20:04:03.393Z,1645646643.393 [Default:CheckIn] Running Loop=27
2022-02-23T20:04:03.393Z,1645646643.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:04:03.393Z,1645646643.393 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:04:05.403Z,1645646645.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200404.00,A,3648.14311,N,12147.19990,W,0.078,0.00,230222,,,D*71
2022-02-23T20:04:05.406Z,1645646645.406 [NAL9602](INFO): GPS fix at 20220223T200404: (36.802385, -121.786665)
2022-02-23T20:04:05.449Z,1645646645.449 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:04:05.449Z,1645646645.449 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:04:13.140Z,1645646653.140 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220223T172545/Courier0088.lzma
2022-02-23T20:04:14.143Z,1645646654.143 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0088.lzma.bak
2022-02-23T20:04:14.143Z,1645646654.143 [DataOverHttps](INFO): SBD MOMSN=16512720
2022-02-23T20:04:20.819Z,1645646660.819 [NAL9602](INFO): SBD MO Status=0, MOMSN=31466, MT Status=0, MTMSN=0
2022-02-23T20:04:20.820Z,1645646660.820 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:04:33.296Z,1645646673.296 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0089.lzma
2022-02-23T20:04:34.298Z,1645646674.298 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0089.lzma.bak
2022-02-23T20:04:34.299Z,1645646674.299 [DataOverHttps](INFO): SBD MOMSN=16512727
2022-02-23T20:04:35.385Z,1645646675.385 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:04:35.386Z,1645646675.386 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:04:35.386Z,1645646675.386 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:04:51.524Z,1645646691.524 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:09:35.963Z,1645646975.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:09:35.963Z,1645646975.963 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:09:35.963Z,1645646975.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:09:35.963Z,1645646975.963 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:09:36.393Z,1645646976.393 [Default:CheckIn:D] Stopped
2022-02-23T20:09:36.394Z,1645646976.394 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:09:36.772Z,1645646976.772 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.098568 min 
2022-02-23T20:09:36.772Z,1645646976.772 [Default:CheckIn:E] Stopped
2022-02-23T20:09:36.773Z,1645646976.773 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:09:36.773Z,1645646976.773 [Default:CheckIn] Stopped
2022-02-23T20:09:36.773Z,1645646976.773 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:09:36.773Z,1645646976.773 [Default:CheckIn](INFO): Running loop #28
2022-02-23T20:09:36.773Z,1645646976.773 [Default:CheckIn] Running Loop=28
2022-02-23T20:09:36.773Z,1645646976.773 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:09:36.773Z,1645646976.773 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:09:38.778Z,1645646978.778 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200938.00,A,3648.14260,N,12147.20174,W,0.117,0.00,230222,,,A*71
2022-02-23T20:09:38.781Z,1645646978.781 [NAL9602](INFO): GPS fix at 20220223T200938: (36.802377, -121.786696)
2022-02-23T20:09:38.791Z,1645646978.791 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:09:38.792Z,1645646978.792 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:09:46.596Z,1645646986.596 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0091.lzma
2022-02-23T20:09:47.598Z,1645646987.598 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0091.lzma.bak
2022-02-23T20:09:47.599Z,1645646987.599 [DataOverHttps](INFO): SBD MOMSN=16512734
2022-02-23T20:10:03.720Z,1645647003.720 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0092.lzma
2022-02-23T20:10:04.722Z,1645647004.722 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0092.lzma.bak
2022-02-23T20:10:04.723Z,1645647004.723 [DataOverHttps](INFO): SBD MOMSN=16512737
2022-02-23T20:10:05.856Z,1645647005.856 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:10:05.857Z,1645647005.857 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:10:05.857Z,1645647005.857 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:10:26.047Z,1645647026.047 [NAL9602](INFO): SBD MO Status=2, MOMSN=31467, MT Status=2, MTMSN=0
2022-02-23T20:10:26.047Z,1645647026.047 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:10:51.903Z,1645647051.903 [NAL9602](INFO): SBD MO Status=2, MOMSN=31467, MT Status=2, MTMSN=0
2022-02-23T20:10:51.903Z,1645647051.903 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:12:51.530Z,1645647171.530 [NAL9602](INFO): SBD MO Status=0, MOMSN=31467, MT Status=0, MTMSN=0
2022-02-23T20:12:51.530Z,1645647171.530 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:13:22.201Z,1645647202.201 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:15:06.468Z,1645647306.468 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:15:06.468Z,1645647306.468 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:15:06.468Z,1645647306.468 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:15:06.472Z,1645647306.472 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:15:06.862Z,1645647306.862 [Default:CheckIn:D] Stopped
2022-02-23T20:15:06.862Z,1645647306.862 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:15:07.263Z,1645647307.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.606380 min 
2022-02-23T20:15:07.264Z,1645647307.264 [Default:CheckIn:E] Stopped
2022-02-23T20:15:07.264Z,1645647307.264 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:15:07.264Z,1645647307.264 [Default:CheckIn] Stopped
2022-02-23T20:15:07.264Z,1645647307.264 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:15:07.264Z,1645647307.264 [Default:CheckIn](INFO): Running loop #29
2022-02-23T20:15:07.264Z,1645647307.264 [Default:CheckIn] Running Loop=29
2022-02-23T20:15:07.264Z,1645647307.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:15:07.265Z,1645647307.265 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:15:09.276Z,1645647309.276 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201508.00,A,3648.14351,N,12147.20250,W,0.039,0.00,230222,,,A*74
2022-02-23T20:15:09.278Z,1645647309.278 [NAL9602](INFO): GPS fix at 20220223T201508: (36.802392, -121.786708)
2022-02-23T20:15:09.318Z,1645647309.318 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:15:09.318Z,1645647309.318 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:15:17.040Z,1645647317.040 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220223T172545/Courier0094.lzma
2022-02-23T20:15:18.043Z,1645647318.043 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0094.lzma.bak
2022-02-23T20:15:18.043Z,1645647318.043 [DataOverHttps](INFO): SBD MOMSN=16512741
2022-02-23T20:15:29.883Z,1645647329.883 [NAL9602](INFO): SBD MO Status=2, MOMSN=31468, MT Status=2, MTMSN=0
2022-02-23T20:15:29.884Z,1645647329.884 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:15:34.072Z,1645647334.072 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0095.lzma
2022-02-23T20:15:35.074Z,1645647335.074 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0095.lzma.bak
2022-02-23T20:15:35.075Z,1645647335.075 [DataOverHttps](INFO): SBD MOMSN=16512744
2022-02-23T20:15:36.370Z,1645647336.370 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:15:36.370Z,1645647336.370 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:15:36.370Z,1645647336.370 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:15:54.927Z,1645647354.927 [NAL9602](INFO): SBD MO Status=2, MOMSN=31468, MT Status=2, MTMSN=0
2022-02-23T20:15:54.927Z,1645647354.927 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:16:05.440Z,1645647365.440 [NAL9602](INFO): SBD MO Status=0, MOMSN=31468, MT Status=0, MTMSN=0
2022-02-23T20:16:05.440Z,1645647365.440 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:16:36.141Z,1645647396.141 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:20:36.962Z,1645647636.962 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:20:36.963Z,1645647636.963 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:20:36.963Z,1645647636.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:20:36.963Z,1645647636.963 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:20:37.356Z,1645647637.356 [Default:CheckIn:D] Stopped
2022-02-23T20:20:37.356Z,1645647637.356 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:20:37.756Z,1645647637.756 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.114616 min 
2022-02-23T20:20:37.757Z,1645647637.757 [Default:CheckIn:E] Stopped
2022-02-23T20:20:37.757Z,1645647637.757 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:20:37.757Z,1645647637.757 [Default:CheckIn] Stopped
2022-02-23T20:20:37.757Z,1645647637.757 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:20:37.757Z,1645647637.757 [Default:CheckIn](INFO): Running loop #30
2022-02-23T20:20:37.757Z,1645647637.757 [Default:CheckIn] Running Loop=30
2022-02-23T20:20:37.757Z,1645647637.757 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:20:37.757Z,1645647637.757 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:20:39.768Z,1645647639.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202039.00,A,3648.15142,N,12147.19099,W,2.333,36.39,230222,,,D*4D
2022-02-23T20:20:39.770Z,1645647639.770 [NAL9602](INFO): GPS fix at 20220223T202039: (36.802524, -121.786517)
2022-02-23T20:20:39.810Z,1645647639.810 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:20:39.810Z,1645647639.810 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:20:47.700Z,1645647647.700 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0097.lzma
2022-02-23T20:20:48.702Z,1645647648.702 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0097.lzma.bak
2022-02-23T20:20:48.703Z,1645647648.703 [DataOverHttps](INFO): SBD MOMSN=16512748
2022-02-23T20:20:56.384Z,1645647656.384 [NAL9602](INFO): SBD MO Status=0, MOMSN=31469, MT Status=0, MTMSN=0
2022-02-23T20:20:56.384Z,1645647656.384 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:21:07.972Z,1645647667.972 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220223T172545/Express0098.lzma
2022-02-23T20:21:08.977Z,1645647668.977 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0098.lzma.bak
2022-02-23T20:21:08.978Z,1645647668.978 [DataOverHttps](INFO): SBD MOMSN=16512751
2022-02-23T20:21:10.128Z,1645647670.128 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:21:10.128Z,1645647670.128 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:21:10.128Z,1645647670.128 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:21:27.085Z,1645647687.085 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:26:10.818Z,1645647970.818 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:26:10.818Z,1645647970.818 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:26:10.818Z,1645647970.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:26:10.818Z,1645647970.818 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:26:11.237Z,1645647971.237 [Default:CheckIn:D] Stopped
2022-02-23T20:26:11.237Z,1645647971.237 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:26:11.624Z,1645647971.624 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.679297 min 
2022-02-23T20:26:11.624Z,1645647971.624 [Default:CheckIn:E] Stopped
2022-02-23T20:26:11.624Z,1645647971.624 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:26:11.624Z,1645647971.624 [Default:CheckIn] Stopped
2022-02-23T20:26:11.624Z,1645647971.624 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:26:11.624Z,1645647971.624 [Default:CheckIn](INFO): Running loop #31
2022-02-23T20:26:11.625Z,1645647971.625 [Default:CheckIn] Running Loop=31
2022-02-23T20:26:11.625Z,1645647971.625 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:26:11.625Z,1645647971.625 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:26:13.636Z,1645647973.636 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202612.00,A,3648.41007,N,12147.11508,W,3.538,7.07,230222,,,D*75
2022-02-23T20:26:13.639Z,1645647973.639 [NAL9602](INFO): GPS fix at 20220223T202612: (36.806835, -121.785251)
2022-02-23T20:26:13.657Z,1645647973.657 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:26:13.657Z,1645647973.657 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:26:24.620Z,1645647984.620 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220223T172545/Courier0100.lzma
2022-02-23T20:26:25.623Z,1645647985.623 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0100.lzma.bak
2022-02-23T20:26:25.623Z,1645647985.623 [DataOverHttps](INFO): SBD MOMSN=16512755
2022-02-23T20:26:42.024Z,1645648002.024 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20220223T172545/Express0101.lzma
2022-02-23T20:26:43.026Z,1645648003.026 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0101.lzma.bak
2022-02-23T20:26:43.027Z,1645648003.027 [DataOverHttps](INFO): SBD MOMSN=16512758
2022-02-23T20:26:44.421Z,1645648004.421 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:26:44.421Z,1645648004.421 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:26:44.421Z,1645648004.421 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:26:52.099Z,1645648012.099 [NAL9602](INFO): SBD MO Status=0, MOMSN=31470, MT Status=0, MTMSN=0
2022-02-23T20:26:52.100Z,1645648012.100 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:27:22.801Z,1645648042.801 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:31:44.909Z,1645648304.909 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:31:44.909Z,1645648304.909 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:31:44.910Z,1645648304.910 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:31:44.910Z,1645648304.910 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:31:45.300Z,1645648305.300 [Default:CheckIn:D] Stopped
2022-02-23T20:31:45.300Z,1645648305.300 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:31:45.710Z,1645648305.710 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.247005 min 
2022-02-23T20:31:45.710Z,1645648305.710 [Default:CheckIn:E] Stopped
2022-02-23T20:31:45.710Z,1645648305.710 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:31:45.711Z,1645648305.711 [Default:CheckIn] Stopped
2022-02-23T20:31:45.711Z,1645648305.711 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:31:45.711Z,1645648305.711 [Default:CheckIn](INFO): Running loop #32
2022-02-23T20:31:45.711Z,1645648305.711 [Default:CheckIn] Running Loop=32
2022-02-23T20:31:45.711Z,1645648305.711 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:31:45.711Z,1645648305.711 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:31:47.723Z,1645648307.723 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203146.00,A,3648.41320,N,12147.40176,W,4.704,227.97,230222,,,D*7E
2022-02-23T20:31:47.726Z,1645648307.726 [NAL9602](INFO): GPS fix at 20220223T203146: (36.806887, -121.790029)
2022-02-23T20:31:47.736Z,1645648307.736 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:31:47.736Z,1645648307.736 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:31:55.420Z,1645648315.420 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0103.lzma
2022-02-23T20:31:56.422Z,1645648316.422 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0103.lzma.bak
2022-02-23T20:31:56.423Z,1645648316.423 [DataOverHttps](INFO): SBD MOMSN=16512762
2022-02-23T20:32:01.455Z,1645648321.455 [NAL9602](INFO): SBD MO Status=0, MOMSN=31471, MT Status=0, MTMSN=0
2022-02-23T20:32:01.456Z,1645648321.456 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:32:12.436Z,1645648332.436 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220223T172545/Express0104.lzma
2022-02-23T20:32:13.438Z,1645648333.438 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0104.lzma.bak
2022-02-23T20:32:13.439Z,1645648333.439 [DataOverHttps](INFO): SBD MOMSN=16512765
2022-02-23T20:32:14.807Z,1645648334.807 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:32:14.808Z,1645648334.808 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:32:14.808Z,1645648334.808 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:32:32.214Z,1645648352.214 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:37:15.324Z,1645648635.324 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:37:15.324Z,1645648635.324 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:37:15.324Z,1645648635.324 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:37:15.324Z,1645648635.324 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:37:15.741Z,1645648635.741 [Default:CheckIn:D] Stopped
2022-02-23T20:37:15.741Z,1645648635.741 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:37:16.132Z,1645648636.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 187.754362 min 
2022-02-23T20:37:16.132Z,1645648636.132 [Default:CheckIn:E] Stopped
2022-02-23T20:37:16.132Z,1645648636.132 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:37:16.132Z,1645648636.132 [Default:CheckIn] Stopped
2022-02-23T20:37:16.132Z,1645648636.132 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:37:16.133Z,1645648636.133 [Default:CheckIn](INFO): Running loop #33
2022-02-23T20:37:16.133Z,1645648636.133 [Default:CheckIn] Running Loop=33
2022-02-23T20:37:16.133Z,1645648636.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:37:16.133Z,1645648636.133 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:37:18.138Z,1645648638.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203717.00,A,3648.30442,N,12147.96389,W,5.968,269.54,230222,,,D*70
2022-02-23T20:37:18.140Z,1645648638.140 [NAL9602](INFO): GPS fix at 20220223T203717: (36.805074, -121.799398)
2022-02-23T20:37:18.152Z,1645648638.152 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:37:18.152Z,1645648638.152 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:37:25.784Z,1645648645.784 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0106.lzma
2022-02-23T20:37:26.787Z,1645648646.787 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0106.lzma.bak
2022-02-23T20:37:26.787Z,1645648646.787 [DataOverHttps](INFO): SBD MOMSN=16512769
2022-02-23T20:37:33.487Z,1645648653.487 [NAL9602](INFO): SBD MO Status=2, MOMSN=31472, MT Status=2, MTMSN=0
2022-02-23T20:37:33.487Z,1645648653.487 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:37:42.880Z,1645648662.880 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20220223T172545/Express0107.lzma
2022-02-23T20:37:43.883Z,1645648663.883 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0107.lzma.bak
2022-02-23T20:37:43.883Z,1645648663.883 [DataOverHttps](INFO): SBD MOMSN=16512772
2022-02-23T20:37:45.226Z,1645648665.226 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:37:45.226Z,1645648665.226 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:37:45.226Z,1645648665.226 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:37:53.283Z,1645648673.283 [NAL9602](INFO): SBD MO Status=0, MOMSN=31472, MT Status=0, MTMSN=0
2022-02-23T20:37:53.284Z,1645648673.284 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:38:24.041Z,1645648704.041 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:42:45.653Z,1645648965.653 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:42:45.653Z,1645648965.653 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:42:45.654Z,1645648965.654 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:42:45.654Z,1645648965.654 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:42:46.062Z,1645648966.062 [Default:CheckIn:D] Stopped
2022-02-23T20:42:46.062Z,1645648966.062 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:42:46.455Z,1645648966.455 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 193.259717 min 
2022-02-23T20:42:46.456Z,1645648966.456 [Default:CheckIn:E] Stopped
2022-02-23T20:42:46.456Z,1645648966.456 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:42:46.456Z,1645648966.456 [Default:CheckIn] Stopped
2022-02-23T20:42:46.456Z,1645648966.456 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:42:46.456Z,1645648966.456 [Default:CheckIn](INFO): Running loop #34
2022-02-23T20:42:46.456Z,1645648966.456 [Default:CheckIn] Running Loop=34
2022-02-23T20:42:46.456Z,1645648966.456 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:42:46.457Z,1645648966.457 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:42:48.476Z,1645648968.476 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204247.00,A,3648.26226,N,12148.60340,W,5.754,275.54,230222,,,D*7B
2022-02-23T20:42:48.478Z,1645648968.478 [NAL9602](INFO): GPS fix at 20220223T204247: (36.804371, -121.810057)
2022-02-23T20:42:48.489Z,1645648968.489 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:42:48.489Z,1645648968.489 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:43:01.616Z,1645648981.616 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0109.lzma
2022-02-23T20:43:02.619Z,1645648982.619 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0109.lzma.bak
2022-02-23T20:43:02.619Z,1645648982.619 [DataOverHttps](INFO): SBD MOMSN=16512776
2022-02-23T20:43:18.780Z,1645648998.780 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20220223T172545/Express0110.lzma
2022-02-23T20:43:19.783Z,1645648999.783 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0110.lzma.bak
2022-02-23T20:43:19.783Z,1645648999.783 [DataOverHttps](INFO): SBD MOMSN=16512779
2022-02-23T20:43:20.848Z,1645649000.848 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:43:20.848Z,1645649000.848 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:43:20.849Z,1645649000.849 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:44:08.915Z,1645649048.915 [NAL9602](INFO): SBD MO Status=2, MOMSN=31473, MT Status=2, MTMSN=0
2022-02-23T20:44:08.915Z,1645649048.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:44:52.841Z,1645649092.841 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2022-02-23T20:45:09.174Z,1645649109.174 [NAL9602](INFO): SBD MO Status=0, MOMSN=31473, MT Status=0, MTMSN=0
2022-02-23T20:45:09.174Z,1645649109.174 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:45:39.870Z,1645649139.870 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:48:21.513Z,1645649301.513 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:48:21.513Z,1645649301.513 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:48:21.513Z,1645649301.513 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:48:21.513Z,1645649301.513 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:48:21.894Z,1645649301.894 [Default:CheckIn:D] Stopped
2022-02-23T20:48:21.895Z,1645649301.895 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:48:22.322Z,1645649302.322 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 198.856917 min 
2022-02-23T20:48:22.322Z,1645649302.322 [Default:CheckIn:E] Stopped
2022-02-23T20:48:22.322Z,1645649302.322 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:48:22.322Z,1645649302.322 [Default:CheckIn] Stopped
2022-02-23T20:48:22.322Z,1645649302.322 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:48:22.323Z,1645649302.323 [Default:CheckIn](INFO): Running loop #35
2022-02-23T20:48:22.323Z,1645649302.323 [Default:CheckIn] Running Loop=35
2022-02-23T20:48:22.323Z,1645649302.323 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:48:22.323Z,1645649302.323 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:48:24.396Z,1645649304.396 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204823.00,A,3648.32509,N,12149.22885,W,4.743,283.61,230222,,,A*74
2022-02-23T20:48:24.399Z,1645649304.399 [NAL9602](INFO): GPS fix at 20220223T204823: (36.805418, -121.820481)
2022-02-23T20:48:24.446Z,1645649304.446 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:48:24.446Z,1645649304.446 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:48:34.572Z,1645649314.572 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0112.lzma
2022-02-23T20:48:35.574Z,1645649315.574 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0112.lzma.bak
2022-02-23T20:48:35.575Z,1645649315.575 [DataOverHttps](INFO): SBD MOMSN=16512783
2022-02-23T20:48:52.703Z,1645649332.703 [NAL9602](INFO): SBD MO Status=0, MOMSN=31474, MT Status=0, MTMSN=0
2022-02-23T20:48:52.704Z,1645649332.704 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:48:55.016Z,1645649335.016 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220223T172545/Express0113.lzma
2022-02-23T20:48:56.019Z,1645649336.019 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0113.lzma.bak
2022-02-23T20:48:56.019Z,1645649336.019 [DataOverHttps](INFO): SBD MOMSN=16512786
2022-02-23T20:48:57.139Z,1645649337.139 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:48:57.140Z,1645649337.140 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:48:57.140Z,1645649337.140 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:49:23.390Z,1645649363.390 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:53:57.731Z,1645649637.731 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:53:57.731Z,1645649637.731 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:53:57.731Z,1645649637.731 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:53:57.731Z,1645649637.731 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:53:58.155Z,1645649638.155 [Default:CheckIn:D] Stopped
2022-02-23T20:53:58.155Z,1645649638.155 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:53:58.593Z,1645649638.593 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 204.460807 min 
2022-02-23T20:53:58.593Z,1645649638.593 [Default:CheckIn:E] Stopped
2022-02-23T20:53:58.593Z,1645649638.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:53:58.593Z,1645649638.593 [Default:CheckIn] Stopped
2022-02-23T20:53:58.593Z,1645649638.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:53:58.593Z,1645649638.593 [Default:CheckIn](INFO): Running loop #36
2022-02-23T20:53:58.593Z,1645649638.593 [Default:CheckIn] Running Loop=36
2022-02-23T20:53:58.594Z,1645649638.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:53:58.594Z,1645649638.594 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:54:00.556Z,1645649640.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205359.00,A,3648.37876,N,12149.38607,W,0.797,103.36,230222,,,A*78
2022-02-23T20:54:00.559Z,1645649640.559 [NAL9602](INFO): GPS fix at 20220223T205359: (36.806313, -121.823101)
2022-02-23T20:54:00.570Z,1645649640.570 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:54:00.570Z,1645649640.570 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:54:09.968Z,1645649649.968 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220223T172545/Courier0115.lzma
2022-02-23T20:54:10.971Z,1645649650.971 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0115.lzma.bak
2022-02-23T20:54:10.971Z,1645649650.971 [DataOverHttps](INFO): SBD MOMSN=16512790
2022-02-23T20:54:23.595Z,1645649663.595 [NAL9602](INFO): SBD MO Status=2, MOMSN=31475, MT Status=2, MTMSN=0
2022-02-23T20:54:23.596Z,1645649663.596 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:54:26.836Z,1645649666.836 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-02-23T20:54:26.843Z,1645649666.843 [BPC1](INFO): Received data from all battery sticks.
2022-02-23T20:54:30.496Z,1645649670.496 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220223T172545/Express0116.lzma
2022-02-23T20:54:31.499Z,1645649671.499 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0116.lzma.bak
2022-02-23T20:54:31.499Z,1645649671.499 [DataOverHttps](INFO): SBD MOMSN=16512793
2022-02-23T20:54:32.530Z,1645649672.530 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T20:54:32.530Z,1645649672.530 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T20:54:32.530Z,1645649672.530 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T20:54:38.956Z,1645649678.956 [NAL9602](INFO): SBD MO Status=2, MOMSN=31475, MT Status=2, MTMSN=0
2022-02-23T20:54:38.956Z,1645649678.956 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:54:55.525Z,1645649695.525 [NAL9602](INFO): SBD MO Status=2, MOMSN=31475, MT Status=2, MTMSN=0
2022-02-23T20:54:55.525Z,1645649695.525 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:55:54.107Z,1645649754.107 [NAL9602](INFO): SBD MO Status=2, MOMSN=31475, MT Status=2, MTMSN=0
2022-02-23T20:55:54.108Z,1645649754.108 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-02-23T20:56:13.499Z,1645649773.499 [NAL9602](INFO): SBD MO Status=0, MOMSN=31475, MT Status=0, MTMSN=0
2022-02-23T20:56:13.499Z,1645649773.499 [NAL9602](INFO): No messages in MT queue
2022-02-23T20:56:44.197Z,1645649804.197 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T20:59:33.144Z,1645649973.144 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T20:59:33.144Z,1645649973.144 [Default:CheckIn:C.Wait] Stopped
2022-02-23T20:59:33.145Z,1645649973.145 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T20:59:33.145Z,1645649973.145 [Default:CheckIn:D] Running Loop=1
2022-02-23T20:59:33.537Z,1645649973.537 [Default:CheckIn:D] Stopped
2022-02-23T20:59:33.538Z,1645649973.538 [Default:CheckIn:E] Running Loop=1
2022-02-23T20:59:33.940Z,1645649973.940 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 210.050977 min 
2022-02-23T20:59:33.941Z,1645649973.941 [Default:CheckIn:E] Stopped
2022-02-23T20:59:33.941Z,1645649973.941 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T20:59:33.941Z,1645649973.941 [Default:CheckIn] Stopped
2022-02-23T20:59:33.941Z,1645649973.941 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T20:59:33.941Z,1645649973.941 [Default:CheckIn](INFO): Running loop #37
2022-02-23T20:59:33.941Z,1645649973.941 [Default:CheckIn] Running Loop=37
2022-02-23T20:59:33.941Z,1645649973.941 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T20:59:33.941Z,1645649973.941 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T20:59:35.949Z,1645649975.949 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205935.00,A,3648.40926,N,12149.33751,W,0.214,64.27,230222,,,A*4B
2022-02-23T20:59:35.951Z,1645649975.951 [NAL9602](INFO): GPS fix at 20220223T205935: (36.806821, -121.822292)
2022-02-23T20:59:35.962Z,1645649975.962 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T20:59:35.962Z,1645649975.962 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T20:59:58.660Z,1645649998.660 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20220223T172545/Courier0118.lzma
2022-02-23T20:59:59.662Z,1645649999.662 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0118.lzma.bak
2022-02-23T20:59:59.663Z,1645649999.663 [DataOverHttps](INFO): SBD MOMSN=16512797
2022-02-23T21:00:00.191Z,1645650000.191 [NAL9602](INFO): SBD MO Status=0, MOMSN=31476, MT Status=0, MTMSN=0
2022-02-23T21:00:00.191Z,1645650000.191 [NAL9602](INFO): No messages in MT queue
2022-02-23T21:00:15.680Z,1645650015.680 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220223T172545/Express0119.lzma
2022-02-23T21:00:16.683Z,1645650016.683 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Express0119.lzma.bak
2022-02-23T21:00:16.683Z,1645650016.683 [DataOverHttps](INFO): SBD MOMSN=16512801
2022-02-23T21:00:17.982Z,1645650017.982 [Default:CheckIn:Read_Iridium] Stopped
2022-02-23T21:00:17.982Z,1645650017.982 [Default:CheckIn:C.Wait] Running Loop=1
2022-02-23T21:00:17.982Z,1645650017.982 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-02-23T21:00:30.946Z,1645650030.946 [NAL9602](INFO): Not Powering down - fast GPS
2022-02-23T21:05:18.419Z,1645650318.419 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-02-23T21:05:18.420Z,1645650318.420 [Default:CheckIn:C.Wait] Stopped
2022-02-23T21:05:18.420Z,1645650318.420 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-02-23T21:05:18.420Z,1645650318.420 [Default:CheckIn:D] Running Loop=1
2022-02-23T21:05:18.822Z,1645650318.822 [Default:CheckIn:D] Stopped
2022-02-23T21:05:18.822Z,1645650318.822 [Default:CheckIn:E] Running Loop=1
2022-02-23T21:05:19.237Z,1645650319.237 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 215.805713 min 
2022-02-23T21:05:19.237Z,1645650319.237 [Default:CheckIn:E] Stopped
2022-02-23T21:05:19.237Z,1645650319.237 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-02-23T21:05:19.237Z,1645650319.237 [Default:CheckIn] Stopped
2022-02-23T21:05:19.237Z,1645650319.237 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-02-23T21:05:19.238Z,1645650319.238 [Default:CheckIn](INFO): Running loop #38
2022-02-23T21:05:19.238Z,1645650319.238 [Default:CheckIn] Running Loop=38
2022-02-23T21:05:19.238Z,1645650319.238 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-02-23T21:05:19.238Z,1645650319.238 [Default:CheckIn:Read_GPS] Running Loop=1
2022-02-23T21:05:21.236Z,1645650321.236 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210520.00,A,3648.43308,N,12149.25930,W,0.583,95.29,230222,,,D*40
2022-02-23T21:05:21.239Z,1645650321.239 [NAL9602](INFO): GPS fix at 20220223T210520: (36.807218, -121.820988)
2022-02-23T21:05:21.250Z,1645650321.250 [Default:CheckIn:Read_GPS] Stopped
2022-02-23T21:05:21.250Z,1645650321.250 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-02-23T21:05:41.040Z,1645650341.040 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220223T172545/Courier0121.lzma
2022-02-23T21:05:42.043Z,1645650342.043 [DataOverHttps](INFO): Moved sent file to Logs/20220223T172545/Courier0121.lzma.bak
2022-02-23T21:05:42.043Z,1645650342.043 [DataOverHttps](IMPORTANT): SBD MOMSN=16512807, MTMSN=20220223T210541
2022-02-23T21:05:44.672Z,1645650344.672 [NAL9602](INFO): SBD MO Status=0, MOMSN=31477, MT Status=0, MTMSN=0
2022-02-23T21:05:44.672Z,1645650344.672 [NAL9602](INFO): No messages in MT queue
2022-02-23T21:05:49.484Z,1645650349.484 [DataOverHttps](INFO): Received command: restart logs