2024-04-15T15:00:38.620Z,1713193238.620 [Supervisor](DEBUG): Initializing supervisor.
2024-04-15T15:00:38.625Z,1713193238.625 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-04-15T15:00:38.625Z,1713193238.625 [SyncHandler](INFO): Protected caller Thread ID is 836
2024-04-15T15:00:38.626Z,1713193238.626 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-04-15T15:00:38.627Z,1713193238.627 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-04-15T15:00:38.627Z,1713193238.627 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2024-04-15T15:00:38.631Z,1713193238.631 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-04-15T15:00:38.649Z,1713193238.649 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-04-15T15:00:38.650Z,1713193238.650 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-04-15T15:00:38.650Z,1713193238.650 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2024-04-15T15:00:38.654Z,1713193238.654 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-04-15T15:00:38.655Z,1713193238.655 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-04-15T15:00:38.656Z,1713193238.656 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2024-04-15T15:00:38.658Z,1713193238.658 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-04-15T15:00:38.659Z,1713193238.659 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-04-15T15:00:38.659Z,1713193238.659 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2024-04-15T15:00:38.663Z,1713193238.663 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-04-15T15:00:38.663Z,1713193238.663 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-04-15T15:00:38.667Z,1713193238.667 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-04-15T15:00:38.951Z,1713193238.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-04-15T15:00:38.953Z,1713193238.953 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-04-15T15:00:39.047Z,1713193239.047 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-04-15T15:00:39.622Z,1713193239.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-04-15T15:00:39.624Z,1713193239.624 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-04-15T15:00:40.007Z,1713193240.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-04-15T15:00:40.009Z,1713193240.009 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-04-15T15:00:40.117Z,1713193240.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-04-15T15:00:40.119Z,1713193240.119 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-04-15T15:00:40.548Z,1713193240.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-04-15T15:00:40.550Z,1713193240.550 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-04-15T15:00:40.781Z,1713193240.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-04-15T15:00:40.783Z,1713193240.783 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-04-15T15:00:41.328Z,1713193241.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-04-15T15:00:41.330Z,1713193241.330 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-04-15T15:00:41.478Z,1713193241.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-04-15T15:00:41.480Z,1713193241.480 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-04-15T15:00:41.571Z,1713193241.571 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-04-15T15:00:42.806Z,1713193242.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-04-15T15:00:42.806Z,1713193242.806 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-04-15T15:00:43.548Z,1713193243.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-04-15T15:00:43.550Z,1713193243.550 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-04-15T15:00:43.760Z,1713193243.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-04-15T15:00:43.760Z,1713193243.760 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-04-15T15:00:43.885Z,1713193243.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-04-15T15:00:43.886Z,1713193243.886 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-04-15T15:00:44.130Z,1713193244.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-04-15T15:00:44.132Z,1713193244.132 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-04-15T15:00:44.400Z,1713193244.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-04-15T15:00:44.401Z,1713193244.401 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-04-15T15:00:44.404Z,1713193244.404 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-04-15T15:00:44.508Z,1713193244.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-04-15T15:00:44.605Z,1713193244.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-04-15T15:00:44.720Z,1713193244.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-04-15T15:00:44.817Z,1713193244.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-04-15T15:00:44.933Z,1713193244.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-04-15T15:00:45.038Z,1713193245.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-04-15T15:00:45.165Z,1713193245.165 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-04-15T15:00:45.327Z,1713193245.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-04-15T15:00:45.480Z,1713193245.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-04-15T15:00:45.604Z,1713193245.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-04-15T15:00:45.881Z,1713193245.881 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-04-15T15:00:45.882Z,1713193245.882 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2024-04-15T15:00:45.894Z,1713193245.894 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-04-15T15:00:45.965Z,1713193245.965 [VerticalControl](DEBUG): Construct VerticalControl.
2024-04-15T15:00:46.041Z,1713193246.041 [VerticalControl] Loaded
2024-04-15T15:00:46.041Z,1713193246.041 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-04-15T15:00:46.044Z,1713193246.044 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-04-15T15:00:46.088Z,1713193246.088 [HorizontalControl] Loaded
2024-04-15T15:00:46.088Z,1713193246.088 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-04-15T15:00:46.090Z,1713193246.090 [SpeedControl](DEBUG): Construct SpeedControl.
2024-04-15T15:00:46.093Z,1713193246.093 [SpeedControl] Loaded
2024-04-15T15:00:46.094Z,1713193246.094 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-04-15T15:00:46.096Z,1713193246.096 [LoopControl](DEBUG): Construct LoopControl.
2024-04-15T15:00:46.097Z,1713193246.097 [LoopControl] Loaded
2024-04-15T15:00:46.097Z,1713193246.097 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-04-15T15:00:46.097Z,1713193246.097 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-04-15T15:00:46.098Z,1713193246.098 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-04-15T15:00:46.114Z,1713193246.114 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-04-15T15:00:46.115Z,1713193246.115 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-04-15T15:00:46.331Z,1713193246.331 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-04-15T15:00:46.332Z,1713193246.332 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-04-15T15:00:46.519Z,1713193246.519 [BuoyancyServo] Loaded
2024-04-15T15:00:46.519Z,1713193246.519 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-04-15T15:00:46.539Z,1713193246.539 [ElevatorServo] Loaded
2024-04-15T15:00:46.540Z,1713193246.540 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-04-15T15:00:46.559Z,1713193246.559 [MassServo] Loaded
2024-04-15T15:00:46.559Z,1713193246.559 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-04-15T15:00:46.577Z,1713193246.577 [RudderServo] Loaded
2024-04-15T15:00:46.578Z,1713193246.578 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-04-15T15:00:46.592Z,1713193246.592 [ThrusterHE] Loaded
2024-04-15T15:00:46.592Z,1713193246.592 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-04-15T15:00:46.593Z,1713193246.593 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-04-15T15:00:46.593Z,1713193246.593 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-04-15T15:00:46.734Z,1713193246.734 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-04-15T15:00:46.734Z,1713193246.734 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-04-15T15:00:46.748Z,1713193246.748 [NavChart] Loaded
2024-04-15T15:00:46.748Z,1713193246.748 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-04-15T15:00:46.754Z,1713193246.754 [UniversalFixResidualReporter] Loaded
2024-04-15T15:00:46.754Z,1713193246.754 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-04-15T15:00:46.755Z,1713193246.755 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-04-15T15:00:46.756Z,1713193246.756 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-04-15T15:00:46.841Z,1713193246.841 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-04-15T15:00:46.842Z,1713193246.842 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-04-15T15:00:47.608Z,1713193247.608 [AHRS_M2] Loaded
2024-04-15T15:00:47.608Z,1713193247.608 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-04-15T15:00:48.426Z,1713193248.426 [BPC1] Loaded
2024-04-15T15:00:48.426Z,1713193248.426 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-04-15T15:00:48.759Z,1713193248.759 [DataOverHttps] Loaded
2024-04-15T15:00:48.759Z,1713193248.759 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-04-15T15:00:48.760Z,1713193248.760 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2024-04-15T15:00:48.761Z,1713193248.761 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2024-04-15T15:00:48.782Z,1713193248.782 [Depth_Keller] Loaded
2024-04-15T15:00:48.782Z,1713193248.782 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-04-15T15:00:48.806Z,1713193248.806 [DropWeight] Loaded
2024-04-15T15:00:48.807Z,1713193248.807 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-04-15T15:00:49.079Z,1713193249.079 [NAL9602] Loaded
2024-04-15T15:00:49.094Z,1713193249.094 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-04-15T15:00:49.150Z,1713193249.150 [Onboard] Loaded
2024-04-15T15:00:49.151Z,1713193249.151 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-04-15T15:00:49.152Z,1713193249.152 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409604E0
2024-04-15T15:00:49.152Z,1713193249.152 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2024-04-15T15:00:49.165Z,1713193249.165 [Power24vConverter] Loaded
2024-04-15T15:00:49.166Z,1713193249.166 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-04-15T15:00:49.179Z,1713193249.179 [Radio_Surface] Loaded
2024-04-15T15:00:49.179Z,1713193249.179 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-04-15T15:00:49.180Z,1713193249.180 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409904E0
2024-04-15T15:00:49.181Z,1713193249.181 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2024-04-15T15:00:49.195Z,1713193249.195 [Sonardyne_Nano] Loaded
2024-04-15T15:00:49.195Z,1713193249.195 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-04-15T15:00:49.292Z,1713193249.292 [DAT] Loaded
2024-04-15T15:00:49.293Z,1713193249.293 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-04-15T15:00:49.294Z,1713193249.294 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C04E0
2024-04-15T15:00:49.294Z,1713193249.294 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925
2024-04-15T15:00:49.295Z,1713193249.295 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-04-15T15:00:49.295Z,1713193249.295 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-04-15T15:00:49.366Z,1713193249.366 [DepthRateCalculator] Loaded
2024-04-15T15:00:49.367Z,1713193249.367 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-04-15T15:00:49.372Z,1713193249.372 [PitchRateCalculator] Loaded
2024-04-15T15:00:49.372Z,1713193249.372 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-04-15T15:00:49.381Z,1713193249.381 [SpeedCalculator] Loaded
2024-04-15T15:00:49.381Z,1713193249.381 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-04-15T15:00:49.386Z,1713193249.386 [YawRateCalculator] Loaded
2024-04-15T15:00:49.386Z,1713193249.386 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-04-15T15:00:49.405Z,1713193249.405 [ElevatorOffsetCalculator] Loaded
2024-04-15T15:00:49.405Z,1713193249.405 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-04-15T15:00:49.406Z,1713193249.406 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-04-15T15:00:49.407Z,1713193249.407 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-04-15T15:00:49.591Z,1713193249.591 [CANONSampler] Loaded
2024-04-15T15:00:49.591Z,1713193249.591 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-04-15T15:00:49.652Z,1713193249.652 [CTD_Seabird] Loaded
2024-04-15T15:00:49.653Z,1713193249.653 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-04-15T15:00:49.654Z,1713193249.654 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7C4E0
2024-04-15T15:00:49.654Z,1713193249.654 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926
2024-04-15T15:00:49.674Z,1713193249.674 [PAR_Licor] Loaded
2024-04-15T15:00:49.674Z,1713193249.674 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-04-15T15:00:49.720Z,1713193249.720 [WetLabsBB2FL] Loaded
2024-04-15T15:00:49.720Z,1713193249.720 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-04-15T15:00:49.721Z,1713193249.721 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAC4E0
2024-04-15T15:00:49.721Z,1713193249.721 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927
2024-04-15T15:00:49.722Z,1713193249.722 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-04-15T15:00:49.723Z,1713193249.723 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-04-15T15:00:49.766Z,1713193249.766 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-04-15T15:00:49.767Z,1713193249.767 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-04-15T15:00:50.178Z,1713193250.178 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-04-15T15:00:50.180Z,1713193250.180 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-04-15T15:00:50.356Z,1713193250.356 [SBIT](DEBUG): Construct Startup Built In Test.
2024-04-15T15:00:50.365Z,1713193250.365 [SBIT] Loaded
2024-04-15T15:00:50.366Z,1713193250.366 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-04-15T15:00:50.369Z,1713193250.369 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-04-15T15:00:50.382Z,1713193250.382 [IBIT] Loaded
2024-04-15T15:00:50.382Z,1713193250.382 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-04-15T15:00:50.388Z,1713193250.388 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-04-15T15:00:50.495Z,1713193250.495 [CBIT] Loaded
2024-04-15T15:00:50.495Z,1713193250.495 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-04-15T15:00:50.496Z,1713193250.496 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-04-15T15:00:50.496Z,1713193250.496 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-04-15T15:00:50.643Z,1713193250.643 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-04-15T15:00:50.649Z,1713193250.649 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-04-15T15:00:50.652Z,1713193250.652 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-04-15T15:00:50.664Z,1713193250.664 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-04-15T15:00:50.665Z,1713193250.665 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0
2024-04-15T15:00:50.665Z,1713193250.665 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2024-04-15T15:00:50.669Z,1713193250.669 [Supervisor](INFO): Main Thread ID is 829
2024-04-15T15:00:50.670Z,1713193250.670 [Supervisor](DEBUG): Running supervisor.
2024-04-15T15:00:50.670Z,1713193250.670 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2024-04-15T15:00:50.671Z,1713193250.671 [CommandExec](INFO): Initializing the command executive.
2024-04-15T15:00:50.672Z,1713193250.672 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2024-04-15T15:00:50.674Z,1713193250.674 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2024-04-15T15:00:50.675Z,1713193250.675 [controlThread](DEBUG): Initializing ControlThread
2024-04-15T15:00:50.676Z,1713193250.676 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-04-15T15:00:50.677Z,1713193250.677 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-04-15T15:00:50.678Z,1713193250.678 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-04-15T15:00:50.679Z,1713193250.679 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-04-15T15:00:50.680Z,1713193250.680 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-04-15T15:00:50.681Z,1713193250.681 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-04-15T15:00:50.685Z,1713193250.685 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-04-15T15:00:50.686Z,1713193250.686 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-04-15T15:00:50.686Z,1713193250.686 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-04-15T15:00:50.686Z,1713193250.686 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-04-15T15:00:50.687Z,1713193250.687 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-04-15T15:00:50.692Z,1713193250.692 [SBIT](INFO): Initialize SBIT Component.
2024-04-15T15:00:50.692Z,1713193250.692 [SBIT](IMPORTANT): git: 2024-04-12
2024-04-15T15:00:50.692Z,1713193250.692 [SBIT](INFO): git hash: d6923c042de9d8b8dc285b2f6044b9806cc7806b
2024-04-15T15:00:50.693Z,1713193250.693 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-04-15T15:00:50.694Z,1713193250.694 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-04-15T15:00:50.695Z,1713193250.695 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2024-04-15T15:00:50.695Z,1713193250.695 [IBIT](INFO): Initialize IBIT Component.
2024-04-15T15:00:50.696Z,1713193250.696 [CBIT](DEBUG): Initialize CBIT Component.
2024-04-15T15:00:50.697Z,1713193250.697 [logger ThreadHandler](INFO): Handler Thread ID is 932
2024-04-15T15:00:50.707Z,1713193250.707 [CBIT](DEBUG): Initialized mux pins.
2024-04-15T15:00:50.708Z,1713193250.708 [CBIT](DEBUG): Initializing the watchdog timer.
2024-04-15T15:00:50.716Z,1713193250.716 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 933
2024-04-15T15:00:50.718Z,1713193250.718 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-04-15T15:00:50.728Z,1713193250.728 [Onboard ThreadHandler](INFO): Handler Thread ID is 934
2024-04-15T15:00:50.732Z,1713193250.732 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-04-15T15:00:50.733Z,1713193250.733 [CBIT](DEBUG): Initializing heartbeat.
2024-04-15T15:00:50.749Z,1713193250.749 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 935
2024-04-15T15:00:50.760Z,1713193250.760 [DAT ThreadHandler](INFO): Handler Thread ID is 936
2024-04-15T15:00:50.761Z,1713193250.761 [DAT](INFO): Powering up
2024-04-15T15:00:50.761Z,1713193250.761 [DAT](DEBUG): Initializing DAT.
2024-04-15T15:00:50.765Z,1713193250.765 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938
2024-04-15T15:00:50.765Z,1713193250.765 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-04-15T15:00:50.769Z,1713193250.769 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940
2024-04-15T15:00:50.772Z,1713193250.772 [WetLabsBB2FL](INFO): Powering up
2024-04-15T15:00:50.774Z,1713193250.774 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942
2024-04-15T15:00:50.784Z,1713193250.784 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-04-15T15:00:50.784Z,1713193250.784 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-04-15T15:00:50.784Z,1713193250.784 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-04-15T15:00:50.784Z,1713193250.784 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-04-15T15:00:50.784Z,1713193250.784 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-04-15T15:00:50.784Z,1713193250.784 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-04-15T15:00:50.785Z,1713193250.785 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-04-15T15:00:50.785Z,1713193250.785 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-04-15T15:00:50.803Z,1713193250.803 [CBIT](DEBUG): Deactivating GF circuits.
2024-04-15T15:00:50.804Z,1713193250.804 [CBIT](DEBUG): Deactivating emergency mode.
2024-04-15T15:00:50.843Z,1713193250.843 [CBIT](DEBUG): Backplane powered.
2024-04-15T15:00:50.844Z,1713193250.844 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-04-15T15:00:50.845Z,1713193250.845 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-04-15T15:00:50.845Z,1713193250.845 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-04-15T15:00:50.852Z,1713193250.852 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-04-15T15:00:50.904Z,1713193250.904 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-04-15T15:00:50.924Z,1713193250.924 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-15T15:00:50.948Z,1713193250.948 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-04-15T15:00:50.949Z,1713193250.949 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-04-15T15:00:50.949Z,1713193250.949 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-04-15T15:00:50.953Z,1713193250.953 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-04-15T15:00:51.151Z,1713193251.151 [Radio_Surface](INFO): Powering up
2024-04-15T15:00:51.242Z,1713193251.242 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-04-15T15:00:51.268Z,1713193251.268 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-04-15T15:00:51.270Z,1713193251.270 [Default:A.Wait](DEBUG): Construct Wait.
2024-04-15T15:00:51.290Z,1713193251.290 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-15T15:00:51.335Z,1713193251.335 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-04-15T15:00:51.338Z,1713193251.338 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-04-15T15:00:51.364Z,1713193251.364 [Default:E.Execute](DEBUG): Construct Execute.
2024-04-15T15:00:51.367Z,1713193251.367 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-04-15T15:00:51.388Z,1713193251.388 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-04-15T15:00:51.411Z,1713193251.411 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-04-15T15:00:51.569Z,1713193251.569 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m
2024-04-15T15:00:51.570Z,1713193251.570 [Power24vConverter](INFO): Powering up.
2024-04-15T15:00:51.571Z,1713193251.571 [Sonardyne_Nano](INFO): Initializing.
2024-04-15T15:00:51.592Z,1713193251.592 [CANONSampler](INFO): Powering down
2024-04-15T15:00:51.620Z,1713193251.620 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-04-15T15:00:51.645Z,1713193251.645 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-04-15T15:00:51.652Z,1713193251.652 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-04-15T15:00:51.653Z,1713193251.653 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-04-15T15:00:51.660Z,1713193251.660 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-04-15T15:00:51.661Z,1713193251.661 [MassServo](DEBUG): Initializing EZServoServo.
2024-04-15T15:00:51.668Z,1713193251.668 [MassServo](DEBUG): Initializing MassServo.
2024-04-15T15:00:51.668Z,1713193251.668 [RudderServo](DEBUG): Initializing EZServoServo.
2024-04-15T15:00:51.676Z,1713193251.676 [RudderServo](DEBUG): Initializing RudderServo.
2024-04-15T15:00:51.677Z,1713193251.677 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-04-15T15:00:51.684Z,1713193251.684 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-04-15T15:00:52.879Z,1713193252.879 [WetLabsBB2FL](INFO): Powering down
2024-04-15T15:00:53.822Z,1713193253.822 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-04-15T15:00:55.064Z,1713193255.064 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2024-04-15T15:00:55.467Z,1713193255.467 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T15:00:57.300Z,1713193257.300 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-04-15T15:00:57.796Z,1713193257.796 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-04-15T15:01:03.360Z,1713193263.360 [DAT](INFO): DAT read:
2024-04-15T15:01:03.361Z,1713193263.361 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-04-15T15:01:05.376Z,1713193265.376 [DAT](INFO): DAT read: MF Frequency Band
2024-04-15T15:01:05.378Z,1713193265.378 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-04-15T15:01:05.378Z,1713193265.378 [DAT](INFO): DAT read: Apr 15 2024 15:00:31
2024-04-15T15:01:06.386Z,1713193266.386 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-04-15T15:01:06.387Z,1713193266.387 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-04-15T15:01:06.387Z,1713193266.387 [DAT](INFO): commRate: 1200
2024-04-15T15:01:08.451Z,1713193268.451 [DAT](INFO): entering command mode
2024-04-15T15:01:08.652Z,1713193268.652 [DAT](INFO): DAT read:
2024-04-15T15:01:08.653Z,1713193268.653 [DAT](INFO): DAT read: user:1>
2024-04-15T15:01:08.653Z,1713193268.653 [DAT](INFO): setting verbose to 3
2024-04-15T15:01:08.904Z,1713193268.904 [DAT](INFO): DAT read: user:1>
2024-04-15T15:01:08.905Z,1713193268.905 [DAT](INFO): DAT read: Verbose | 3
2024-04-15T15:01:08.906Z,1713193268.906 [DAT](INFO): set verbose to 3
2024-04-15T15:01:08.906Z,1713193268.906 [DAT](INFO): setting DatVerbose to 27440
2024-04-15T15:01:09.156Z,1713193269.156 [DAT](INFO): DAT read: user:2>
2024-04-15T15:01:09.157Z,1713193269.157 [DAT](INFO): DAT read: DatVerbose | 27440
2024-04-15T15:01:09.157Z,1713193269.157 [DAT](INFO): set DatVerbose to 27440
2024-04-15T15:01:09.158Z,1713193269.158 [DAT](INFO): setting transmit power to 8
2024-04-15T15:01:09.408Z,1713193269.408 [DAT](INFO): DAT read: user:3>
2024-04-15T15:01:09.409Z,1713193269.409 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-04-15T15:01:09.409Z,1713193269.409 [DAT](INFO): set transmit power to 8
2024-04-15T15:01:09.410Z,1713193269.410 [DAT](INFO): setting local address to 8
2024-04-15T15:01:09.660Z,1713193269.660 [DAT](INFO): DAT read: user:4>
2024-04-15T15:01:09.661Z,1713193269.661 [DAT](INFO): DAT read: LocalAddr | 8
2024-04-15T15:01:09.661Z,1713193269.661 [DAT](INFO): set local address to 8
2024-04-15T15:01:09.663Z,1713193269.663 [DAT](INFO): Setting time to: 15:1:9 And date to:4/15/2024
2024-04-15T15:01:09.912Z,1713193269.912 [DAT](INFO): DAT read: user:5>
2024-04-15T15:01:09.913Z,1713193269.913 [DAT](INFO): DAT read: Mon Apr 15, 2024 15:01:09
2024-04-15T15:01:09.913Z,1713193269.913 [DAT](INFO): Local DAT time set to Mon Apr 15, 2024 15:01:09
2024-04-15T15:01:19.278Z,1713193279.278 [NAL9602](INFO): Powering up NAL9602
2024-04-15T15:01:30.185Z,1713193290.185 [NAL9602](INFO): NAL9602 initialized
2024-04-15T15:01:40.317Z,1713193300.317 [SBIT](IMPORTANT): Beginning Startup BIT
2024-04-15T15:01:40.326Z,1713193300.326 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-15T15:01:51.220Z,1713193311.220 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005471
CHAN A1 (24V): 0.000530
CHAN A2 (12V): -0.002073
CHAN A3 (5V): -0.001177
CHAN B0 (3.3V): -0.000231
CHAN B1 (3.15aV): -0.000001
CHAN B2 (3.15bV): -0.000072
CHAN B3 (GND): 0.000299
OPEN: -0.000556
Full Scale: +/- 1 mA
2024-04-15T15:02:34.438Z,1713193354.438 [SBIT](IMPORTANT): SBIT PASSED
2024-04-15T15:02:34.438Z,1713193354.438 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-15T15:02:34.439Z,1713193354.439 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-04-15T15:02:34.439Z,1713193354.439 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-04-15T15:02:34.440Z,1713193354.440 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=186 cubic_centimeter;
2024-04-15T15:02:34.440Z,1713193354.440 [SBIT](IMPORTANT): VerticalControl.massDefault=7.5 millimeter;
2024-04-15T15:02:34.843Z,1713193354.843 [MissionManager](IMPORTANT): Started mission Startup
2024-04-15T15:02:34.843Z,1713193354.843 [Startup] Running Loop=1
2024-04-15T15:02:34.843Z,1713193354.843 [Startup](DEBUG): Aggregate::initialize Startup
2024-04-15T15:02:34.844Z,1713193354.844 [Startup:A.GoToSurface] Running Loop=1
2024-04-15T15:02:34.844Z,1713193354.844 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-15T15:02:34.844Z,1713193354.844 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-15T15:02:34.845Z,1713193354.845 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-15T15:02:34.845Z,1713193354.845 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-15T15:02:34.845Z,1713193354.845 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-15T15:02:34.846Z,1713193354.846 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-15T15:02:34.847Z,1713193354.847 [Startup:StartupSatComms] Running Loop=1
2024-04-15T15:02:34.848Z,1713193354.848 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-04-15T15:02:34.848Z,1713193354.848 [Startup:StartupSatComms:A] Running Loop=1
2024-04-15T15:02:35.242Z,1713193355.242 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-04-15T15:03:20.706Z,1713193400.706 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004968
2024-04-15T15:03:35.237Z,1713193415.237 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-15T15:02:34.8Z
2024-04-15T15:03:35.237Z,1713193415.237 [Startup:StartupSatComms:A] Stopped
2024-04-15T15:03:35.237Z,1713193415.237 [Startup:StartupSatComms:B] Running Loop=1
2024-04-15T15:03:35.625Z,1713193415.625 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-15T15:03:42.411Z,1713193422.411 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240415T145908/Courier0000.lzma
2024-04-15T15:03:43.413Z,1713193423.413 [DataOverHttps](INFO): Moved sent file to Logs/20240415T145908/Courier0000.lzma.bak
2024-04-15T15:03:43.413Z,1713193423.413 [DataOverHttps](INFO): SBD MOMSN=19407856
2024-04-15T15:03:50.980Z,1713193430.980 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-04-15T15:03:50.980Z,1713193430.980 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:03:51.016Z,1713193431.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:03:51.398Z,1713193431.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:03:51.398Z,1713193431.398 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-04-15T15:03:59.246Z,1713193439.246 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240415T150038/Courier0000.lzma
2024-04-15T15:04:00.249Z,1713193440.249 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0000.lzma.bak
2024-04-15T15:04:00.250Z,1713193440.250 [DataOverHttps](INFO): SBD MOMSN=19407859
2024-04-15T15:04:16.286Z,1713193456.286 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240415T145908/Express0001.lzma
2024-04-15T15:04:17.289Z,1713193457.289 [DataOverHttps](INFO): Moved sent file to Logs/20240415T145908/Express0001.lzma.bak
2024-04-15T15:04:17.289Z,1713193457.289 [DataOverHttps](INFO): SBD MOMSN=19407863
2024-04-15T15:04:32.832Z,1713193472.832 [DataOverHttps](INFO): Sending 768 bytes from file Logs/20240415T150038/Express0001.lzma
2024-04-15T15:04:33.836Z,1713193473.836 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0001.lzma.bak
2024-04-15T15:04:33.836Z,1713193473.836 [DataOverHttps](INFO): SBD MOMSN=19407865
2024-04-15T15:04:34.996Z,1713193474.996 [Startup:StartupSatComms:B] Stopped
2024-04-15T15:04:34.997Z,1713193474.997 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-04-15T15:04:34.997Z,1713193474.997 [Startup:StartupSatComms] Stopped
2024-04-15T15:04:34.997Z,1713193474.997 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-04-15T15:04:34.998Z,1713193474.998 [Startup](INFO): Completed Startup
2024-04-15T15:04:34.998Z,1713193474.998 [MissionManager](INFO): Startup is completed.
2024-04-15T15:04:34.998Z,1713193474.998 [MissionManager](INFO): Uninitializing Mission Startup
2024-04-15T15:04:34.998Z,1713193474.998 [Startup] Stopped
2024-04-15T15:04:34.998Z,1713193474.998 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-04-15T15:04:34.998Z,1713193474.998 [Startup:A.GoToSurface] Stopped
2024-04-15T15:04:34.999Z,1713193474.999 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-15T15:04:35.403Z,1713193475.403 [MissionManager](IMPORTANT): Started mission Default
2024-04-15T15:04:35.403Z,1713193475.403 [Default] Running Loop=1
2024-04-15T15:04:35.404Z,1713193475.404 [Default](DEBUG): Aggregate::initialize Default
2024-04-15T15:04:35.404Z,1713193475.404 [Default:B.GoToSurface] Running Loop=1
2024-04-15T15:04:35.404Z,1713193475.404 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-15T15:04:35.404Z,1713193475.404 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-15T15:04:35.404Z,1713193475.404 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-15T15:04:35.405Z,1713193475.405 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-15T15:04:35.405Z,1713193475.405 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-15T15:04:35.405Z,1713193475.405 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-15T15:04:35.405Z,1713193475.405 [Default:A.Wait] Running Loop=1
2024-04-15T15:04:35.405Z,1713193475.405 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-04-15T15:04:48.765Z,1713193488.765 [Default:A.Wait](INFO): Done Waiting.
2024-04-15T15:04:48.765Z,1713193488.765 [Default:A.Wait] Stopped
2024-04-15T15:04:48.765Z,1713193488.765 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T15:04:49.154Z,1713193489.154 [Default:CheckIn] Running Loop=1
2024-04-15T15:04:49.154Z,1713193489.154 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T15:04:49.154Z,1713193489.154 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T15:04:49.557Z,1713193489.557 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-04-15T15:06:34.168Z,1713193594.168 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-15T15:06:51.952Z,1713193611.952 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-04-15T15:06:51.952Z,1713193611.952 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:06:51.987Z,1713193611.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:06:52.454Z,1713193612.454 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:06:52.454Z,1713193612.454 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-04-15T15:08:55.973Z,1713193735.973 [BPC1](ERROR): Battery stick #17 (s/n: 02D0) reported OVER_TEMP_ALARM. Status code: 0x1717.
2024-04-15T15:08:55.974Z,1713193735.974 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 4.
2024-04-15T15:08:55.978Z,1713193735.978 [BPC1](INFO): Received data from all battery sticks.
2024-04-15T15:09:49.325Z,1713193789.325 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-15T15:04:49.2Z
2024-04-15T15:09:49.325Z,1713193789.325 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T15:09:49.326Z,1713193789.326 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T15:09:49.737Z,1713193789.737 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-15T15:09:52.968Z,1713193792.968 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-04-15T15:09:52.968Z,1713193792.968 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:09:52.988Z,1713193792.988 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:09:53.392Z,1713193793.392 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:09:53.392Z,1713193793.392 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-04-15T15:09:56.062Z,1713193796.062 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240415T150038/Courier0004.lzma
2024-04-15T15:09:57.064Z,1713193797.064 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0004.lzma.bak
2024-04-15T15:09:57.065Z,1713193797.065 [DataOverHttps](INFO): SBD MOMSN=19407888
2024-04-15T15:10:16.367Z,1713193816.367 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20240415T150038/Express0005.lzma
2024-04-15T15:10:17.365Z,1713193817.365 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0005.lzma.bak
2024-04-15T15:10:17.365Z,1713193817.365 [DataOverHttps](INFO): SBD MOMSN=19407890
2024-04-15T15:10:18.438Z,1713193818.438 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T15:10:18.438Z,1713193818.438 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T15:10:18.438Z,1713193818.438 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T15:11:31.545Z,1713193891.545 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-15T15:11:31.545Z,1713193891.545 [NAL9602] Data Fault, FailCount= 1
2024-04-15T15:11:31.545Z,1713193891.545 [NAL9602](ERROR): Data Fault
2024-04-15T15:11:31.562Z,1713193891.562 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-15T15:11:31.945Z,1713193891.945 [NAL9602](INFO): Powering down
2024-04-15T15:11:32.773Z,1713193892.773 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-15T15:11:32.773Z,1713193892.773 [NAL9602] No Fault, FailCount= 1
2024-04-15T15:11:54.599Z,1713193914.599 [CommandExec](IMPORTANT): got command configSet list
2024-04-15T15:11:54.599Z,1713193914.599 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-15T15:11:54.600Z,1713193914.600 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-04-15T15:11:54.600Z,1713193914.600 [CommandExec](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-04-15T15:11:54.600Z,1713193914.600 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=186 cubic_centimeter;
2024-04-15T15:11:54.600Z,1713193914.600 [CommandExec](IMPORTANT): VerticalControl.massDefault=7.5 millimeter;
2024-04-15T15:12:02.248Z,1713193922.248 [NAL9602](INFO): Powering up NAL9602
2024-04-15T15:12:13.160Z,1713193933.160 [NAL9602](INFO): NAL9602 initialized
2024-04-15T15:12:54.007Z,1713193974.007 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-04-15T15:12:54.007Z,1713193974.007 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:12:54.031Z,1713193974.031 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:12:54.441Z,1713193974.441 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:12:54.441Z,1713193974.441 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-04-15T15:14:28.914Z,1713194068.914 [CommandExec](IMPORTANT): got command configSet list
2024-04-15T15:14:28.915Z,1713194068.915 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-15T15:14:28.916Z,1713194068.916 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-04-15T15:14:28.916Z,1713194068.916 [CommandExec](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-04-15T15:14:28.916Z,1713194068.916 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=186 cubic_centimeter;
2024-04-15T15:14:28.916Z,1713194068.916 [CommandExec](IMPORTANT): VerticalControl.massDefault=7.5 millimeter;
2024-04-15T15:15:19.052Z,1713194119.052 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T15:15:19.052Z,1713194119.052 [Default:CheckIn:C.Wait] Stopped
2024-04-15T15:15:19.052Z,1713194119.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T15:15:19.052Z,1713194119.052 [Default:CheckIn:D] Running Loop=1
2024-04-15T15:15:19.468Z,1713194119.468 [Default:CheckIn:D] Stopped
2024-04-15T15:15:19.468Z,1713194119.468 [Default:CheckIn:E] Running Loop=1
2024-04-15T15:15:19.861Z,1713194119.861 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.734410 min
2024-04-15T15:15:19.861Z,1713194119.861 [Default:CheckIn:E] Stopped
2024-04-15T15:15:19.861Z,1713194119.861 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T15:15:19.861Z,1713194119.861 [Default:CheckIn] Stopped
2024-04-15T15:15:19.862Z,1713194119.862 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T15:15:19.862Z,1713194119.862 [Default:CheckIn](INFO): Running loop #2
2024-04-15T15:15:19.862Z,1713194119.862 [Default:CheckIn] Running Loop=2
2024-04-15T15:15:19.862Z,1713194119.862 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T15:15:19.862Z,1713194119.862 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T15:15:55.007Z,1713194155.007 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-04-15T15:15:55.007Z,1713194155.007 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:15:55.026Z,1713194155.026 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:15:55.433Z,1713194155.433 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:15:55.433Z,1713194155.433 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-04-15T15:18:05.484Z,1713194285.484 [CommandExec](IMPORTANT): got command burn on
2024-04-15T15:18:05.484Z,1713194285.484 [CommandExec](IMPORTANT): Activating dropweight wire
2024-04-15T15:18:28.536Z,1713194308.536 [CommandExec](IMPORTANT): got command burn off
2024-04-15T15:18:28.536Z,1713194308.536 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-04-15T15:18:55.996Z,1713194335.996 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-04-15T15:18:55.996Z,1713194335.996 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:18:56.039Z,1713194336.039 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:18:56.413Z,1713194336.413 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:18:56.414Z,1713194336.414 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-04-15T15:20:20.029Z,1713194420.029 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-15T15:15:19.9Z
2024-04-15T15:20:20.029Z,1713194420.029 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T15:20:20.029Z,1713194420.029 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T15:20:27.326Z,1713194427.326 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240415T150038/Courier0007.lzma
2024-04-15T15:20:28.329Z,1713194428.329 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0007.lzma.bak
2024-04-15T15:20:28.329Z,1713194428.329 [DataOverHttps](INFO): SBD MOMSN=19407971
2024-04-15T15:20:43.927Z,1713194443.927 [DataOverHttps](INFO): Sending 472 bytes from file Logs/20240415T150038/Express0008.lzma
2024-04-15T15:20:44.921Z,1713194444.921 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0008.lzma.bak
2024-04-15T15:20:44.921Z,1713194444.921 [DataOverHttps](INFO): SBD MOMSN=19407973
2024-04-15T15:20:46.286Z,1713194446.286 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T15:20:46.286Z,1713194446.286 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T15:20:46.287Z,1713194446.287 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T15:21:56.972Z,1713194516.972 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-04-15T15:21:56.972Z,1713194516.972 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:21:57.007Z,1713194517.007 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:21:57.386Z,1713194517.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:21:57.386Z,1713194517.386 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-04-15T15:22:16.357Z,1713194536.357 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-15T15:22:16.357Z,1713194536.357 [NAL9602] Data Fault, FailCount= 2
2024-04-15T15:22:16.357Z,1713194536.357 [NAL9602](ERROR): Data Fault
2024-04-15T15:22:16.374Z,1713194536.374 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-15T15:22:16.762Z,1713194536.762 [NAL9602](INFO): Powering down
2024-04-15T15:22:17.581Z,1713194537.581 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-15T15:22:17.581Z,1713194537.581 [NAL9602] No Fault, FailCount= 2
2024-04-15T15:22:47.056Z,1713194567.056 [NAL9602](INFO): Powering up NAL9602
2024-04-15T15:22:58.024Z,1713194578.024 [NAL9602](INFO): NAL9602 initialized
2024-04-15T15:24:57.787Z,1713194697.787 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-04-15T15:24:57.787Z,1713194697.787 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:24:57.839Z,1713194697.839 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:24:58.199Z,1713194698.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:24:58.199Z,1713194698.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-04-15T15:25:47.061Z,1713194747.061 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T15:25:47.061Z,1713194747.061 [Default:CheckIn:C.Wait] Stopped
2024-04-15T15:25:47.061Z,1713194747.061 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T15:25:47.061Z,1713194747.061 [Default:CheckIn:D] Running Loop=1
2024-04-15T15:25:47.453Z,1713194747.453 [Default:CheckIn:D] Stopped
2024-04-15T15:25:47.453Z,1713194747.453 [Default:CheckIn:E] Running Loop=1
2024-04-15T15:25:47.850Z,1713194747.850 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.200826 min
2024-04-15T15:25:47.850Z,1713194747.850 [Default:CheckIn:E] Stopped
2024-04-15T15:25:47.850Z,1713194747.850 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T15:25:47.850Z,1713194747.850 [Default:CheckIn] Stopped
2024-04-15T15:25:47.850Z,1713194747.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T15:25:47.850Z,1713194747.850 [Default:CheckIn](INFO): Running loop #3
2024-04-15T15:25:47.850Z,1713194747.850 [Default:CheckIn] Running Loop=3
2024-04-15T15:25:47.850Z,1713194747.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T15:25:47.851Z,1713194747.851 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T15:27:58.763Z,1713194878.763 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-04-15T15:27:58.763Z,1713194878.763 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:27:58.774Z,1713194878.774 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:27:59.177Z,1713194879.177 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:27:59.177Z,1713194879.177 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-04-15T15:30:48.005Z,1713195048.005 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-15T15:25:47.9Z
2024-04-15T15:30:48.005Z,1713195048.005 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T15:30:48.005Z,1713195048.005 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T15:30:48.852Z,1713195048.852 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T15:30:48.852Z,1713195048.852 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T15:30:48.852Z,1713195048.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T15:30:59.792Z,1713195059.792 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-04-15T15:30:59.792Z,1713195059.792 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:30:59.827Z,1713195059.827 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:31:00.203Z,1713195060.203 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:31:00.203Z,1713195060.203 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-04-15T15:32:59.044Z,1713195179.044 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-15T15:32:59.045Z,1713195179.045 [NAL9602] Data Fault, FailCount= 3
2024-04-15T15:32:59.045Z,1713195179.045 [NAL9602](ERROR): Data Fault
2024-04-15T15:32:59.062Z,1713195179.062 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-15T15:32:59.436Z,1713195179.436 [NAL9602](INFO): Powering down
2024-04-15T15:33:00.260Z,1713195180.260 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-15T15:33:00.260Z,1713195180.260 [NAL9602] No Fault, FailCount= 3
2024-04-15T15:33:29.736Z,1713195209.736 [NAL9602](INFO): Powering up NAL9602
2024-04-15T15:33:40.648Z,1713195220.648 [NAL9602](INFO): NAL9602 initialized
2024-04-15T15:34:00.858Z,1713195240.858 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-04-15T15:34:00.858Z,1713195240.858 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:34:00.869Z,1713195240.869 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:34:01.281Z,1713195241.281 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:34:01.281Z,1713195241.281 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-04-15T15:35:49.533Z,1713195349.533 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T15:35:49.533Z,1713195349.533 [Default:CheckIn:C.Wait] Stopped
2024-04-15T15:35:49.533Z,1713195349.533 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T15:35:49.534Z,1713195349.534 [Default:CheckIn:D] Running Loop=1
2024-04-15T15:35:49.943Z,1713195349.943 [Default:CheckIn:D] Stopped
2024-04-15T15:35:49.943Z,1713195349.943 [Default:CheckIn:E] Running Loop=1
2024-04-15T15:35:50.369Z,1713195350.369 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.242326 min
2024-04-15T15:35:50.369Z,1713195350.369 [Default:CheckIn:E] Stopped
2024-04-15T15:35:50.369Z,1713195350.369 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T15:35:50.369Z,1713195350.369 [Default:CheckIn] Stopped
2024-04-15T15:35:50.369Z,1713195350.369 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T15:35:50.370Z,1713195350.370 [Default:CheckIn](INFO): Running loop #4
2024-04-15T15:35:50.370Z,1713195350.370 [Default:CheckIn] Running Loop=4
2024-04-15T15:35:50.370Z,1713195350.370 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T15:35:50.370Z,1713195350.370 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T15:37:01.848Z,1713195421.848 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-04-15T15:37:01.848Z,1713195421.848 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:37:01.859Z,1713195421.859 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:37:02.269Z,1713195422.269 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:37:02.269Z,1713195422.269 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-04-15T15:40:02.863Z,1713195602.863 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-04-15T15:40:02.863Z,1713195602.863 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-15T15:40:02.874Z,1713195602.874 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-15T15:40:03.287Z,1713195603.287 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-15T15:40:03.287Z,1713195603.287 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-04-15T15:40:19.002Z,1713195619.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154017.00,A,3648.17310,N,12147.28622,W,0.136,0.00,150424,,,A*78
2024-04-15T15:40:19.006Z,1713195619.006 [NAL9602](INFO): GPS fix at 20240415T154017: (36.802885, -121.788104)
2024-04-15T15:40:19.053Z,1713195619.053 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T15:40:19.053Z,1713195619.053 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T15:40:33.170Z,1713195633.170 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240415T150038/Courier0010.lzma
2024-04-15T15:40:34.173Z,1713195634.173 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0010.lzma.bak
2024-04-15T15:40:34.173Z,1713195634.173 [DataOverHttps](INFO): SBD MOMSN=19408032
2024-04-15T15:40:51.072Z,1713195651.072 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T15:40:53.791Z,1713195653.791 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20240415T150038/Courier0013.lzma
2024-04-15T15:40:54.793Z,1713195654.793 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0013.lzma.bak
2024-04-15T15:40:54.793Z,1713195654.793 [DataOverHttps](INFO): SBD MOMSN=19408034
2024-04-15T15:41:04.120Z,1713195664.120 [DataOverHttps](IMPORTANT): SBD MTMSN=20240415T154103
2024-04-15T15:41:11.590Z,1713195671.590 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240415T150038/Express0011.lzma
2024-04-15T15:41:11.593Z,1713195671.593 [DataOverHttps](INFO): Received command: ibit
2024-04-15T15:41:11.658Z,1713195671.658 [CommandExec](IMPORTANT): got command ibit
2024-04-15T15:41:11.686Z,1713195671.686 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-04-15T15:41:11.686Z,1713195671.686 [IBIT](IMPORTANT): Beginning control surface checks.
2024-04-15T15:41:11.689Z,1713195671.689 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-15T15:41:12.769Z,1713195672.769 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0011.lzma.bak
2024-04-15T15:41:12.770Z,1713195672.770 [DataOverHttps](INFO): SBD MOMSN=19408038
2024-04-15T15:41:13.280Z,1713195673.280 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154111.00,A,3648.17161,N,12147.28632,W,0.058,0.00,150424,,,A*73
2024-04-15T15:41:13.282Z,1713195673.282 [NAL9602](INFO): GPS fix at 20240415T154111: (36.802860, -121.788105)
2024-04-15T15:41:22.587Z,1713195682.587 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-04-15T15:41:22.000Z,1713195683.000 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-15T15:41:28.938Z,1713195688.938 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240415T150038/Courier0016.lzma
2024-04-15T15:41:29.945Z,1713195689.945 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0016.lzma.bak
2024-04-15T15:41:29.945Z,1713195689.945 [DataOverHttps](INFO): SBD MOMSN=19408043
2024-04-15T15:41:34.030Z,1713195694.030 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002936
CHAN A1 (24V): 0.000468
CHAN A2 (12V): -0.002001
CHAN A3 (5V): -0.001383
CHAN B0 (3.3V): 0.000422
CHAN B1 (3.15aV): 0.000166
CHAN B2 (3.15bV): -0.000711
CHAN B3 (GND): 0.000074
OPEN: -0.000311
Full Scale: +/- 1 mA
2024-04-15T15:41:46.119Z,1713195706.119 [DataOverHttps](INFO): Sending 293 bytes from file Logs/20240415T150038/Express0014.lzma
2024-04-15T15:41:47.121Z,1713195707.121 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0014.lzma.bak
2024-04-15T15:41:47.121Z,1713195707.121 [DataOverHttps](INFO): SBD MOMSN=19408045
2024-04-15T15:41:57.743Z,1713195717.743 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802860 Longitude: -121.788109
2024-04-15T15:41:58.157Z,1713195718.157 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2024-04-15T15:41:58.157Z,1713195718.157 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-04-15T15:41:58.157Z,1713195718.157 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-04-15T15:41:58.540Z,1713195718.540 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-04-15T15:41:58.540Z,1713195718.540 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-04-15T15:41:58.540Z,1713195718.540 [IBIT](IMPORTANT): Pressure:9.754810 PSI
2024-04-15T15:41:58.540Z,1713195718.540 [IBIT](IMPORTANT): Humidity:13.307288 %
2024-04-15T15:41:58.957Z,1713195718.957 [IBIT](IMPORTANT): Vehicle Pitch:-1.308118 degrees
2024-04-15T15:41:58.957Z,1713195718.957 [IBIT](IMPORTANT): Vehicle Roll:4.943735 degrees
2024-04-15T15:41:58.958Z,1713195718.958 [IBIT](IMPORTANT): Vehicle Heading:23.168924 degrees
2024-04-15T15:41:59.385Z,1713195719.385 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-04-15T15:41:59.385Z,1713195719.385 [IBIT](IMPORTANT): buoyancyNeutral: 186.000000 cc
2024-04-15T15:41:59.385Z,1713195719.385 [IBIT](IMPORTANT): massDefault: 0.750000 cm
2024-04-15T15:41:59.385Z,1713195719.385 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-04-15T15:41:59.386Z,1713195719.386 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-04-15T15:41:59.386Z,1713195719.386 [IBIT](IMPORTANT): IBIT PASSED
2024-04-15T15:42:02.570Z,1713195722.570 [NAL9602](INFO): SBD MO Status=2, MOMSN=49376, MT Status=2, MTMSN=0
2024-04-15T15:42:02.570Z,1713195722.570 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-15T15:42:03.063Z,1713195723.063 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20240415T150038/Express0017.lzma
2024-04-15T15:42:04.065Z,1713195724.065 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0017.lzma.bak
2024-04-15T15:42:04.065Z,1713195724.065 [DataOverHttps](INFO): SBD MOMSN=19408049
2024-04-15T15:42:05.406Z,1713195725.406 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T15:42:05.406Z,1713195725.406 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T15:42:05.406Z,1713195725.406 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T15:42:20.344Z,1713195740.344 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=49376, MT Status=1, MTMSN=3170
2024-04-15T15:42:20.344Z,1713195740.344 [NAL9602](INFO): Data available in MT queue
2024-04-15T15:42:20.851Z,1713195740.851 [NAL9602](INFO): Received command: gfscan
2024-04-15T15:42:20.864Z,1713195740.864 [CommandExec](IMPORTANT): got command gfscan
2024-04-15T15:42:20.962Z,1713195740.962 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-15T15:42:31.703Z,1713195751.703 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002662
CHAN A1 (24V): 0.000571
CHAN A2 (12V): -0.002188
CHAN A3 (5V): -0.001887
CHAN B0 (3.3V): -0.000399
CHAN B1 (3.15aV): -0.000358
CHAN B2 (3.15bV): -0.000013
CHAN B3 (GND): 0.000020
OPEN: -0.000538
Full Scale: +/- 1 mA
2024-04-15T15:42:51.452Z,1713195771.452 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T15:47:06.020Z,1713196026.020 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T15:47:06.020Z,1713196026.020 [Default:CheckIn:C.Wait] Stopped
2024-04-15T15:47:06.020Z,1713196026.020 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T15:47:06.020Z,1713196026.020 [Default:CheckIn:D] Running Loop=1
2024-04-15T15:47:06.406Z,1713196026.406 [Default:CheckIn:D] Stopped
2024-04-15T15:47:06.406Z,1713196026.406 [Default:CheckIn:E] Running Loop=1
2024-04-15T15:47:06.810Z,1713196026.810 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.516707 min
2024-04-15T15:47:06.810Z,1713196026.810 [Default:CheckIn:E] Stopped
2024-04-15T15:47:06.810Z,1713196026.810 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T15:47:06.810Z,1713196026.810 [Default:CheckIn] Stopped
2024-04-15T15:47:06.811Z,1713196026.811 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T15:47:06.811Z,1713196026.811 [Default:CheckIn](INFO): Running loop #5
2024-04-15T15:47:06.811Z,1713196026.811 [Default:CheckIn] Running Loop=5
2024-04-15T15:47:06.811Z,1713196026.811 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T15:47:06.812Z,1713196026.812 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T15:47:08.823Z,1713196028.823 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154708.00,A,3648.17341,N,12147.28457,W,0.078,0.00,150424,,,A*7E
2024-04-15T15:47:08.825Z,1713196028.825 [NAL9602](INFO): GPS fix at 20240415T154708: (36.802890, -121.788076)
2024-04-15T15:47:08.836Z,1713196028.836 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T15:47:08.836Z,1713196028.836 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T15:47:16.642Z,1713196036.642 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0019.lzma
2024-04-15T15:47:17.645Z,1713196037.645 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0019.lzma.bak
2024-04-15T15:47:17.645Z,1713196037.645 [DataOverHttps](INFO): SBD MOMSN=19408066
2024-04-15T15:47:30.641Z,1713196050.641 [NAL9602](INFO): SBD MO Status=0, MOMSN=49377, MT Status=0, MTMSN=0
2024-04-15T15:47:30.641Z,1713196050.641 [NAL9602](INFO): No messages in MT queue
2024-04-15T15:47:33.787Z,1713196053.787 [DataOverHttps](INFO): Sending 787 bytes from file Logs/20240415T150038/Express0020.lzma
2024-04-15T15:47:34.789Z,1713196054.789 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0020.lzma.bak
2024-04-15T15:47:34.789Z,1713196054.789 [DataOverHttps](INFO): SBD MOMSN=19408069
2024-04-15T15:47:35.900Z,1713196055.900 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T15:47:35.900Z,1713196055.900 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T15:47:35.901Z,1713196055.901 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T15:48:01.348Z,1713196081.348 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T15:52:36.488Z,1713196356.488 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T15:52:36.488Z,1713196356.488 [Default:CheckIn:C.Wait] Stopped
2024-04-15T15:52:36.488Z,1713196356.488 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T15:52:36.489Z,1713196356.489 [Default:CheckIn:D] Running Loop=1
2024-04-15T15:52:36.886Z,1713196356.886 [Default:CheckIn:D] Stopped
2024-04-15T15:52:36.886Z,1713196356.886 [Default:CheckIn:E] Running Loop=1
2024-04-15T15:52:37.286Z,1713196357.286 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.024707 min
2024-04-15T15:52:37.286Z,1713196357.286 [Default:CheckIn:E] Stopped
2024-04-15T15:52:37.287Z,1713196357.287 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T15:52:37.287Z,1713196357.287 [Default:CheckIn] Stopped
2024-04-15T15:52:37.287Z,1713196357.287 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T15:52:37.287Z,1713196357.287 [Default:CheckIn](INFO): Running loop #6
2024-04-15T15:52:37.287Z,1713196357.287 [Default:CheckIn] Running Loop=6
2024-04-15T15:52:37.287Z,1713196357.287 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T15:52:37.287Z,1713196357.287 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T15:52:39.299Z,1713196359.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155238.00,A,3648.16973,N,12147.27890,W,0.214,157.03,150424,,,A*73
2024-04-15T15:52:39.301Z,1713196359.301 [NAL9602](INFO): GPS fix at 20240415T155238: (36.802829, -121.787982)
2024-04-15T15:52:39.312Z,1713196359.312 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T15:52:39.312Z,1713196359.312 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T15:52:47.302Z,1713196367.302 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0022.lzma
2024-04-15T15:52:48.305Z,1713196368.305 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0022.lzma.bak
2024-04-15T15:52:48.305Z,1713196368.305 [DataOverHttps](INFO): SBD MOMSN=19408099
2024-04-15T15:53:06.830Z,1713196386.830 [NAL9602](INFO): SBD MO Status=0, MOMSN=49378, MT Status=0, MTMSN=0
2024-04-15T15:53:06.831Z,1713196386.831 [NAL9602](INFO): No messages in MT queue
2024-04-15T15:53:07.354Z,1713196387.354 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0023.lzma
2024-04-15T15:53:08.357Z,1713196388.357 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0023.lzma.bak
2024-04-15T15:53:08.357Z,1713196388.357 [DataOverHttps](INFO): SBD MOMSN=19408102
2024-04-15T15:53:09.662Z,1713196389.662 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T15:53:09.662Z,1713196389.662 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T15:53:09.662Z,1713196389.662 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T15:53:37.527Z,1713196417.527 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T15:58:10.298Z,1713196690.298 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T15:58:10.298Z,1713196690.298 [Default:CheckIn:C.Wait] Stopped
2024-04-15T15:58:10.298Z,1713196690.298 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T15:58:10.298Z,1713196690.298 [Default:CheckIn:D] Running Loop=1
2024-04-15T15:58:10.706Z,1713196690.706 [Default:CheckIn:D] Stopped
2024-04-15T15:58:10.706Z,1713196690.706 [Default:CheckIn:E] Running Loop=1
2024-04-15T15:58:11.117Z,1713196691.117 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.588371 min
2024-04-15T15:58:11.117Z,1713196691.117 [Default:CheckIn:E] Stopped
2024-04-15T15:58:11.117Z,1713196691.117 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T15:58:11.117Z,1713196691.117 [Default:CheckIn] Stopped
2024-04-15T15:58:11.117Z,1713196691.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T15:58:11.117Z,1713196691.117 [Default:CheckIn](INFO): Running loop #7
2024-04-15T15:58:11.117Z,1713196691.117 [Default:CheckIn] Running Loop=7
2024-04-15T15:58:11.117Z,1713196691.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T15:58:11.117Z,1713196691.117 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T15:58:13.118Z,1713196693.118 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155812.00,A,3648.13888,N,12147.23106,W,0.019,54.53,150424,,,A*4B
2024-04-15T15:58:13.120Z,1713196693.120 [NAL9602](INFO): GPS fix at 20240415T155812: (36.802315, -121.787184)
2024-04-15T15:58:13.143Z,1713196693.143 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T15:58:13.143Z,1713196693.143 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T15:58:22.999Z,1713196702.999 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0025.lzma
2024-04-15T15:58:24.001Z,1713196704.001 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0025.lzma.bak
2024-04-15T15:58:24.001Z,1713196704.001 [DataOverHttps](INFO): SBD MOMSN=19408124
2024-04-15T15:58:24.446Z,1713196704.446 [NAL9602](INFO): SBD MO Status=0, MOMSN=49379, MT Status=0, MTMSN=0
2024-04-15T15:58:24.446Z,1713196704.446 [NAL9602](INFO): No messages in MT queue
2024-04-15T15:58:40.970Z,1713196720.970 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240415T150038/Express0026.lzma
2024-04-15T15:58:41.973Z,1713196721.973 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0026.lzma.bak
2024-04-15T15:58:41.973Z,1713196721.973 [DataOverHttps](INFO): SBD MOMSN=19408128
2024-04-15T15:58:43.042Z,1713196723.042 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T15:58:43.042Z,1713196723.042 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T15:58:43.042Z,1713196723.042 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T15:58:55.164Z,1713196735.164 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:03:43.455Z,1713197023.455 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:03:43.456Z,1713197023.456 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:03:43.456Z,1713197023.456 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:03:43.456Z,1713197023.456 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:03:43.846Z,1713197023.846 [Default:CheckIn:D] Stopped
2024-04-15T16:03:43.846Z,1713197023.846 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:03:44.242Z,1713197024.242 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.140702 min
2024-04-15T16:03:44.242Z,1713197024.242 [Default:CheckIn:E] Stopped
2024-04-15T16:03:44.243Z,1713197024.243 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:03:44.243Z,1713197024.243 [Default:CheckIn] Stopped
2024-04-15T16:03:44.243Z,1713197024.243 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:03:44.243Z,1713197024.243 [Default:CheckIn](INFO): Running loop #8
2024-04-15T16:03:44.243Z,1713197024.243 [Default:CheckIn] Running Loop=8
2024-04-15T16:03:44.243Z,1713197024.243 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:03:44.243Z,1713197024.243 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:03:46.250Z,1713197026.250 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160345.00,A,3648.14104,N,12147.23008,W,0.019,54.53,150424,,,A*41
2024-04-15T16:03:46.252Z,1713197026.252 [NAL9602](INFO): GPS fix at 20240415T160345: (36.802351, -121.787168)
2024-04-15T16:03:46.263Z,1713197026.263 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:03:46.263Z,1713197026.263 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:03:54.058Z,1713197034.058 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0028.lzma
2024-04-15T16:03:55.061Z,1713197035.061 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0028.lzma.bak
2024-04-15T16:03:55.061Z,1713197035.061 [DataOverHttps](INFO): SBD MOMSN=19408153
2024-04-15T16:04:10.982Z,1713197050.982 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240415T150038/Express0029.lzma
2024-04-15T16:04:11.985Z,1713197051.985 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0029.lzma.bak
2024-04-15T16:04:11.985Z,1713197051.985 [DataOverHttps](INFO): SBD MOMSN=19408156
2024-04-15T16:04:13.326Z,1713197053.326 [NAL9602](INFO): SBD MO Status=0, MOMSN=49380, MT Status=0, MTMSN=0
2024-04-15T16:04:13.326Z,1713197053.326 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:04:13.337Z,1713197053.337 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:04:13.337Z,1713197053.337 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:04:13.337Z,1713197053.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:04:44.038Z,1713197084.038 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:04:52.805Z,1713197092.805 [DataOverHttps](IMPORTANT): SBD MTMSN=20240415T160451
2024-04-15T16:05:01.942Z,1713197101.942 [DataOverHttps](INFO): Received command: failc
2024-04-15T16:05:01.979Z,1713197101.979 [CommandExec](IMPORTANT): got command failComponent
2024-04-15T16:05:01.979Z,1713197101.979 [CommandExec](IMPORTANT): Failed components:
2024-04-15T16:05:01.980Z,1713197101.980 [CommandExec](IMPORTANT): No failed Components.
2024-04-15T16:09:13.976Z,1713197353.976 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:09:13.976Z,1713197353.976 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:09:13.976Z,1713197353.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:09:13.976Z,1713197353.976 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:09:14.389Z,1713197354.389 [Default:CheckIn:D] Stopped
2024-04-15T16:09:14.389Z,1713197354.389 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:09:14.774Z,1713197354.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.649756 min
2024-04-15T16:09:14.774Z,1713197354.774 [Default:CheckIn:E] Stopped
2024-04-15T16:09:14.774Z,1713197354.774 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:09:14.774Z,1713197354.774 [Default:CheckIn] Stopped
2024-04-15T16:09:14.774Z,1713197354.774 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:09:14.774Z,1713197354.774 [Default:CheckIn](INFO): Running loop #9
2024-04-15T16:09:14.775Z,1713197354.775 [Default:CheckIn] Running Loop=9
2024-04-15T16:09:14.775Z,1713197354.775 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:09:14.775Z,1713197354.775 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:09:16.790Z,1713197356.790 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160916.00,A,3648.13948,N,12147.23091,W,0.019,54.53,150424,,,A*4A
2024-04-15T16:09:16.792Z,1713197356.792 [NAL9602](INFO): GPS fix at 20240415T160916: (36.802325, -121.787182)
2024-04-15T16:09:16.804Z,1713197356.804 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:09:16.804Z,1713197356.804 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:09:24.134Z,1713197364.134 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0031.lzma
2024-04-15T16:09:25.137Z,1713197365.137 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0031.lzma.bak
2024-04-15T16:09:25.137Z,1713197365.137 [DataOverHttps](INFO): SBD MOMSN=19408170
2024-04-15T16:09:31.330Z,1713197371.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=49381, MT Status=0, MTMSN=0
2024-04-15T16:09:31.330Z,1713197371.330 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:09:41.095Z,1713197381.095 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20240415T150038/Express0032.lzma
2024-04-15T16:09:42.097Z,1713197382.097 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0032.lzma.bak
2024-04-15T16:09:42.097Z,1713197382.097 [DataOverHttps](INFO): SBD MOMSN=19408176
2024-04-15T16:09:58.050Z,1713197398.050 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240415T150038/Express0033.lzma
2024-04-15T16:09:59.053Z,1713197399.053 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0033.lzma.bak
2024-04-15T16:09:59.053Z,1713197399.053 [DataOverHttps](INFO): SBD MOMSN=19408183
2024-04-15T16:10:00.430Z,1713197400.430 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:10:00.430Z,1713197400.430 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:10:00.431Z,1713197400.431 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:10:02.036Z,1713197402.036 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:10:03.243Z,1713197403.243 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T16:10:16.171Z,1713197416.171 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T16:10:29.099Z,1713197429.099 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T16:10:42.028Z,1713197442.028 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T16:10:54.954Z,1713197454.954 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T16:11:07.882Z,1713197467.882 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T16:11:20.810Z,1713197480.810 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T16:14:02.825Z,1713197642.825 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-04-15T16:14:02.827Z,1713197642.827 [BPC1](INFO): Received data from all battery sticks.
2024-04-15T16:15:01.042Z,1713197701.042 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:15:01.042Z,1713197701.042 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:15:01.042Z,1713197701.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:15:01.042Z,1713197701.042 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:15:01.408Z,1713197701.408 [Default:CheckIn:D] Stopped
2024-04-15T16:15:01.408Z,1713197701.408 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:15:01.820Z,1713197701.820 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.433398 min
2024-04-15T16:15:01.820Z,1713197701.820 [Default:CheckIn:E] Stopped
2024-04-15T16:15:01.820Z,1713197701.820 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:15:01.820Z,1713197701.820 [Default:CheckIn] Stopped
2024-04-15T16:15:01.820Z,1713197701.820 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:15:01.820Z,1713197701.820 [Default:CheckIn](INFO): Running loop #10
2024-04-15T16:15:01.820Z,1713197701.820 [Default:CheckIn] Running Loop=10
2024-04-15T16:15:01.820Z,1713197701.820 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:15:01.820Z,1713197701.820 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:15:03.822Z,1713197703.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161503.00,A,3648.13925,N,12147.23106,W,0.019,54.53,150424,,,A*47
2024-04-15T16:15:03.825Z,1713197703.825 [NAL9602](INFO): GPS fix at 20240415T161503: (36.802321, -121.787184)
2024-04-15T16:15:03.856Z,1713197703.856 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:15:03.856Z,1713197703.856 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:15:11.178Z,1713197711.178 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240415T150038/Courier0036.lzma
2024-04-15T16:15:12.181Z,1713197712.181 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0036.lzma.bak
2024-04-15T16:15:12.181Z,1713197712.181 [DataOverHttps](INFO): SBD MOMSN=19408195
2024-04-15T16:15:28.154Z,1713197728.154 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240415T150038/Express0038.lzma
2024-04-15T16:15:29.157Z,1713197729.157 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0038.lzma.bak
2024-04-15T16:15:29.157Z,1713197729.157 [DataOverHttps](INFO): SBD MOMSN=19408198
2024-04-15T16:15:30.494Z,1713197730.494 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:15:30.495Z,1713197730.495 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:15:30.495Z,1713197730.495 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:15:34.526Z,1713197734.526 [NAL9602](INFO): SBD MO Status=2, MOMSN=49382, MT Status=2, MTMSN=0
2024-04-15T16:15:34.526Z,1713197734.526 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-15T16:16:35.153Z,1713197795.153 [NAL9602](INFO): SBD MO Status=2, MOMSN=49382, MT Status=2, MTMSN=0
2024-04-15T16:16:35.153Z,1713197795.153 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-15T16:17:25.245Z,1713197845.245 [NAL9602](INFO): SBD MO Status=2, MOMSN=49382, MT Status=2, MTMSN=0
2024-04-15T16:17:25.245Z,1713197845.245 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-15T16:17:51.506Z,1713197871.506 [NAL9602](INFO): SBD MO Status=2, MOMSN=49382, MT Status=2, MTMSN=0
2024-04-15T16:17:51.506Z,1713197871.506 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-15T16:18:10.495Z,1713197890.495 [NAL9602](INFO): SBD MO Status=0, MOMSN=49382, MT Status=0, MTMSN=0
2024-04-15T16:18:10.495Z,1713197890.495 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:18:41.203Z,1713197921.203 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:20:31.115Z,1713198031.115 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:20:31.115Z,1713198031.115 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:20:31.115Z,1713198031.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:20:31.115Z,1713198031.115 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:20:31.507Z,1713198031.507 [Default:CheckIn:D] Stopped
2024-04-15T16:20:31.508Z,1713198031.508 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:20:31.914Z,1713198031.914 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.935067 min
2024-04-15T16:20:31.914Z,1713198031.914 [Default:CheckIn:E] Stopped
2024-04-15T16:20:31.914Z,1713198031.914 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:20:31.914Z,1713198031.914 [Default:CheckIn] Stopped
2024-04-15T16:20:31.914Z,1713198031.914 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:20:31.915Z,1713198031.915 [Default:CheckIn](INFO): Running loop #11
2024-04-15T16:20:31.915Z,1713198031.915 [Default:CheckIn] Running Loop=11
2024-04-15T16:20:31.915Z,1713198031.915 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:20:31.915Z,1713198031.915 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:20:33.919Z,1713198033.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162033.00,A,3648.13868,N,12147.20912,W,0.019,70.09,150424,,,D*48
2024-04-15T16:20:33.921Z,1713198033.921 [NAL9602](INFO): GPS fix at 20240415T162033: (36.802311, -121.786819)
2024-04-15T16:20:33.932Z,1713198033.932 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:20:33.932Z,1713198033.932 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:20:41.282Z,1713198041.282 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0040.lzma
2024-04-15T16:20:42.285Z,1713198042.285 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0040.lzma.bak
2024-04-15T16:20:42.285Z,1713198042.285 [DataOverHttps](INFO): SBD MOMSN=19408262
2024-04-15T16:20:45.633Z,1713198045.633 [NAL9602](INFO): SBD MO Status=0, MOMSN=49383, MT Status=0, MTMSN=0
2024-04-15T16:20:45.634Z,1713198045.634 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:21:01.855Z,1713198061.855 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240415T150038/Express0041.lzma
2024-04-15T16:21:02.857Z,1713198062.857 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0041.lzma.bak
2024-04-15T16:21:02.857Z,1713198062.857 [DataOverHttps](INFO): SBD MOMSN=19408265
2024-04-15T16:21:03.883Z,1713198063.883 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:21:03.883Z,1713198063.883 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:21:03.883Z,1713198063.883 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:21:16.368Z,1713198076.368 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:26:04.440Z,1713198364.440 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:26:04.440Z,1713198364.440 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:26:04.440Z,1713198364.440 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:26:04.440Z,1713198364.440 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:26:04.839Z,1713198364.839 [Default:CheckIn:D] Stopped
2024-04-15T16:26:04.839Z,1713198364.839 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:26:05.255Z,1713198365.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.490601 min
2024-04-15T16:26:05.255Z,1713198365.255 [Default:CheckIn:E] Stopped
2024-04-15T16:26:05.256Z,1713198365.256 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:26:05.256Z,1713198365.256 [Default:CheckIn] Stopped
2024-04-15T16:26:05.256Z,1713198365.256 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:26:05.256Z,1713198365.256 [Default:CheckIn](INFO): Running loop #12
2024-04-15T16:26:05.256Z,1713198365.256 [Default:CheckIn] Running Loop=12
2024-04-15T16:26:05.256Z,1713198365.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:26:05.256Z,1713198365.256 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:26:07.258Z,1713198367.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162606.00,A,3648.13974,N,12147.20868,W,0.058,70.09,150424,,,D*4D
2024-04-15T16:26:07.260Z,1713198367.260 [NAL9602](INFO): GPS fix at 20240415T162606: (36.802329, -121.786811)
2024-04-15T16:26:07.291Z,1713198367.291 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:26:07.291Z,1713198367.291 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:26:14.634Z,1713198374.634 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0043.lzma
2024-04-15T16:26:15.637Z,1713198375.637 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0043.lzma.bak
2024-04-15T16:26:15.637Z,1713198375.637 [DataOverHttps](INFO): SBD MOMSN=19408279
2024-04-15T16:26:21.846Z,1713198381.846 [NAL9602](INFO): SBD MO Status=0, MOMSN=49384, MT Status=0, MTMSN=0
2024-04-15T16:26:21.846Z,1713198381.846 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:26:34.778Z,1713198394.778 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0044.lzma
2024-04-15T16:26:35.781Z,1713198395.781 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0044.lzma.bak
2024-04-15T16:26:35.781Z,1713198395.781 [DataOverHttps](INFO): SBD MOMSN=19408282
2024-04-15T16:26:36.839Z,1713198396.839 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:26:36.839Z,1713198396.839 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:26:36.839Z,1713198396.839 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:26:52.560Z,1713198412.560 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:31:37.447Z,1713198697.447 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:31:37.447Z,1713198697.447 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:31:37.447Z,1713198697.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:31:37.448Z,1713198697.448 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:31:37.865Z,1713198697.865 [Default:CheckIn:D] Stopped
2024-04-15T16:31:37.866Z,1713198697.866 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:31:38.246Z,1713198698.246 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.041032 min
2024-04-15T16:31:38.246Z,1713198698.246 [Default:CheckIn:E] Stopped
2024-04-15T16:31:38.246Z,1713198698.246 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:31:38.246Z,1713198698.246 [Default:CheckIn] Stopped
2024-04-15T16:31:38.247Z,1713198698.247 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:31:38.247Z,1713198698.247 [Default:CheckIn](INFO): Running loop #13
2024-04-15T16:31:38.247Z,1713198698.247 [Default:CheckIn] Running Loop=13
2024-04-15T16:31:38.247Z,1713198698.247 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:31:38.247Z,1713198698.247 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:31:40.256Z,1713198700.256 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163139.00,A,3648.13957,N,12147.20852,W,0.019,70.09,150424,,,D*4A
2024-04-15T16:31:40.258Z,1713198700.258 [NAL9602](INFO): GPS fix at 20240415T163139: (36.802326, -121.786809)
2024-04-15T16:31:40.269Z,1713198700.269 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:31:40.269Z,1713198700.269 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:31:47.446Z,1713198707.446 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0046.lzma
2024-04-15T16:31:48.449Z,1713198708.449 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0046.lzma.bak
2024-04-15T16:31:48.449Z,1713198708.449 [DataOverHttps](INFO): SBD MOMSN=19408294
2024-04-15T16:31:58.839Z,1713198718.839 [NAL9602](INFO): SBD MO Status=0, MOMSN=49385, MT Status=0, MTMSN=0
2024-04-15T16:31:58.839Z,1713198718.839 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:32:04.631Z,1713198724.631 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0047.lzma
2024-04-15T16:32:05.633Z,1713198725.633 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0047.lzma.bak
2024-04-15T16:32:05.633Z,1713198725.633 [DataOverHttps](INFO): SBD MOMSN=19408297
2024-04-15T16:32:06.931Z,1713198726.931 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:32:06.931Z,1713198726.931 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:32:06.931Z,1713198726.931 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:32:29.548Z,1713198749.548 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:37:07.512Z,1713199027.512 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:37:07.512Z,1713199027.512 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:37:07.512Z,1713199027.512 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:37:07.513Z,1713199027.513 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:37:07.936Z,1713199027.936 [Default:CheckIn:D] Stopped
2024-04-15T16:37:07.936Z,1713199027.936 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:37:08.339Z,1713199028.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.542204 min
2024-04-15T16:37:08.339Z,1713199028.339 [Default:CheckIn:E] Stopped
2024-04-15T16:37:08.339Z,1713199028.339 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:37:08.339Z,1713199028.339 [Default:CheckIn] Stopped
2024-04-15T16:37:08.339Z,1713199028.339 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:37:08.340Z,1713199028.340 [Default:CheckIn](INFO): Running loop #14
2024-04-15T16:37:08.340Z,1713199028.340 [Default:CheckIn] Running Loop=14
2024-04-15T16:37:08.340Z,1713199028.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:37:08.340Z,1713199028.340 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:37:10.334Z,1713199030.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163709.00,A,3648.13951,N,12147.20870,W,0.058,70.09,150424,,,D*4C
2024-04-15T16:37:10.336Z,1713199030.336 [NAL9602](INFO): GPS fix at 20240415T163709: (36.802325, -121.786812)
2024-04-15T16:37:10.346Z,1713199030.346 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:37:10.346Z,1713199030.346 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:37:17.774Z,1713199037.774 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0049.lzma
2024-04-15T16:37:18.777Z,1713199038.777 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0049.lzma.bak
2024-04-15T16:37:18.777Z,1713199038.777 [DataOverHttps](INFO): SBD MOMSN=19408312
2024-04-15T16:37:35.035Z,1713199055.035 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240415T150038/Express0050.lzma
2024-04-15T16:37:36.037Z,1713199056.037 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0050.lzma.bak
2024-04-15T16:37:36.037Z,1713199056.037 [DataOverHttps](INFO): SBD MOMSN=19408315
2024-04-15T16:37:37.414Z,1713199057.414 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:37:37.414Z,1713199057.414 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:37:37.414Z,1713199057.414 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:37:37.805Z,1713199057.805 [NAL9602](INFO): SBD MO Status=0, MOMSN=49386, MT Status=0, MTMSN=0
2024-04-15T16:37:37.806Z,1713199057.806 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:38:08.505Z,1713199088.505 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:42:38.036Z,1713199358.036 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:42:38.036Z,1713199358.036 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:42:38.036Z,1713199358.036 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:42:38.036Z,1713199358.036 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:42:38.438Z,1713199358.438 [Default:CheckIn:D] Stopped
2024-04-15T16:42:38.438Z,1713199358.438 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:42:38.840Z,1713199358.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.050578 min
2024-04-15T16:42:38.840Z,1713199358.840 [Default:CheckIn:E] Stopped
2024-04-15T16:42:38.840Z,1713199358.840 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:42:38.840Z,1713199358.840 [Default:CheckIn] Stopped
2024-04-15T16:42:38.840Z,1713199358.840 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:42:38.840Z,1713199358.840 [Default:CheckIn](INFO): Running loop #15
2024-04-15T16:42:38.840Z,1713199358.840 [Default:CheckIn] Running Loop=15
2024-04-15T16:42:38.840Z,1713199358.840 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:42:38.841Z,1713199358.841 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:42:40.847Z,1713199360.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164240.00,A,3648.13896,N,12147.20871,W,0.019,70.09,150424,,,D*4D
2024-04-15T16:42:40.849Z,1713199360.849 [NAL9602](INFO): GPS fix at 20240415T164240: (36.802316, -121.786812)
2024-04-15T16:42:40.860Z,1713199360.860 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:42:40.860Z,1713199360.860 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:42:49.263Z,1713199369.263 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0052.lzma
2024-04-15T16:42:50.265Z,1713199370.265 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0052.lzma.bak
2024-04-15T16:42:50.265Z,1713199370.265 [DataOverHttps](INFO): SBD MOMSN=19408328
2024-04-15T16:42:55.445Z,1713199375.445 [NAL9602](INFO): SBD MO Status=0, MOMSN=49387, MT Status=0, MTMSN=0
2024-04-15T16:42:55.446Z,1713199375.446 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:43:09.131Z,1713199389.131 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0053.lzma
2024-04-15T16:43:10.133Z,1713199390.133 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0053.lzma.bak
2024-04-15T16:43:10.133Z,1713199390.133 [DataOverHttps](INFO): SBD MOMSN=19408332
2024-04-15T16:43:11.202Z,1713199391.202 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:43:11.202Z,1713199391.202 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:43:11.202Z,1713199391.202 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:43:26.144Z,1713199406.144 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:48:11.958Z,1713199691.958 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:48:11.958Z,1713199691.958 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:48:11.958Z,1713199691.958 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:48:11.958Z,1713199691.958 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:48:12.374Z,1713199692.374 [Default:CheckIn:D] Stopped
2024-04-15T16:48:12.374Z,1713199692.374 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:48:12.764Z,1713199692.764 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.616170 min
2024-04-15T16:48:12.764Z,1713199692.764 [Default:CheckIn:E] Stopped
2024-04-15T16:48:12.764Z,1713199692.764 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:48:12.764Z,1713199692.764 [Default:CheckIn] Stopped
2024-04-15T16:48:12.764Z,1713199692.764 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:48:12.764Z,1713199692.764 [Default:CheckIn](INFO): Running loop #16
2024-04-15T16:48:12.764Z,1713199692.764 [Default:CheckIn] Running Loop=16
2024-04-15T16:48:12.764Z,1713199692.764 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:48:12.765Z,1713199692.765 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:48:14.767Z,1713199694.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164814.00,A,3648.13926,N,12147.20981,W,0.039,70.09,150424,,,D*40
2024-04-15T16:48:14.769Z,1713199694.769 [NAL9602](INFO): GPS fix at 20240415T164814: (36.802321, -121.786830)
2024-04-15T16:48:14.780Z,1713199694.780 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:48:14.780Z,1713199694.780 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:48:25.451Z,1713199705.451 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0055.lzma
2024-04-15T16:48:26.453Z,1713199706.453 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0055.lzma.bak
2024-04-15T16:48:26.453Z,1713199706.453 [DataOverHttps](INFO): SBD MOMSN=19408360
2024-04-15T16:48:33.811Z,1713199713.811 [NAL9602](INFO): SBD MO Status=0, MOMSN=49388, MT Status=0, MTMSN=0
2024-04-15T16:48:33.811Z,1713199713.811 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:48:42.471Z,1713199722.471 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240415T150038/Express0056.lzma
2024-04-15T16:48:43.473Z,1713199723.473 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0056.lzma.bak
2024-04-15T16:48:43.473Z,1713199723.473 [DataOverHttps](INFO): SBD MOMSN=19408363
2024-04-15T16:48:44.743Z,1713199724.743 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:48:44.743Z,1713199724.743 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:48:44.744Z,1713199724.744 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:49:04.611Z,1713199744.611 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:53:45.267Z,1713200025.267 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:53:45.267Z,1713200025.267 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:53:45.267Z,1713200025.267 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:53:45.267Z,1713200025.267 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:53:45.643Z,1713200025.643 [Default:CheckIn:D] Stopped
2024-04-15T16:53:45.643Z,1713200025.643 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:53:46.051Z,1713200026.051 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.170662 min
2024-04-15T16:53:46.052Z,1713200026.052 [Default:CheckIn:E] Stopped
2024-04-15T16:53:46.052Z,1713200026.052 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:53:46.052Z,1713200026.052 [Default:CheckIn] Stopped
2024-04-15T16:53:46.052Z,1713200026.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:53:46.052Z,1713200026.052 [Default:CheckIn](INFO): Running loop #17
2024-04-15T16:53:46.052Z,1713200026.052 [Default:CheckIn] Running Loop=17
2024-04-15T16:53:46.052Z,1713200026.052 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:53:46.052Z,1713200026.052 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:53:48.062Z,1713200028.062 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165347.00,A,3648.13882,N,12147.20949,W,0.058,70.09,150424,,,D*40
2024-04-15T16:53:48.064Z,1713200028.064 [NAL9602](INFO): GPS fix at 20240415T165347: (36.802314, -121.786825)
2024-04-15T16:53:48.095Z,1713200028.095 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:53:48.095Z,1713200028.095 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:53:56.122Z,1713200036.122 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0058.lzma
2024-04-15T16:53:57.125Z,1713200037.125 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0058.lzma.bak
2024-04-15T16:53:57.125Z,1713200037.125 [DataOverHttps](INFO): SBD MOMSN=19408377
2024-04-15T16:54:05.441Z,1713200045.441 [NAL9602](INFO): SBD MO Status=0, MOMSN=49389, MT Status=0, MTMSN=0
2024-04-15T16:54:05.442Z,1713200045.442 [NAL9602](INFO): No messages in MT queue
2024-04-15T16:54:13.151Z,1713200053.151 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240415T150038/Express0059.lzma
2024-04-15T16:54:14.141Z,1713200054.141 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0059.lzma.bak
2024-04-15T16:54:14.141Z,1713200054.141 [DataOverHttps](INFO): SBD MOMSN=19408380
2024-04-15T16:54:15.161Z,1713200055.161 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:54:15.161Z,1713200055.161 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:54:15.161Z,1713200055.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T16:54:36.180Z,1713200076.180 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T16:59:15.683Z,1713200355.683 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T16:59:15.683Z,1713200355.683 [Default:CheckIn:C.Wait] Stopped
2024-04-15T16:59:15.683Z,1713200355.683 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T16:59:15.683Z,1713200355.683 [Default:CheckIn:D] Running Loop=1
2024-04-15T16:59:16.094Z,1713200356.094 [Default:CheckIn:D] Stopped
2024-04-15T16:59:16.094Z,1713200356.094 [Default:CheckIn:E] Running Loop=1
2024-04-15T16:59:16.490Z,1713200356.490 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.678174 min
2024-04-15T16:59:16.490Z,1713200356.490 [Default:CheckIn:E] Stopped
2024-04-15T16:59:16.490Z,1713200356.490 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T16:59:16.491Z,1713200356.491 [Default:CheckIn] Stopped
2024-04-15T16:59:16.491Z,1713200356.491 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T16:59:16.491Z,1713200356.491 [Default:CheckIn](INFO): Running loop #18
2024-04-15T16:59:16.491Z,1713200356.491 [Default:CheckIn] Running Loop=18
2024-04-15T16:59:16.491Z,1713200356.491 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T16:59:16.491Z,1713200356.491 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T16:59:18.498Z,1713200358.498 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165917.00,A,3648.13775,N,12147.21038,W,0.039,70.09,150424,,,D*41
2024-04-15T16:59:18.500Z,1713200358.500 [NAL9602](INFO): GPS fix at 20240415T165917: (36.802296, -121.786840)
2024-04-15T16:59:18.511Z,1713200358.511 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T16:59:18.511Z,1713200358.511 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T16:59:26.302Z,1713200366.302 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0061.lzma
2024-04-15T16:59:27.305Z,1713200367.305 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0061.lzma.bak
2024-04-15T16:59:27.305Z,1713200367.305 [DataOverHttps](INFO): SBD MOMSN=19408393
2024-04-15T16:59:43.511Z,1713200383.511 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0062.lzma
2024-04-15T16:59:44.513Z,1713200384.513 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0062.lzma.bak
2024-04-15T16:59:44.513Z,1713200384.513 [DataOverHttps](INFO): SBD MOMSN=19408396
2024-04-15T16:59:45.622Z,1713200385.622 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T16:59:45.622Z,1713200385.622 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T16:59:45.622Z,1713200385.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:00:29.662Z,1713200429.662 [NAL9602](INFO): SBD MO Status=0, MOMSN=49390, MT Status=0, MTMSN=0
2024-04-15T17:00:29.662Z,1713200429.662 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:00:58.005Z,1713200458.005 [Sonardyne_Nano](ERROR): Failed to parse response: >VS:2109,HPR45,EXT,TILT
2024-04-15T17:00:58.005Z,1713200458.005 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T17:01:00.436Z,1713200460.436 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:04:46.018Z,1713200686.018 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:04:46.018Z,1713200686.018 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:04:46.018Z,1713200686.018 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:04:46.018Z,1713200686.018 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:04:46.430Z,1713200686.430 [Default:CheckIn:D] Stopped
2024-04-15T17:04:46.431Z,1713200686.431 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:04:46.839Z,1713200686.839 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.183781 min
2024-04-15T17:04:46.839Z,1713200686.839 [Default:CheckIn:E] Stopped
2024-04-15T17:04:46.839Z,1713200686.839 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:04:46.839Z,1713200686.839 [Default:CheckIn] Stopped
2024-04-15T17:04:46.839Z,1713200686.839 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:04:46.840Z,1713200686.840 [Default:CheckIn](INFO): Running loop #19
2024-04-15T17:04:46.840Z,1713200686.840 [Default:CheckIn] Running Loop=19
2024-04-15T17:04:46.840Z,1713200686.840 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:04:46.840Z,1713200686.840 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:04:48.842Z,1713200688.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170448.00,A,3648.13904,N,12147.20781,W,0.019,70.09,150424,,,D*4C
2024-04-15T17:04:48.852Z,1713200688.852 [NAL9602](INFO): GPS fix at 20240415T170448: (36.802317, -121.786797)
2024-04-15T17:04:48.863Z,1713200688.863 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:04:48.863Z,1713200688.863 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:04:58.625Z,1713200698.625 [NAL9602](INFO): SBD MO Status=0, MOMSN=49391, MT Status=0, MTMSN=0
2024-04-15T17:04:58.625Z,1713200698.625 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:04:59.434Z,1713200699.434 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0064.lzma
2024-04-15T17:05:00.437Z,1713200700.437 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0064.lzma.bak
2024-04-15T17:05:00.437Z,1713200700.437 [DataOverHttps](INFO): SBD MOMSN=19408407
2024-04-15T17:05:16.411Z,1713200716.411 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240415T150038/Express0065.lzma
2024-04-15T17:05:17.413Z,1713200717.413 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0065.lzma.bak
2024-04-15T17:05:17.413Z,1713200717.413 [DataOverHttps](INFO): SBD MOMSN=19408410
2024-04-15T17:05:18.797Z,1713200718.797 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:05:18.797Z,1713200718.797 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:05:18.797Z,1713200718.797 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:05:29.280Z,1713200729.280 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:10:19.547Z,1713201019.547 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:10:19.547Z,1713201019.547 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:10:19.547Z,1713201019.547 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:10:19.547Z,1713201019.547 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:10:19.955Z,1713201019.955 [Default:CheckIn:D] Stopped
2024-04-15T17:10:19.955Z,1713201019.955 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:10:20.375Z,1713201020.375 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.742529 min
2024-04-15T17:10:20.375Z,1713201020.375 [Default:CheckIn:E] Stopped
2024-04-15T17:10:20.375Z,1713201020.375 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:10:20.375Z,1713201020.375 [Default:CheckIn] Stopped
2024-04-15T17:10:20.375Z,1713201020.375 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:10:20.376Z,1713201020.376 [Default:CheckIn](INFO): Running loop #20
2024-04-15T17:10:20.376Z,1713201020.376 [Default:CheckIn] Running Loop=20
2024-04-15T17:10:20.376Z,1713201020.376 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:10:20.376Z,1713201020.376 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:10:22.346Z,1713201022.346 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171021.00,A,3648.14002,N,12147.20820,W,0.039,0.00,150424,,,D*76
2024-04-15T17:10:22.348Z,1713201022.348 [NAL9602](INFO): GPS fix at 20240415T171021: (36.802334, -121.786803)
2024-04-15T17:10:22.398Z,1713201022.398 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:10:22.398Z,1713201022.398 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:10:30.894Z,1713201030.894 [NAL9602](INFO): SBD MO Status=0, MOMSN=49392, MT Status=0, MTMSN=0
2024-04-15T17:10:30.894Z,1713201030.894 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:10:32.858Z,1713201032.858 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0067.lzma
2024-04-15T17:10:33.861Z,1713201033.861 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0067.lzma.bak
2024-04-15T17:10:33.861Z,1713201033.861 [DataOverHttps](INFO): SBD MOMSN=19408422
2024-04-15T17:10:51.519Z,1713201051.519 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240415T150038/Express0068.lzma
2024-04-15T17:10:52.521Z,1713201052.521 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0068.lzma.bak
2024-04-15T17:10:52.522Z,1713201052.522 [DataOverHttps](IMPORTANT): SBD MOMSN=19408426, MTMSN=20240415T171051
2024-04-15T17:10:53.581Z,1713201053.581 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:10:53.581Z,1713201053.581 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:10:53.581Z,1713201053.581 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:10:57.966Z,1713201057.966 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T17:11:01.596Z,1713201061.596 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:11:01.718Z,1713201061.718 [DataOverHttps](INFO): Received command: strobe off
2024-04-15T17:11:01.792Z,1713201061.792 [CommandExec](IMPORTANT): got command strobe off
2024-04-15T17:11:01.792Z,1713201061.792 [CommandExec](IMPORTANT): Deactivating strobe
2024-04-15T17:15:10.054Z,1713201310.054 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T17:15:22.176Z,1713201322.176 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T17:15:34.703Z,1713201334.703 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T17:15:47.626Z,1713201347.626 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T17:15:54.111Z,1713201354.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:15:54.111Z,1713201354.111 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:15:54.111Z,1713201354.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:15:54.112Z,1713201354.112 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:15:54.520Z,1713201354.520 [Default:CheckIn:D] Stopped
2024-04-15T17:15:54.520Z,1713201354.520 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:15:54.915Z,1713201354.915 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.318612 min
2024-04-15T17:15:54.916Z,1713201354.916 [Default:CheckIn:E] Stopped
2024-04-15T17:15:54.916Z,1713201354.916 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:15:54.916Z,1713201354.916 [Default:CheckIn] Stopped
2024-04-15T17:15:54.916Z,1713201354.916 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:15:54.916Z,1713201354.916 [Default:CheckIn](INFO): Running loop #21
2024-04-15T17:15:54.916Z,1713201354.916 [Default:CheckIn] Running Loop=21
2024-04-15T17:15:54.916Z,1713201354.916 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:15:54.916Z,1713201354.916 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:15:56.931Z,1713201356.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171556.00,A,3648.13888,N,12147.20993,W,0.078,0.00,150424,,,D*72
2024-04-15T17:15:56.933Z,1713201356.933 [NAL9602](INFO): GPS fix at 20240415T171556: (36.802315, -121.786832)
2024-04-15T17:15:56.944Z,1713201356.944 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:15:56.944Z,1713201356.944 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:16:00.561Z,1713201360.561 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T17:16:04.906Z,1713201364.906 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0070.lzma
2024-04-15T17:16:05.909Z,1713201365.909 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0070.lzma.bak
2024-04-15T17:16:05.909Z,1713201365.909 [DataOverHttps](INFO): SBD MOMSN=19408444
2024-04-15T17:16:13.482Z,1713201373.482 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T17:16:16.730Z,1713201376.730 [NAL9602](INFO): SBD MO Status=0, MOMSN=49393, MT Status=0, MTMSN=0
2024-04-15T17:16:16.730Z,1713201376.730 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:16:24.795Z,1713201384.795 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20240415T150038/Express0071.lzma
2024-04-15T17:16:25.797Z,1713201385.797 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0071.lzma.bak
2024-04-15T17:16:25.797Z,1713201385.797 [DataOverHttps](INFO): SBD MOMSN=19408447
2024-04-15T17:16:26.467Z,1713201386.467 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T17:16:42.495Z,1713201402.495 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240415T150038/Express0074.lzma
2024-04-15T17:16:43.497Z,1713201403.497 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0074.lzma.bak
2024-04-15T17:16:43.497Z,1713201403.497 [DataOverHttps](INFO): SBD MOMSN=19408453
2024-04-15T17:16:44.659Z,1713201404.659 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:16:44.659Z,1713201404.659 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:16:44.659Z,1713201404.659 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:16:47.480Z,1713201407.480 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:19:07.430Z,1713201547.430 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-04-15T17:19:07.442Z,1713201547.442 [BPC1](INFO): Received data from all battery sticks.
2024-04-15T17:20:58.242Z,1713201658.242 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T17:21:45.522Z,1713201705.522 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:21:45.522Z,1713201705.522 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:21:45.522Z,1713201705.522 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:21:45.522Z,1713201705.522 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:21:45.931Z,1713201705.931 [Default:CheckIn:D] Stopped
2024-04-15T17:21:45.931Z,1713201705.931 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:21:46.328Z,1713201706.328 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.175472 min
2024-04-15T17:21:46.328Z,1713201706.328 [Default:CheckIn:E] Stopped
2024-04-15T17:21:46.328Z,1713201706.328 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:21:46.328Z,1713201706.328 [Default:CheckIn] Stopped
2024-04-15T17:21:46.328Z,1713201706.328 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:21:46.328Z,1713201706.328 [Default:CheckIn](INFO): Running loop #22
2024-04-15T17:21:46.328Z,1713201706.328 [Default:CheckIn] Running Loop=22
2024-04-15T17:21:46.328Z,1713201706.328 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:21:46.329Z,1713201706.329 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:21:48.338Z,1713201708.338 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172147.00,A,3648.13850,N,12147.21140,W,0.019,0.00,150424,,,D*70
2024-04-15T17:21:48.340Z,1713201708.340 [NAL9602](INFO): GPS fix at 20240415T172147: (36.802308, -121.786857)
2024-04-15T17:21:48.351Z,1713201708.351 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:21:48.351Z,1713201708.351 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:21:56.134Z,1713201716.134 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20240415T150038/Courier0076.lzma
2024-04-15T17:21:57.137Z,1713201717.137 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0076.lzma.bak
2024-04-15T17:21:57.137Z,1713201717.137 [DataOverHttps](INFO): SBD MOMSN=19408463
2024-04-15T17:21:58.841Z,1713201718.841 [NAL9602](INFO): SBD MO Status=0, MOMSN=49394, MT Status=0, MTMSN=0
2024-04-15T17:21:58.841Z,1713201718.841 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:22:13.054Z,1713201733.054 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240415T150038/Express0077.lzma
2024-04-15T17:22:14.057Z,1713201734.057 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0077.lzma.bak
2024-04-15T17:22:14.057Z,1713201734.057 [DataOverHttps](INFO): SBD MOMSN=19408467
2024-04-15T17:22:15.411Z,1713201735.411 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:22:15.411Z,1713201735.411 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:22:15.411Z,1713201735.411 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:22:29.588Z,1713201749.588 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:27:15.922Z,1713202035.922 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:27:15.922Z,1713202035.922 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:27:15.922Z,1713202035.922 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:27:15.923Z,1713202035.923 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:27:16.329Z,1713202036.329 [Default:CheckIn:D] Stopped
2024-04-15T17:27:16.329Z,1713202036.329 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:27:16.743Z,1713202036.743 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.682080 min
2024-04-15T17:27:16.743Z,1713202036.743 [Default:CheckIn:E] Stopped
2024-04-15T17:27:16.743Z,1713202036.743 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:27:16.743Z,1713202036.743 [Default:CheckIn] Stopped
2024-04-15T17:27:16.743Z,1713202036.743 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:27:16.743Z,1713202036.743 [Default:CheckIn](INFO): Running loop #23
2024-04-15T17:27:16.743Z,1713202036.743 [Default:CheckIn] Running Loop=23
2024-04-15T17:27:16.743Z,1713202036.743 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:27:16.744Z,1713202036.744 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:27:18.746Z,1713202038.746 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172718.00,A,3648.13903,N,12147.21025,W,0.019,0.00,150424,,,D*79
2024-04-15T17:27:18.748Z,1713202038.748 [NAL9602](INFO): GPS fix at 20240415T172718: (36.802317, -121.786838)
2024-04-15T17:27:18.779Z,1713202038.779 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:27:18.779Z,1713202038.779 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:27:26.526Z,1713202046.526 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0079.lzma
2024-04-15T17:27:27.529Z,1713202047.529 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0079.lzma.bak
2024-04-15T17:27:27.529Z,1713202047.529 [DataOverHttps](INFO): SBD MOMSN=19408478
2024-04-15T17:27:43.502Z,1713202063.502 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0080.lzma
2024-04-15T17:27:43.790Z,1713202063.790 [NAL9602](INFO): SBD MO Status=0, MOMSN=49395, MT Status=0, MTMSN=0
2024-04-15T17:27:43.790Z,1713202063.790 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:27:44.505Z,1713202064.505 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0080.lzma.bak
2024-04-15T17:27:44.505Z,1713202064.505 [DataOverHttps](INFO): SBD MOMSN=19408481
2024-04-15T17:27:45.830Z,1713202065.830 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:27:45.830Z,1713202065.830 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:27:45.830Z,1713202065.830 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:28:14.496Z,1713202094.496 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:30:58.356Z,1713202258.356 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T17:32:46.308Z,1713202366.308 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:32:46.308Z,1713202366.308 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:32:46.308Z,1713202366.308 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:32:46.308Z,1713202366.308 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:32:46.702Z,1713202366.702 [Default:CheckIn:D] Stopped
2024-04-15T17:32:46.702Z,1713202366.702 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:32:47.102Z,1713202367.102 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.188314 min
2024-04-15T17:32:47.102Z,1713202367.102 [Default:CheckIn:E] Stopped
2024-04-15T17:32:47.102Z,1713202367.102 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:32:47.102Z,1713202367.102 [Default:CheckIn] Stopped
2024-04-15T17:32:47.103Z,1713202367.103 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:32:47.103Z,1713202367.103 [Default:CheckIn](INFO): Running loop #24
2024-04-15T17:32:47.103Z,1713202367.103 [Default:CheckIn] Running Loop=24
2024-04-15T17:32:47.103Z,1713202367.103 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:32:47.103Z,1713202367.103 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:32:49.114Z,1713202369.114 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173248.00,A,3648.13969,N,12147.21018,W,0.019,0.00,150424,,,D*7A
2024-04-15T17:32:49.116Z,1713202369.116 [NAL9602](INFO): GPS fix at 20240415T173248: (36.802328, -121.786836)
2024-04-15T17:32:49.164Z,1713202369.164 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:32:49.164Z,1713202369.164 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:32:59.087Z,1713202379.087 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0082.lzma
2024-04-15T17:33:00.089Z,1713202380.089 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0082.lzma.bak
2024-04-15T17:33:00.089Z,1713202380.089 [DataOverHttps](INFO): SBD MOMSN=19408492
2024-04-15T17:33:13.926Z,1713202393.926 [NAL9602](INFO): SBD MO Status=0, MOMSN=49396, MT Status=0, MTMSN=0
2024-04-15T17:33:13.926Z,1713202393.926 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:33:16.078Z,1713202396.078 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240415T150038/Express0083.lzma
2024-04-15T17:33:17.081Z,1713202397.081 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0083.lzma.bak
2024-04-15T17:33:17.081Z,1713202397.081 [DataOverHttps](INFO): SBD MOMSN=19408495
2024-04-15T17:33:18.374Z,1713202398.374 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:33:18.374Z,1713202398.374 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:33:18.374Z,1713202398.374 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:33:44.625Z,1713202424.625 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:38:19.126Z,1713202699.126 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:38:19.126Z,1713202699.126 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:38:19.126Z,1713202699.126 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:38:19.126Z,1713202699.126 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:38:19.522Z,1713202699.522 [Default:CheckIn:D] Stopped
2024-04-15T17:38:19.522Z,1713202699.522 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:38:19.932Z,1713202699.932 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.735319 min
2024-04-15T17:38:19.932Z,1713202699.932 [Default:CheckIn:E] Stopped
2024-04-15T17:38:19.932Z,1713202699.932 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:38:19.932Z,1713202699.932 [Default:CheckIn] Stopped
2024-04-15T17:38:19.932Z,1713202699.932 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:38:19.932Z,1713202699.932 [Default:CheckIn](INFO): Running loop #25
2024-04-15T17:38:19.933Z,1713202699.933 [Default:CheckIn] Running Loop=25
2024-04-15T17:38:19.933Z,1713202699.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:38:19.933Z,1713202699.933 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:38:21.926Z,1713202701.926 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173821.00,A,3648.13948,N,12147.20957,W,0.019,0.00,150424,,,D*7F
2024-04-15T17:38:21.928Z,1713202701.928 [NAL9602](INFO): GPS fix at 20240415T173821: (36.802325, -121.786826)
2024-04-15T17:38:21.975Z,1713202701.975 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:38:21.976Z,1713202701.976 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:38:30.322Z,1713202710.322 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0085.lzma
2024-04-15T17:38:31.325Z,1713202711.325 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0085.lzma.bak
2024-04-15T17:38:31.325Z,1713202711.325 [DataOverHttps](INFO): SBD MOMSN=19408511
2024-04-15T17:38:47.267Z,1713202727.267 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240415T150038/Express0086.lzma
2024-04-15T17:38:48.273Z,1713202728.273 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0086.lzma.bak
2024-04-15T17:38:48.273Z,1713202728.273 [DataOverHttps](INFO): SBD MOMSN=19408514
2024-04-15T17:38:49.450Z,1713202729.450 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:38:49.450Z,1713202729.450 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:38:49.450Z,1713202729.450 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:38:56.338Z,1713202736.338 [NAL9602](INFO): SBD MO Status=0, MOMSN=49397, MT Status=0, MTMSN=0
2024-04-15T17:38:56.338Z,1713202736.338 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:39:27.039Z,1713202767.039 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:40:58.437Z,1713202858.437 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T17:43:50.158Z,1713203030.158 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:43:50.158Z,1713203030.158 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:43:50.158Z,1713203030.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:43:50.158Z,1713203030.158 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:43:50.559Z,1713203030.559 [Default:CheckIn:D] Stopped
2024-04-15T17:43:50.559Z,1713203030.559 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:43:50.976Z,1713203030.976 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.252588 min
2024-04-15T17:43:50.976Z,1713203030.976 [Default:CheckIn:E] Stopped
2024-04-15T17:43:50.976Z,1713203030.976 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:43:50.976Z,1713203030.976 [Default:CheckIn] Stopped
2024-04-15T17:43:50.976Z,1713203030.976 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:43:50.977Z,1713203030.977 [Default:CheckIn](INFO): Running loop #26
2024-04-15T17:43:50.977Z,1713203030.977 [Default:CheckIn] Running Loop=26
2024-04-15T17:43:50.977Z,1713203030.977 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:43:50.977Z,1713203030.977 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:43:52.978Z,1713203032.978 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174352.00,A,3648.13916,N,12147.20969,W,0.019,0.00,150424,,,D*71
2024-04-15T17:43:52.980Z,1713203032.980 [NAL9602](INFO): GPS fix at 20240415T174352: (36.802319, -121.786828)
2024-04-15T17:43:53.011Z,1713203033.011 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:43:53.011Z,1713203033.011 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:44:00.710Z,1713203040.710 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0088.lzma
2024-04-15T17:44:01.713Z,1713203041.713 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0088.lzma.bak
2024-04-15T17:44:01.713Z,1713203041.713 [DataOverHttps](INFO): SBD MOMSN=19408531
2024-04-15T17:44:17.806Z,1713203057.806 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0089.lzma
2024-04-15T17:44:18.809Z,1713203058.809 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0089.lzma.bak
2024-04-15T17:44:18.810Z,1713203058.810 [DataOverHttps](INFO): SBD MOMSN=19408534
2024-04-15T17:44:20.065Z,1713203060.065 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:44:20.065Z,1713203060.065 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:44:20.065Z,1713203060.065 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:44:23.301Z,1713203063.301 [NAL9602](INFO): SBD MO Status=2, MOMSN=49398, MT Status=2, MTMSN=0
2024-04-15T17:44:23.302Z,1713203063.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-15T17:44:49.190Z,1713203089.190 [NAL9602](INFO): SBD MO Status=2, MOMSN=49398, MT Status=2, MTMSN=0
2024-04-15T17:44:49.190Z,1713203089.190 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-04-15T17:46:20.150Z,1713203180.150 [NAL9602](INFO): SBD MO Status=0, MOMSN=49398, MT Status=0, MTMSN=0
2024-04-15T17:46:20.150Z,1713203180.150 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:46:50.878Z,1713203210.878 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:49:20.790Z,1713203360.790 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:49:20.790Z,1713203360.790 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:49:20.790Z,1713203360.790 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:49:20.790Z,1713203360.790 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:49:21.198Z,1713203361.198 [Default:CheckIn:D] Stopped
2024-04-15T17:49:21.198Z,1713203361.198 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:49:21.603Z,1713203361.603 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 164.763249 min
2024-04-15T17:49:21.603Z,1713203361.603 [Default:CheckIn:E] Stopped
2024-04-15T17:49:21.603Z,1713203361.603 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:49:21.603Z,1713203361.603 [Default:CheckIn] Stopped
2024-04-15T17:49:21.604Z,1713203361.604 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:49:21.604Z,1713203361.604 [Default:CheckIn](INFO): Running loop #27
2024-04-15T17:49:21.604Z,1713203361.604 [Default:CheckIn] Running Loop=27
2024-04-15T17:49:21.604Z,1713203361.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:49:21.604Z,1713203361.604 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:49:23.610Z,1713203363.610 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174922.00,A,3648.13736,N,12147.21011,W,0.039,0.00,150424,,,D*75
2024-04-15T17:49:23.612Z,1713203363.612 [NAL9602](INFO): GPS fix at 20240415T174922: (36.802289, -121.786835)
2024-04-15T17:49:23.644Z,1713203363.644 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:49:23.644Z,1713203363.644 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:49:30.650Z,1713203370.650 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0091.lzma
2024-04-15T17:49:31.653Z,1713203371.653 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0091.lzma.bak
2024-04-15T17:49:31.653Z,1713203371.653 [DataOverHttps](INFO): SBD MOMSN=19408549
2024-04-15T17:49:47.778Z,1713203387.778 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240415T150038/Express0092.lzma
2024-04-15T17:49:48.781Z,1713203388.781 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0092.lzma.bak
2024-04-15T17:49:48.781Z,1713203388.781 [DataOverHttps](INFO): SBD MOMSN=19408552
2024-04-15T17:49:49.878Z,1713203389.878 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:49:49.879Z,1713203389.879 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:49:49.879Z,1713203389.879 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:49:54.314Z,1713203394.314 [NAL9602](INFO): SBD MO Status=0, MOMSN=49399, MT Status=0, MTMSN=0
2024-04-15T17:49:54.314Z,1713203394.314 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:50:25.056Z,1713203425.056 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T17:50:58.606Z,1713203458.606 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T17:54:50.559Z,1713203690.559 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T17:54:50.559Z,1713203690.559 [Default:CheckIn:C.Wait] Stopped
2024-04-15T17:54:50.559Z,1713203690.559 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T17:54:50.559Z,1713203690.559 [Default:CheckIn:D] Running Loop=1
2024-04-15T17:54:50.959Z,1713203690.959 [Default:CheckIn:D] Stopped
2024-04-15T17:54:50.959Z,1713203690.959 [Default:CheckIn:E] Running Loop=1
2024-04-15T17:54:51.389Z,1713203691.389 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 170.259245 min
2024-04-15T17:54:51.389Z,1713203691.389 [Default:CheckIn:E] Stopped
2024-04-15T17:54:51.389Z,1713203691.389 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T17:54:51.389Z,1713203691.389 [Default:CheckIn] Stopped
2024-04-15T17:54:51.389Z,1713203691.389 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T17:54:51.389Z,1713203691.389 [Default:CheckIn](INFO): Running loop #28
2024-04-15T17:54:51.390Z,1713203691.390 [Default:CheckIn] Running Loop=28
2024-04-15T17:54:51.390Z,1713203691.390 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T17:54:51.390Z,1713203691.390 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T17:54:53.364Z,1713203693.364 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175452.00,A,3648.13875,N,12147.20562,W,0.039,125.09,150424,,,D*79
2024-04-15T17:54:53.366Z,1713203693.366 [NAL9602](INFO): GPS fix at 20240415T175452: (36.802312, -121.786760)
2024-04-15T17:54:53.385Z,1713203693.385 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T17:54:53.385Z,1713203693.385 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T17:55:00.707Z,1713203700.707 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T150038/Courier0094.lzma
2024-04-15T17:55:01.705Z,1713203701.705 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0094.lzma.bak
2024-04-15T17:55:01.705Z,1713203701.705 [DataOverHttps](INFO): SBD MOMSN=19408565
2024-04-15T17:55:04.786Z,1713203704.786 [NAL9602](INFO): SBD MO Status=0, MOMSN=49400, MT Status=0, MTMSN=0
2024-04-15T17:55:04.786Z,1713203704.786 [NAL9602](INFO): No messages in MT queue
2024-04-15T17:55:17.738Z,1713203717.738 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240415T150038/Express0095.lzma
2024-04-15T17:55:18.741Z,1713203718.741 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0095.lzma.bak
2024-04-15T17:55:18.741Z,1713203718.741 [DataOverHttps](INFO): SBD MOMSN=19408568
2024-04-15T17:55:20.147Z,1713203720.147 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T17:55:20.147Z,1713203720.147 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T17:55:20.147Z,1713203720.147 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T17:55:35.488Z,1713203735.488 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T18:00:20.841Z,1713204020.841 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T18:00:20.841Z,1713204020.841 [Default:CheckIn:C.Wait] Stopped
2024-04-15T18:00:20.841Z,1713204020.841 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T18:00:20.841Z,1713204020.841 [Default:CheckIn:D] Running Loop=1
2024-04-15T18:00:21.267Z,1713204021.267 [Default:CheckIn:D] Stopped
2024-04-15T18:00:21.267Z,1713204021.267 [Default:CheckIn:E] Running Loop=1
2024-04-15T18:00:21.699Z,1713204021.699 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.764193 min
2024-04-15T18:00:21.699Z,1713204021.699 [Default:CheckIn:E] Stopped
2024-04-15T18:00:21.699Z,1713204021.699 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T18:00:21.699Z,1713204021.699 [Default:CheckIn] Stopped
2024-04-15T18:00:21.700Z,1713204021.700 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T18:00:21.700Z,1713204021.700 [Default:CheckIn](INFO): Running loop #29
2024-04-15T18:00:21.700Z,1713204021.700 [Default:CheckIn] Running Loop=29
2024-04-15T18:00:21.700Z,1713204021.700 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T18:00:21.700Z,1713204021.700 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T18:00:23.671Z,1713204023.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180022.00,A,3648.13748,N,12147.20642,W,0.058,125.09,150424,,,D*77
2024-04-15T18:00:23.673Z,1713204023.673 [NAL9602](INFO): GPS fix at 20240415T180022: (36.802291, -121.786774)
2024-04-15T18:00:23.692Z,1713204023.692 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T18:00:23.692Z,1713204023.692 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T18:00:31.594Z,1713204031.594 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0097.lzma
2024-04-15T18:00:32.597Z,1713204032.597 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0097.lzma.bak
2024-04-15T18:00:32.597Z,1713204032.597 [DataOverHttps](INFO): SBD MOMSN=19408592
2024-04-15T18:00:40.221Z,1713204040.221 [NAL9602](INFO): SBD MO Status=0, MOMSN=49401, MT Status=0, MTMSN=0
2024-04-15T18:00:40.221Z,1713204040.221 [NAL9602](INFO): No messages in MT queue
2024-04-15T18:00:49.170Z,1713204049.170 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0098.lzma
2024-04-15T18:00:50.173Z,1713204050.173 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0098.lzma.bak
2024-04-15T18:00:50.174Z,1713204050.174 [DataOverHttps](INFO): SBD MOMSN=19408595
2024-04-15T18:00:51.550Z,1713204051.550 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T18:00:51.550Z,1713204051.550 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T18:00:51.550Z,1713204051.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T18:00:58.862Z,1713204058.862 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T18:01:10.940Z,1713204070.940 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T18:05:52.176Z,1713204352.176 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T18:05:52.176Z,1713204352.176 [Default:CheckIn:C.Wait] Stopped
2024-04-15T18:05:52.176Z,1713204352.176 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T18:05:52.176Z,1713204352.176 [Default:CheckIn:D] Running Loop=1
2024-04-15T18:05:52.597Z,1713204352.597 [Default:CheckIn:D] Stopped
2024-04-15T18:05:52.597Z,1713204352.597 [Default:CheckIn:E] Running Loop=1
2024-04-15T18:05:53.003Z,1713204353.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 181.286556 min
2024-04-15T18:05:53.003Z,1713204353.003 [Default:CheckIn:E] Stopped
2024-04-15T18:05:53.003Z,1713204353.003 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T18:05:53.003Z,1713204353.003 [Default:CheckIn] Stopped
2024-04-15T18:05:53.003Z,1713204353.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T18:05:53.003Z,1713204353.003 [Default:CheckIn](INFO): Running loop #30
2024-04-15T18:05:53.004Z,1713204353.004 [Default:CheckIn] Running Loop=30
2024-04-15T18:05:53.004Z,1713204353.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T18:05:53.004Z,1713204353.004 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T18:05:55.001Z,1713204355.001 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180554.00,A,3648.13751,N,12147.20568,W,0.058,125.09,150424,,,D*70
2024-04-15T18:05:55.003Z,1713204355.003 [NAL9602](INFO): GPS fix at 20240415T180554: (36.802292, -121.786761)
2024-04-15T18:05:55.014Z,1713204355.014 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T18:05:55.014Z,1713204355.014 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T18:06:02.678Z,1713204362.678 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0100.lzma
2024-04-15T18:06:03.681Z,1713204363.681 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0100.lzma.bak
2024-04-15T18:06:03.681Z,1713204363.681 [DataOverHttps](INFO): SBD MOMSN=19408617
2024-04-15T18:06:09.137Z,1713204369.137 [NAL9602](INFO): SBD MO Status=0, MOMSN=49402, MT Status=0, MTMSN=0
2024-04-15T18:06:09.138Z,1713204369.138 [NAL9602](INFO): No messages in MT queue
2024-04-15T18:06:21.135Z,1713204381.135 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0101.lzma
2024-04-15T18:06:22.141Z,1713204382.141 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0101.lzma.bak
2024-04-15T18:06:22.141Z,1713204382.141 [DataOverHttps](INFO): SBD MOMSN=19408625
2024-04-15T18:06:23.322Z,1713204383.322 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T18:06:23.322Z,1713204383.322 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T18:06:23.322Z,1713204383.322 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T18:06:39.890Z,1713204399.890 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T18:10:58.882Z,1713204658.882 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T18:11:23.941Z,1713204683.941 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T18:11:23.941Z,1713204683.941 [Default:CheckIn:C.Wait] Stopped
2024-04-15T18:11:23.941Z,1713204683.941 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T18:11:23.942Z,1713204683.942 [Default:CheckIn:D] Running Loop=1
2024-04-15T18:11:24.359Z,1713204684.359 [Default:CheckIn:D] Stopped
2024-04-15T18:11:24.359Z,1713204684.359 [Default:CheckIn:E] Running Loop=1
2024-04-15T18:11:24.776Z,1713204684.776 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 186.815918 min
2024-04-15T18:11:24.776Z,1713204684.776 [Default:CheckIn:E] Stopped
2024-04-15T18:11:24.776Z,1713204684.776 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T18:11:24.776Z,1713204684.776 [Default:CheckIn] Stopped
2024-04-15T18:11:24.776Z,1713204684.776 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T18:11:24.777Z,1713204684.777 [Default:CheckIn](INFO): Running loop #31
2024-04-15T18:11:24.777Z,1713204684.777 [Default:CheckIn] Running Loop=31
2024-04-15T18:11:24.777Z,1713204684.777 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T18:11:24.777Z,1713204684.777 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T18:11:26.764Z,1713204686.764 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181126.00,A,3648.13731,N,12147.20709,W,0.039,125.09,150424,,,D*74
2024-04-15T18:11:26.766Z,1713204686.766 [NAL9602](INFO): GPS fix at 20240415T181126: (36.802289, -121.786785)
2024-04-15T18:11:26.777Z,1713204686.777 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T18:11:26.777Z,1713204686.777 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T18:11:33.971Z,1713204693.971 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0103.lzma
2024-04-15T18:11:34.973Z,1713204694.973 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0103.lzma.bak
2024-04-15T18:11:34.973Z,1713204694.973 [DataOverHttps](INFO): SBD MOMSN=19408637
2024-04-15T18:11:50.982Z,1713204710.982 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240415T150038/Express0104.lzma
2024-04-15T18:11:51.985Z,1713204711.985 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0104.lzma.bak
2024-04-15T18:11:51.985Z,1713204711.985 [DataOverHttps](INFO): SBD MOMSN=19408640
2024-04-15T18:11:53.034Z,1713204713.034 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T18:11:53.034Z,1713204713.034 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T18:11:53.035Z,1713204713.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T18:11:54.233Z,1713204714.233 [NAL9602](INFO): SBD MO Status=0, MOMSN=49403, MT Status=0, MTMSN=0
2024-04-15T18:11:54.233Z,1713204714.233 [NAL9602](INFO): No messages in MT queue
2024-04-15T18:12:24.940Z,1713204744.940 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T18:16:53.632Z,1713205013.632 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T18:16:53.632Z,1713205013.632 [Default:CheckIn:C.Wait] Stopped
2024-04-15T18:16:53.632Z,1713205013.632 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T18:16:53.632Z,1713205013.632 [Default:CheckIn:D] Running Loop=1
2024-04-15T18:16:54.071Z,1713205014.071 [Default:CheckIn:D] Stopped
2024-04-15T18:16:54.071Z,1713205014.071 [Default:CheckIn:E] Running Loop=1
2024-04-15T18:16:54.465Z,1713205014.465 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 192.310921 min
2024-04-15T18:16:54.465Z,1713205014.465 [Default:CheckIn:E] Stopped
2024-04-15T18:16:54.465Z,1713205014.465 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T18:16:54.465Z,1713205014.465 [Default:CheckIn] Stopped
2024-04-15T18:16:54.465Z,1713205014.465 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T18:16:54.465Z,1713205014.465 [Default:CheckIn](INFO): Running loop #32
2024-04-15T18:16:54.465Z,1713205014.465 [Default:CheckIn] Running Loop=32
2024-04-15T18:16:54.465Z,1713205014.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T18:16:54.465Z,1713205014.465 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T18:16:56.442Z,1713205016.442 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181655.00,A,3648.26080,N,12147.13287,W,3.616,6.80,150424,,,D*76
2024-04-15T18:16:56.444Z,1713205016.444 [NAL9602](INFO): GPS fix at 20240415T181655: (36.804347, -121.785548)
2024-04-15T18:16:56.459Z,1713205016.459 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T18:16:56.459Z,1713205016.459 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T18:17:04.154Z,1713205024.154 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0106.lzma
2024-04-15T18:17:05.157Z,1713205025.157 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0106.lzma.bak
2024-04-15T18:17:05.157Z,1713205025.157 [DataOverHttps](INFO): SBD MOMSN=19408653
2024-04-15T18:17:12.609Z,1713205032.609 [NAL9602](INFO): SBD MO Status=0, MOMSN=49404, MT Status=0, MTMSN=0
2024-04-15T18:17:12.610Z,1713205032.610 [NAL9602](INFO): No messages in MT queue
2024-04-15T18:17:21.226Z,1713205041.226 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240415T150038/Express0107.lzma
2024-04-15T18:17:22.229Z,1713205042.229 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0107.lzma.bak
2024-04-15T18:17:22.229Z,1713205042.229 [DataOverHttps](INFO): SBD MOMSN=19408656
2024-04-15T18:17:23.522Z,1713205043.522 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T18:17:23.522Z,1713205043.522 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T18:17:23.522Z,1713205043.522 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T18:17:43.308Z,1713205063.308 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T18:20:14.824Z,1713205214.824 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T18:20:27.326Z,1713205227.326 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T18:20:40.256Z,1713205240.256 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T18:20:53.193Z,1713205253.193 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T18:20:59.258Z,1713205259.258 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T18:21:06.516Z,1713205266.516 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T18:21:18.636Z,1713205278.636 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T18:21:31.562Z,1713205291.562 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T18:22:24.231Z,1713205344.231 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T18:22:24.231Z,1713205344.231 [Default:CheckIn:C.Wait] Stopped
2024-04-15T18:22:24.231Z,1713205344.231 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T18:22:24.231Z,1713205344.231 [Default:CheckIn:D] Running Loop=1
2024-04-15T18:22:24.547Z,1713205344.547 [Default:CheckIn:D] Stopped
2024-04-15T18:22:24.547Z,1713205344.547 [Default:CheckIn:E] Running Loop=1
2024-04-15T18:22:24.930Z,1713205344.930 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 197.819059 min
2024-04-15T18:22:24.930Z,1713205344.930 [Default:CheckIn:E] Stopped
2024-04-15T18:22:24.930Z,1713205344.930 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T18:22:24.931Z,1713205344.931 [Default:CheckIn] Stopped
2024-04-15T18:22:24.931Z,1713205344.931 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T18:22:24.931Z,1713205344.931 [Default:CheckIn](INFO): Running loop #33
2024-04-15T18:22:24.931Z,1713205344.931 [Default:CheckIn] Running Loop=33
2024-04-15T18:22:24.931Z,1713205344.931 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T18:22:24.931Z,1713205344.931 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T18:22:26.931Z,1713205346.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182226.00,A,3648.35576,N,12147.50577,W,9.078,236.24,150424,,,D*7F
2024-04-15T18:22:26.933Z,1713205346.933 [NAL9602](INFO): GPS fix at 20240415T182226: (36.805929, -121.791763)
2024-04-15T18:22:26.944Z,1713205346.944 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T18:22:26.944Z,1713205346.944 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T18:22:34.725Z,1713205354.725 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0109.lzma
2024-04-15T18:22:35.721Z,1713205355.721 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0109.lzma.bak
2024-04-15T18:22:35.721Z,1713205355.721 [DataOverHttps](INFO): SBD MOMSN=19408661
2024-04-15T18:22:42.680Z,1713205362.680 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-04-15T18:22:44.294Z,1713205364.294 [NAL9602](INFO): SBD MO Status=0, MOMSN=49405, MT Status=0, MTMSN=0
2024-04-15T18:22:44.294Z,1713205364.294 [NAL9602](INFO): No messages in MT queue
2024-04-15T18:22:54.727Z,1713205374.727 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240415T150038/Express0110.lzma
2024-04-15T18:22:55.729Z,1713205375.729 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0110.lzma.bak
2024-04-15T18:22:55.729Z,1713205375.729 [DataOverHttps](INFO): SBD MOMSN=19408664
2024-04-15T18:22:56.899Z,1713205376.899 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T18:22:56.899Z,1713205376.899 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T18:22:56.900Z,1713205376.900 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T18:23:15.052Z,1713205395.052 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T18:24:10.827Z,1713205450.827 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-04-15T18:24:10.829Z,1713205450.829 [BPC1](INFO): Received data from all battery sticks.
2024-04-15T18:27:57.480Z,1713205677.480 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T18:27:57.480Z,1713205677.480 [Default:CheckIn:C.Wait] Stopped
2024-04-15T18:27:57.480Z,1713205677.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T18:27:57.480Z,1713205677.480 [Default:CheckIn:D] Running Loop=1
2024-04-15T18:27:57.874Z,1713205677.874 [Default:CheckIn:D] Stopped
2024-04-15T18:27:57.874Z,1713205677.874 [Default:CheckIn:E] Running Loop=1
2024-04-15T18:27:58.280Z,1713205678.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 203.374512 min
2024-04-15T18:27:58.280Z,1713205678.280 [Default:CheckIn:E] Stopped
2024-04-15T18:27:58.280Z,1713205678.280 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T18:27:58.280Z,1713205678.280 [Default:CheckIn] Stopped
2024-04-15T18:27:58.280Z,1713205678.280 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T18:27:58.280Z,1713205678.280 [Default:CheckIn](INFO): Running loop #34
2024-04-15T18:27:58.280Z,1713205678.280 [Default:CheckIn] Running Loop=34
2024-04-15T18:27:58.281Z,1713205678.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T18:27:58.281Z,1713205678.281 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T18:28:00.285Z,1713205680.285 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182759.00,A,3648.24973,N,12148.62176,W,10.263,274.22,150424,,,D*40
2024-04-15T18:28:00.297Z,1713205680.297 [NAL9602](INFO): GPS fix at 20240415T182759: (36.804162, -121.810363)
2024-04-15T18:28:00.308Z,1713205680.308 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T18:28:00.308Z,1713205680.308 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T18:28:08.435Z,1713205688.435 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240415T150038/Courier0112.lzma
2024-04-15T18:28:09.433Z,1713205689.433 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0112.lzma.bak
2024-04-15T18:28:09.433Z,1713205689.433 [DataOverHttps](INFO): SBD MOMSN=19408682
2024-04-15T18:28:28.355Z,1713205708.355 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240415T150038/Express0113.lzma
2024-04-15T18:28:29.357Z,1713205709.357 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0113.lzma.bak
2024-04-15T18:28:29.357Z,1713205709.357 [DataOverHttps](INFO): SBD MOMSN=19408685
2024-04-15T18:28:29.850Z,1713205709.850 [NAL9602](INFO): SBD MO Status=0, MOMSN=49406, MT Status=0, MTMSN=0
2024-04-15T18:28:29.850Z,1713205709.850 [NAL9602](INFO): No messages in MT queue
2024-04-15T18:28:30.680Z,1713205710.680 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T18:28:30.680Z,1713205710.680 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T18:28:30.680Z,1713205710.680 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T18:29:00.552Z,1713205740.552 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T18:30:59.346Z,1713205859.346 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T18:33:31.262Z,1713206011.262 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T18:33:31.263Z,1713206011.263 [Default:CheckIn:C.Wait] Stopped
2024-04-15T18:33:31.263Z,1713206011.263 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T18:33:31.263Z,1713206011.263 [Default:CheckIn:D] Running Loop=1
2024-04-15T18:33:31.658Z,1713206011.658 [Default:CheckIn:D] Stopped
2024-04-15T18:33:31.658Z,1713206011.658 [Default:CheckIn:E] Running Loop=1
2024-04-15T18:33:32.062Z,1713206012.062 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 208.937581 min
2024-04-15T18:33:32.062Z,1713206012.062 [Default:CheckIn:E] Stopped
2024-04-15T18:33:32.062Z,1713206012.062 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T18:33:32.062Z,1713206012.062 [Default:CheckIn] Stopped
2024-04-15T18:33:32.062Z,1713206012.062 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T18:33:32.062Z,1713206012.062 [Default:CheckIn](INFO): Running loop #35
2024-04-15T18:33:32.062Z,1713206012.062 [Default:CheckIn] Running Loop=35
2024-04-15T18:33:32.063Z,1713206012.063 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T18:33:32.063Z,1713206012.063 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T18:33:34.083Z,1713206014.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183333.00,A,3647.99271,N,12149.77118,W,10.827,259.20,150424,,,D*43
2024-04-15T18:33:34.085Z,1713206014.085 [NAL9602](INFO): GPS fix at 20240415T183333: (36.799878, -121.829520)
2024-04-15T18:33:34.096Z,1713206014.096 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T18:33:34.096Z,1713206014.096 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T18:33:41.646Z,1713206021.646 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0115.lzma
2024-04-15T18:33:42.649Z,1713206022.649 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0115.lzma.bak
2024-04-15T18:33:42.649Z,1713206022.649 [DataOverHttps](INFO): SBD MOMSN=19408698
2024-04-15T18:33:44.577Z,1713206024.577 [NAL9602](INFO): SBD MO Status=0, MOMSN=49407, MT Status=0, MTMSN=0
2024-04-15T18:33:44.578Z,1713206024.578 [NAL9602](INFO): No messages in MT queue
2024-04-15T18:33:58.454Z,1713206038.454 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240415T150038/Express0116.lzma
2024-04-15T18:33:59.457Z,1713206039.457 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0116.lzma.bak
2024-04-15T18:33:59.457Z,1713206039.457 [DataOverHttps](INFO): SBD MOMSN=19408701
2024-04-15T18:34:00.755Z,1713206040.755 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T18:34:00.755Z,1713206040.755 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T18:34:00.755Z,1713206040.755 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T18:34:15.285Z,1713206055.285 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T18:39:01.340Z,1713206341.340 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T18:39:01.340Z,1713206341.340 [Default:CheckIn:C.Wait] Stopped
2024-04-15T18:39:01.340Z,1713206341.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T18:39:01.340Z,1713206341.340 [Default:CheckIn:D] Running Loop=1
2024-04-15T18:39:01.736Z,1713206341.736 [Default:CheckIn:D] Stopped
2024-04-15T18:39:01.736Z,1713206341.736 [Default:CheckIn:E] Running Loop=1
2024-04-15T18:39:02.144Z,1713206342.144 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 214.438883 min
2024-04-15T18:39:02.144Z,1713206342.144 [Default:CheckIn:E] Stopped
2024-04-15T18:39:02.144Z,1713206342.144 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T18:39:02.144Z,1713206342.144 [Default:CheckIn] Stopped
2024-04-15T18:39:02.145Z,1713206342.145 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T18:39:02.145Z,1713206342.145 [Default:CheckIn](INFO): Running loop #36
2024-04-15T18:39:02.145Z,1713206342.145 [Default:CheckIn] Running Loop=36
2024-04-15T18:39:02.145Z,1713206342.145 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T18:39:02.145Z,1713206342.145 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T18:39:04.158Z,1713206344.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183903.00,A,3647.86883,N,12150.80126,W,2.197,71.19,150424,,,D*4D
2024-04-15T18:39:04.160Z,1713206344.160 [NAL9602](INFO): GPS fix at 20240415T183903: (36.797814, -121.846688)
2024-04-15T18:39:04.171Z,1713206344.171 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T18:39:04.171Z,1713206344.171 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T18:39:11.894Z,1713206351.894 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0118.lzma
2024-04-15T18:39:12.897Z,1713206352.897 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0118.lzma.bak
2024-04-15T18:39:12.897Z,1713206352.897 [DataOverHttps](INFO): SBD MOMSN=19408714
2024-04-15T18:39:17.481Z,1713206357.481 [NAL9602](INFO): SBD MO Status=0, MOMSN=49408, MT Status=0, MTMSN=0
2024-04-15T18:39:17.481Z,1713206357.481 [NAL9602](INFO): No messages in MT queue
2024-04-15T18:39:29.102Z,1713206369.102 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240415T150038/Express0119.lzma
2024-04-15T18:39:30.143Z,1713206370.143 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Express0119.lzma.bak
2024-04-15T18:39:30.143Z,1713206370.143 [DataOverHttps](INFO): SBD MOMSN=19408717
2024-04-15T18:39:31.243Z,1713206371.243 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T18:39:31.243Z,1713206371.243 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T18:39:31.243Z,1713206371.243 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T18:39:48.186Z,1713206388.186 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T18:40:59.698Z,1713206459.698 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-04-15T18:44:31.811Z,1713206671.811 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T18:44:31.811Z,1713206671.811 [Default:CheckIn:C.Wait] Stopped
2024-04-15T18:44:31.811Z,1713206671.811 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T18:44:31.811Z,1713206671.811 [Default:CheckIn:D] Running Loop=1
2024-04-15T18:44:32.207Z,1713206672.207 [Default:CheckIn:D] Stopped
2024-04-15T18:44:32.208Z,1713206672.208 [Default:CheckIn:E] Running Loop=1
2024-04-15T18:44:32.619Z,1713206672.619 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 219.946729 min
2024-04-15T18:44:32.619Z,1713206672.619 [Default:CheckIn:E] Stopped
2024-04-15T18:44:32.619Z,1713206672.619 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T18:44:32.619Z,1713206672.619 [Default:CheckIn] Stopped
2024-04-15T18:44:32.619Z,1713206672.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T18:44:32.619Z,1713206672.619 [Default:CheckIn](INFO): Running loop #37
2024-04-15T18:44:32.620Z,1713206672.620 [Default:CheckIn] Running Loop=37
2024-04-15T18:44:32.620Z,1713206672.620 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T18:44:32.620Z,1713206672.620 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T18:44:34.624Z,1713206674.624 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184433.00,A,3647.87071,N,12150.71119,W,0.136,113.00,150424,,,D*76
2024-04-15T18:44:34.626Z,1713206674.626 [NAL9602](INFO): GPS fix at 20240415T184433: (36.797845, -121.845186)
2024-04-15T18:44:34.637Z,1713206674.637 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T18:44:34.637Z,1713206674.637 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T18:44:42.210Z,1713206682.210 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240415T150038/Courier0121.lzma
2024-04-15T18:44:43.213Z,1713206683.213 [DataOverHttps](INFO): Moved sent file to Logs/20240415T150038/Courier0121.lzma.bak
2024-04-15T18:44:43.213Z,1713206683.213 [DataOverHttps](IMPORTANT): SBD MOMSN=19408730, MTMSN=20240415T184442
2024-04-15T18:44:46.338Z,1713206686.338 [NAL9602](INFO): SBD MO Status=0, MOMSN=49409, MT Status=0, MTMSN=0
2024-04-15T18:44:46.338Z,1713206686.338 [NAL9602](INFO): No messages in MT queue
2024-04-15T18:44:52.867Z,1713206692.867 [DataOverHttps](INFO): Received command: restart logs