2023-02-13T15:39:05.777Z,1676302745.777 [Supervisor](DEBUG): Initializing supervisor.
2023-02-13T15:39:05.782Z,1676302745.782 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-02-13T15:39:05.782Z,1676302745.782 [SyncHandler](INFO): Protected caller Thread ID is 837
2023-02-13T15:39:05.783Z,1676302745.783 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-02-13T15:39:05.784Z,1676302745.784 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-02-13T15:39:05.784Z,1676302745.784 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2023-02-13T15:39:05.788Z,1676302745.788 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-02-13T15:39:05.805Z,1676302745.805 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-02-13T15:39:05.806Z,1676302745.806 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-02-13T15:39:05.806Z,1676302745.806 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2023-02-13T15:39:05.811Z,1676302745.811 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-02-13T15:39:05.812Z,1676302745.812 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-02-13T15:39:05.812Z,1676302745.812 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2023-02-13T15:39:05.814Z,1676302745.814 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-02-13T15:39:05.815Z,1676302745.815 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-02-13T15:39:05.815Z,1676302745.815 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2023-02-13T15:39:05.819Z,1676302745.819 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-02-13T15:39:05.819Z,1676302745.819 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-02-13T15:39:05.824Z,1676302745.824 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-02-13T15:39:05.920Z,1676302745.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-02-13T15:39:05.922Z,1676302745.922 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-02-13T15:39:06.622Z,1676302746.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-02-13T15:39:06.624Z,1676302746.624 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-02-13T15:39:06.843Z,1676302746.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-02-13T15:39:06.845Z,1676302746.845 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-02-13T15:39:06.925Z,1676302746.925 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-02-13T15:39:07.053Z,1676302747.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-02-13T15:39:07.055Z,1676302747.055 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-02-13T15:39:07.136Z,1676302747.136 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-02-13T15:39:07.242Z,1676302747.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-02-13T15:39:07.244Z,1676302747.244 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-02-13T15:39:07.616Z,1676302747.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-02-13T15:39:07.617Z,1676302747.617 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-02-13T15:39:08.130Z,1676302748.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-02-13T15:39:08.130Z,1676302748.130 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-02-13T15:39:08.346Z,1676302748.346 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-02-13T15:39:08.347Z,1676302748.347 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-02-13T15:39:08.525Z,1676302748.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-02-13T15:39:08.527Z,1676302748.527 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-02-13T15:39:09.022Z,1676302749.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-02-13T15:39:09.024Z,1676302749.024 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-02-13T15:39:09.324Z,1676302749.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-02-13T15:39:09.324Z,1676302749.324 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-02-13T15:39:09.904Z,1676302749.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-02-13T15:39:10.002Z,1676302750.002 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-02-13T15:39:10.359Z,1676302750.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-02-13T15:39:10.359Z,1676302750.359 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-02-13T15:39:10.683Z,1676302750.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-02-13T15:39:10.684Z,1676302750.684 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-02-13T15:39:10.687Z,1676302750.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-02-13T15:39:10.772Z,1676302750.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-02-13T15:39:10.928Z,1676302750.928 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-02-13T15:39:11.038Z,1676302751.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-02-13T15:39:11.124Z,1676302751.124 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-02-13T15:39:11.219Z,1676302751.219 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-02-13T15:39:11.402Z,1676302751.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-02-13T15:39:11.665Z,1676302751.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-02-13T15:39:11.666Z,1676302751.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-02-13T15:39:11.758Z,1676302751.758 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-02-13T15:39:11.856Z,1676302751.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-02-13T15:39:11.991Z,1676302751.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-02-13T15:39:12.089Z,1676302752.089 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-02-13T15:39:12.108Z,1676302752.108 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-02-13T15:39:12.233Z,1676302752.233 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-02-13T15:39:12.234Z,1676302752.234 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-02-13T15:39:12.301Z,1676302752.301 [VerticalControl](DEBUG): Construct VerticalControl.
2023-02-13T15:39:12.361Z,1676302752.361 [VerticalControl] Loaded
2023-02-13T15:39:12.362Z,1676302752.362 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-02-13T15:39:12.364Z,1676302752.364 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-02-13T15:39:12.405Z,1676302752.405 [HorizontalControl] Loaded
2023-02-13T15:39:12.406Z,1676302752.406 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-02-13T15:39:12.408Z,1676302752.408 [SpeedControl](DEBUG): Construct SpeedControl.
2023-02-13T15:39:12.411Z,1676302752.411 [SpeedControl] Loaded
2023-02-13T15:39:12.411Z,1676302752.411 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-02-13T15:39:12.414Z,1676302752.414 [LoopControl](DEBUG): Construct LoopControl.
2023-02-13T15:39:12.414Z,1676302752.414 [LoopControl] Loaded
2023-02-13T15:39:12.415Z,1676302752.415 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-02-13T15:39:12.415Z,1676302752.415 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-02-13T15:39:12.416Z,1676302752.416 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-02-13T15:39:12.485Z,1676302752.485 [DepthRateCalculator] Loaded
2023-02-13T15:39:12.485Z,1676302752.485 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-02-13T15:39:12.490Z,1676302752.490 [PitchRateCalculator] Loaded
2023-02-13T15:39:12.490Z,1676302752.490 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-02-13T15:39:12.500Z,1676302752.500 [SpeedCalculator] Loaded
2023-02-13T15:39:12.500Z,1676302752.500 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-02-13T15:39:12.504Z,1676302752.504 [YawRateCalculator] Loaded
2023-02-13T15:39:12.505Z,1676302752.505 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-02-13T15:39:12.523Z,1676302752.523 [ElevatorOffsetCalculator] Loaded
2023-02-13T15:39:12.523Z,1676302752.523 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-02-13T15:39:12.524Z,1676302752.524 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-02-13T15:39:12.524Z,1676302752.524 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-02-13T15:39:12.566Z,1676302752.566 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-02-13T15:39:12.567Z,1676302752.567 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-02-13T15:39:12.649Z,1676302752.649 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-02-13T15:39:12.650Z,1676302752.650 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-02-13T15:39:13.006Z,1676302753.006 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-02-13T15:39:13.007Z,1676302753.007 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-02-13T15:39:13.258Z,1676302753.258 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-02-13T15:39:13.260Z,1676302753.260 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-02-13T15:39:14.019Z,1676302754.019 [AHRS_M2] Loaded
2023-02-13T15:39:14.019Z,1676302754.019 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-02-13T15:39:14.268Z,1676302754.268 [BackseatComponent] Loaded
2023-02-13T15:39:14.268Z,1676302754.268 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-02-13T15:39:14.269Z,1676302754.269 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409764E0
2023-02-13T15:39:14.270Z,1676302754.270 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 925
2023-02-13T15:39:14.272Z,1676302754.272 [LcmUniversalReporter] Loaded
2023-02-13T15:39:14.273Z,1676302754.273 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-02-13T15:39:15.581Z,1676302755.581 [BPC1] Loaded
2023-02-13T15:39:15.582Z,1676302755.582 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-02-13T15:39:15.724Z,1676302755.724 [DataOverHttps] Loaded
2023-02-13T15:39:15.724Z,1676302755.724 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-02-13T15:39:15.725Z,1676302755.725 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A64E0
2023-02-13T15:39:15.725Z,1676302755.725 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926
2023-02-13T15:39:15.746Z,1676302755.746 [Depth_Keller] Loaded
2023-02-13T15:39:15.746Z,1676302755.746 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-02-13T15:39:15.751Z,1676302755.751 [DropWeight] Loaded
2023-02-13T15:39:15.752Z,1676302755.752 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-02-13T15:39:15.770Z,1676302755.770 [MultiRay] Loaded
2023-02-13T15:39:15.770Z,1676302755.770 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-02-13T15:39:15.832Z,1676302755.832 [NAL9602] Loaded
2023-02-13T15:39:15.832Z,1676302755.832 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-02-13T15:39:15.861Z,1676302755.861 [Onboard] Loaded
2023-02-13T15:39:15.862Z,1676302755.862 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-02-13T15:39:15.863Z,1676302755.863 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D64E0
2023-02-13T15:39:15.863Z,1676302755.863 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927
2023-02-13T15:39:15.876Z,1676302755.876 [Power24vConverter] Loaded
2023-02-13T15:39:15.876Z,1676302755.876 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-02-13T15:39:15.889Z,1676302755.889 [Radio_Surface] Loaded
2023-02-13T15:39:15.889Z,1676302755.889 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-02-13T15:39:15.890Z,1676302755.890 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A064E0
2023-02-13T15:39:15.890Z,1676302755.890 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928
2023-02-13T15:39:15.935Z,1676302755.935 [RDI_Pathfinder] Loaded
2023-02-13T15:39:15.936Z,1676302755.936 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-02-13T15:39:16.027Z,1676302756.027 [DAT] Loaded
2023-02-13T15:39:16.027Z,1676302756.027 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-02-13T15:39:16.028Z,1676302756.028 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A364E0
2023-02-13T15:39:16.028Z,1676302756.028 [DAT ThreadHandler](INFO): Protected caller Thread ID is 929
2023-02-13T15:39:16.029Z,1676302756.029 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-02-13T15:39:16.030Z,1676302756.030 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-02-13T15:39:16.165Z,1676302756.165 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-02-13T15:39:16.165Z,1676302756.165 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-02-13T15:39:16.179Z,1676302756.179 [NavChart] Loaded
2023-02-13T15:39:16.179Z,1676302756.179 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-02-13T15:39:16.185Z,1676302756.185 [UniversalFixResidualReporter] Loaded
2023-02-13T15:39:16.185Z,1676302756.185 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-02-13T15:39:16.186Z,1676302756.186 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-02-13T15:39:16.186Z,1676302756.186 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-02-13T15:39:16.355Z,1676302756.355 [SBIT](DEBUG): Construct Startup Built In Test.
2023-02-13T15:39:16.364Z,1676302756.364 [SBIT] Loaded
2023-02-13T15:39:16.365Z,1676302756.365 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-02-13T15:39:16.368Z,1676302756.368 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-02-13T15:39:16.381Z,1676302756.381 [IBIT] Loaded
2023-02-13T15:39:16.381Z,1676302756.381 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-02-13T15:39:16.387Z,1676302756.387 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-02-13T15:39:16.491Z,1676302756.491 [CBIT] Loaded
2023-02-13T15:39:16.491Z,1676302756.491 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-02-13T15:39:16.491Z,1676302756.491 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-02-13T15:39:16.492Z,1676302756.492 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-02-13T15:39:16.654Z,1676302756.654 [BuoyancyServo] Loaded
2023-02-13T15:39:16.654Z,1676302756.654 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-02-13T15:39:16.675Z,1676302756.675 [ElevatorServo] Loaded
2023-02-13T15:39:16.675Z,1676302756.675 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-02-13T15:39:16.695Z,1676302756.695 [MassServo] Loaded
2023-02-13T15:39:16.695Z,1676302756.695 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-02-13T15:39:16.714Z,1676302756.714 [RudderServo] Loaded
2023-02-13T15:39:16.714Z,1676302756.714 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-02-13T15:39:16.732Z,1676302756.732 [ThrusterServo] Loaded
2023-02-13T15:39:16.732Z,1676302756.732 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-02-13T15:39:16.732Z,1676302756.732 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-02-13T15:39:16.733Z,1676302756.733 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-02-13T15:39:16.748Z,1676302756.748 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-02-13T15:39:16.749Z,1676302756.749 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-02-13T15:39:16.967Z,1676302756.967 [CTD_Seabird] Loaded
2023-02-13T15:39:16.968Z,1676302756.968 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-02-13T15:39:16.969Z,1676302756.969 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B7E4E0
2023-02-13T15:39:16.969Z,1676302756.969 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 930
2023-02-13T15:39:16.989Z,1676302756.989 [PAR_Licor] Loaded
2023-02-13T15:39:16.990Z,1676302756.990 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-02-13T15:39:17.021Z,1676302757.021 [WetLabsBB2FL] Loaded
2023-02-13T15:39:17.021Z,1676302757.021 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-02-13T15:39:17.022Z,1676302757.022 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BAE4E0
2023-02-13T15:39:17.023Z,1676302757.023 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 931
2023-02-13T15:39:17.042Z,1676302757.042 [WetLabsUBAT] Loaded
2023-02-13T15:39:17.042Z,1676302757.042 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-02-13T15:39:17.043Z,1676302757.043 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BDE4E0
2023-02-13T15:39:17.044Z,1676302757.044 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 932
2023-02-13T15:39:17.044Z,1676302757.044 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-02-13T15:39:17.050Z,1676302757.050 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-02-13T15:39:17.053Z,1676302757.053 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-02-13T15:39:17.064Z,1676302757.064 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-02-13T15:39:17.065Z,1676302757.065 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C0E4E0
2023-02-13T15:39:17.066Z,1676302757.066 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 933
2023-02-13T15:39:17.070Z,1676302757.070 [Supervisor](INFO): Main Thread ID is 830
2023-02-13T15:39:17.070Z,1676302757.070 [Supervisor](DEBUG): Running supervisor.
2023-02-13T15:39:17.071Z,1676302757.071 [CommandExec ThreadHandler](INFO): Handler Thread ID is 934
2023-02-13T15:39:17.071Z,1676302757.071 [CommandExec](INFO): Initializing the command executive.
2023-02-13T15:39:17.073Z,1676302757.073 [CommandLine ThreadHandler](INFO): Handler Thread ID is 935
2023-02-13T15:39:17.075Z,1676302757.075 [controlThread ThreadHandler](INFO): Handler Thread ID is 936
2023-02-13T15:39:17.075Z,1676302757.075 [controlThread](DEBUG): Initializing ControlThread
2023-02-13T15:39:17.076Z,1676302757.076 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-02-13T15:39:17.078Z,1676302757.078 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-02-13T15:39:17.078Z,1676302757.078 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-02-13T15:39:17.079Z,1676302757.079 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-02-13T15:39:17.079Z,1676302757.079 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-02-13T15:39:17.080Z,1676302757.080 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-02-13T15:39:17.080Z,1676302757.080 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-02-13T15:39:17.080Z,1676302757.080 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-02-13T15:39:17.081Z,1676302757.081 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-02-13T15:39:17.088Z,1676302757.088 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-02-13T15:39:17.088Z,1676302757.088 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-02-13T15:39:17.088Z,1676302757.088 [SBIT](INFO): Initialize SBIT Component.
2023-02-13T15:39:17.089Z,1676302757.089 [SBIT](IMPORTANT): git: 2023-02-10
2023-02-13T15:39:17.089Z,1676302757.089 [SBIT](INFO): git hash: c406eb0a7e3d06ad2f4805df3abb847da8df30bc
2023-02-13T15:39:17.089Z,1676302757.089 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-02-13T15:39:17.090Z,1676302757.090 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-02-13T15:39:17.092Z,1676302757.092 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2023-02-13T15:39:17.092Z,1676302757.092 [IBIT](INFO): Initialize IBIT Component.
2023-02-13T15:39:17.093Z,1676302757.093 [CBIT](DEBUG): Initialize CBIT Component.
2023-02-13T15:39:17.094Z,1676302757.094 [logger ThreadHandler](INFO): Handler Thread ID is 937
2023-02-13T15:39:17.105Z,1676302757.105 [CBIT](DEBUG): Initialized mux pins.
2023-02-13T15:39:17.105Z,1676302757.105 [CBIT](DEBUG): Initializing the watchdog timer.
2023-02-13T15:39:17.113Z,1676302757.113 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 938
2023-02-13T15:39:17.125Z,1676302757.125 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939
2023-02-13T15:39:17.126Z,1676302757.126 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-02-13T15:39:17.129Z,1676302757.129 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-02-13T15:39:17.130Z,1676302757.130 [CBIT](DEBUG): Initializing heartbeat.
2023-02-13T15:39:17.137Z,1676302757.137 [Onboard ThreadHandler](INFO): Handler Thread ID is 940
2023-02-13T15:39:17.155Z,1676302757.155 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941
2023-02-13T15:39:17.185Z,1676302757.185 [DAT ThreadHandler](INFO): Handler Thread ID is 942
2023-02-13T15:39:17.186Z,1676302757.186 [DAT](INFO): Powering up
2023-02-13T15:39:17.186Z,1676302757.186 [DAT](DEBUG): Initializing DAT.
2023-02-13T15:39:17.193Z,1676302757.193 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 944
2023-02-13T15:39:17.194Z,1676302757.194 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-02-13T15:39:17.198Z,1676302757.198 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 946
2023-02-13T15:39:17.201Z,1676302757.201 [CBIT](DEBUG): Deactivating GF circuits.
2023-02-13T15:39:17.201Z,1676302757.201 [CBIT](DEBUG): Deactivating emergency mode.
2023-02-13T15:39:17.202Z,1676302757.202 [WetLabsBB2FL](INFO): Powering up
2023-02-13T15:39:17.203Z,1676302757.203 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 948
2023-02-13T15:39:17.205Z,1676302757.205 [WetLabsUBAT](INFO): Powering up
2023-02-13T15:39:17.207Z,1676302757.207 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 950
2023-02-13T15:39:17.215Z,1676302757.215 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-02-13T15:39:17.215Z,1676302757.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-02-13T15:39:17.216Z,1676302757.216 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-02-13T15:39:17.216Z,1676302757.216 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-02-13T15:39:17.216Z,1676302757.216 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-02-13T15:39:17.216Z,1676302757.216 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-02-13T15:39:17.216Z,1676302757.216 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-02-13T15:39:17.216Z,1676302757.216 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-02-13T15:39:17.251Z,1676302757.251 [CBIT](DEBUG): Backplane powered.
2023-02-13T15:39:17.262Z,1676302757.262 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-02-13T15:39:17.316Z,1676302757.316 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-13T15:39:17.363Z,1676302757.363 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-02-13T15:39:17.363Z,1676302757.363 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-02-13T15:39:17.435Z,1676302757.435 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-02-13T15:39:17.436Z,1676302757.436 [Default:A.Wait](DEBUG): Construct Wait.
2023-02-13T15:39:17.462Z,1676302757.462 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-13T15:39:17.490Z,1676302757.490 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-02-13T15:39:17.492Z,1676302757.492 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-02-13T15:39:17.520Z,1676302757.520 [Default:E.Execute](DEBUG): Construct Execute.
2023-02-13T15:39:17.536Z,1676302757.536 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-02-13T15:39:17.540Z,1676302757.540 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-02-13T15:39:17.547Z,1676302757.547 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-02-13T15:39:17.561Z,1676302757.561 [Radio_Surface](INFO): Powering up
2023-02-13T15:39:17.727Z,1676302757.727 [MultiRay](INFO): Powering up MultiRay Lights
2023-02-13T15:39:17.763Z,1676302757.763 [Power24vConverter](INFO): Powering up.
2023-02-13T15:39:17.764Z,1676302757.764 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-02-13T15:39:17.810Z,1676302757.810 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-02-13T15:39:17.817Z,1676302757.817 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-02-13T15:39:17.818Z,1676302757.818 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-02-13T15:39:17.826Z,1676302757.826 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-02-13T15:39:17.827Z,1676302757.827 [MassServo](DEBUG): Initializing EZServoServo.
2023-02-13T15:39:17.841Z,1676302757.841 [MassServo](DEBUG): Initializing MassServo.
2023-02-13T15:39:17.842Z,1676302757.842 [RudderServo](DEBUG): Initializing EZServoServo.
2023-02-13T15:39:17.849Z,1676302757.849 [RudderServo](DEBUG): Initializing RudderServo.
2023-02-13T15:39:17.850Z,1676302757.850 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-02-13T15:39:17.857Z,1676302757.857 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-02-13T15:39:18.685Z,1676302758.685 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-02-13T15:39:18.685Z,1676302758.685 [RudderServo](FAULT): Rudder failed to initialize
2023-02-13T15:39:18.685Z,1676302758.685 [RudderServo] Communications Fault, FailCount= 1
2023-02-13T15:39:18.685Z,1676302758.685 [RudderServo](ERROR): Communications Fault
2023-02-13T15:39:18.786Z,1676302758.786 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-02-13T15:39:19.074Z,1676302759.074 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-02-13T15:39:19.074Z,1676302759.074 [RudderServo](INFO): Powering down
2023-02-13T15:39:19.621Z,1676302759.621 [RudderServo](DEBUG): Initializing EZServoServo.
2023-02-13T15:39:19.742Z,1676302759.742 [RudderServo](DEBUG): Initializing RudderServo.
2023-02-13T15:39:19.746Z,1676302759.746 [CBIT](INFO): Clearing failed state for component RudderServo
2023-02-13T15:39:19.746Z,1676302759.746 [RudderServo] No Fault, FailCount= 1
2023-02-13T15:39:19.813Z,1676302759.813 [WetLabsBB2FL](INFO): Powering down
2023-02-13T15:39:24.225Z,1676302764.225 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-02-13T15:39:29.313Z,1676302769.313 [DAT](INFO): DAT read: 

2023-02-13T15:39:29.315Z,1676302769.315 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-02-13T15:39:30.826Z,1676302770.826 [DAT](INFO): DAT read: MF Frequency Band

2023-02-13T15:39:30.827Z,1676302770.827 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-02-13T15:39:30.828Z,1676302770.828 [DAT](INFO): DAT read: Feb 13 2023 15:39:24 

2023-02-13T15:39:31.834Z,1676302771.834 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-02-13T15:39:31.835Z,1676302771.835 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-02-13T15:39:31.836Z,1676302771.836 [DAT](INFO): commRate: 800
2023-02-13T15:39:33.901Z,1676302773.901 [DAT](INFO): entering command mode
2023-02-13T15:39:34.102Z,1676302774.102 [DAT](INFO): DAT read: 

2023-02-13T15:39:34.102Z,1676302774.102 [DAT](INFO): DAT read: user:1>

2023-02-13T15:39:34.102Z,1676302774.102 [DAT](INFO): setting verbose to 3
2023-02-13T15:39:34.354Z,1676302774.354 [DAT](INFO): DAT read: user:1>

2023-02-13T15:39:34.355Z,1676302774.355 [DAT](INFO): DAT read: Verbose         | 3           

2023-02-13T15:39:34.355Z,1676302774.355 [DAT](INFO): set verbose to 3
2023-02-13T15:39:34.355Z,1676302774.355 [DAT](INFO): setting DatVerbose to 27440
2023-02-13T15:39:34.606Z,1676302774.606 [DAT](INFO): DAT read: user:2>

2023-02-13T15:39:34.608Z,1676302774.608 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-02-13T15:39:34.608Z,1676302774.608 [DAT](INFO): set DatVerbose to 27440
2023-02-13T15:39:34.609Z,1676302774.609 [DAT](INFO): setting transmit power to 8
2023-02-13T15:39:34.858Z,1676302774.858 [DAT](INFO): DAT read: user:3>

2023-02-13T15:39:34.859Z,1676302774.859 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-02-13T15:39:34.859Z,1676302774.859 [DAT](INFO): set transmit power to 8
2023-02-13T15:39:34.859Z,1676302774.859 [DAT](INFO): setting local address to 9
2023-02-13T15:39:35.110Z,1676302775.110 [DAT](INFO): DAT read: user:4>

2023-02-13T15:39:35.111Z,1676302775.111 [DAT](INFO): DAT read: LocalAddr       | 9           

2023-02-13T15:39:35.111Z,1676302775.111 [DAT](INFO): set local address to 9
2023-02-13T15:39:35.112Z,1676302775.112 [DAT](INFO): Setting time to: 15:39:35 And date to:2/13/2023
2023-02-13T15:39:35.363Z,1676302775.363 [DAT](INFO): DAT read: user:5>

2023-02-13T15:39:35.366Z,1676302775.366 [DAT](INFO): DAT read: Mon Feb 13, 2023  15:39:35  

2023-02-13T15:39:35.367Z,1676302775.367 [DAT](INFO): Local DAT time set to Mon Feb 13, 2023  15:39:35  

2023-02-13T15:39:45.879Z,1676302785.879 [NAL9602](INFO): Powering up NAL9602
2023-02-13T15:39:45.934Z,1676302785.934 [CommandExec](IMPORTANT): got command failComponent
2023-02-13T15:39:45.934Z,1676302785.934 [CommandExec](IMPORTANT): Failed components:
2023-02-13T15:39:45.934Z,1676302785.934 [CommandExec](IMPORTANT): No failed Components.
2023-02-13T15:39:47.417Z,1676302787.417 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup.
2023-02-13T15:39:47.417Z,1676302787.417 [WetLabsUBAT] Communications Fault, FailCount= 1
2023-02-13T15:39:47.418Z,1676302787.418 [WetLabsUBAT](ERROR): Communications Fault
2023-02-13T15:39:47.579Z,1676302787.579 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT
2023-02-13T15:39:47.941Z,1676302787.941 [WetLabsUBAT](INFO): Powering down
2023-02-13T15:39:48.761Z,1676302788.761 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2023-02-13T15:39:48.761Z,1676302788.761 [WetLabsUBAT] No Fault, FailCount= 1
2023-02-13T15:39:49.157Z,1676302789.157 [WetLabsUBAT](INFO): Powering up
2023-02-13T15:39:56.775Z,1676302796.775 [NAL9602](INFO): NAL9602 initialized
2023-02-13T15:39:57.514Z,1676302797.514 [CommandExec](IMPORTANT): got command failComponent
2023-02-13T15:39:57.514Z,1676302797.514 [CommandExec](IMPORTANT): Failed components:
2023-02-13T15:39:57.514Z,1676302797.514 [CommandExec](IMPORTANT): No failed Components.
2023-02-13T15:39:58.417Z,1676302798.417 [WetLabsUBAT](INFO): Powering down
2023-02-13T15:40:06.504Z,1676302806.504 [SBIT](IMPORTANT): Beginning Startup BIT
2023-02-13T15:40:06.509Z,1676302806.509 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T15:40:17.510Z,1676302817.510 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.013997
CHAN A1 (24V): 0.000501
CHAN A2 (12V): -0.006900
CHAN A3 (5V): -0.002689
CHAN B0 (3.3V): 0.000137
CHAN B1 (3.15aV): -0.001167
CHAN B2 (3.15bV): 0.000072
CHAN B3 (GND): 0.002633
OPEN: -0.003816
Full Scale: +/- 1 mA
2023-02-13T15:41:00.313Z,1676302860.313 [SBIT](IMPORTANT): SBIT PASSED
2023-02-13T15:41:00.313Z,1676302860.313 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-02-13T15:41:00.314Z,1676302860.314 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-02-13T15:41:00.314Z,1676302860.314 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-02-13T15:41:00.314Z,1676302860.314 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2023-02-13T15:41:00.314Z,1676302860.314 [SBIT](IMPORTANT): DAT.baud=115200 bit_per_second;

2023-02-13T15:41:00.315Z,1676302860.315 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2023-02-13T15:41:00.315Z,1676302860.315 [SBIT](IMPORTANT): DAT.surfaceThreshold=0 meter;

2023-02-13T15:41:00.315Z,1676302860.315 [SBIT](IMPORTANT): DAT.verbosity=2 count;

2023-02-13T15:41:00.315Z,1676302860.315 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-02-13T15:41:00.315Z,1676302860.315 [SBIT](IMPORTANT): Express none _.adinos;

2023-02-13T15:41:00.315Z,1676302860.315 [SBIT](IMPORTANT): Express none _.aother;

2023-02-13T15:41:00.315Z,1676302860.315 [SBIT](IMPORTANT): Express linearApproximation _.dinoflagellates 10000000000.000000 none;

2023-02-13T15:41:00.315Z,1676302860.315 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=24 ampere_hour;

2023-02-13T15:41:00.316Z,1676302860.316 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2023-02-13T15:41:00.316Z,1676302860.316 [SBIT](IMPORTANT): Power24vConverter.simulateHardware=0 bool;

2023-02-13T15:41:00.316Z,1676302860.316 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;

2023-02-13T15:41:00.316Z,1676302860.316 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=172.109746 cubic_centimeter;

2023-02-13T15:41:00.316Z,1676302860.316 [SBIT](IMPORTANT): VerticalControl.massDefault=7.328833 millimeter;

2023-02-13T15:41:00.667Z,1676302860.667 [MissionManager](IMPORTANT): Started mission Startup
2023-02-13T15:41:00.667Z,1676302860.667 [Startup] Running Loop=1
2023-02-13T15:41:00.667Z,1676302860.667 [Startup](DEBUG): Aggregate::initialize Startup
2023-02-13T15:41:00.667Z,1676302860.667 [Startup:A.GoToSurface] Running Loop=1
2023-02-13T15:41:00.667Z,1676302860.667 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-13T15:41:00.668Z,1676302860.668 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-13T15:41:00.668Z,1676302860.668 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-13T15:41:00.669Z,1676302860.669 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-13T15:41:00.673Z,1676302860.673 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-13T15:41:00.674Z,1676302860.674 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-13T15:41:00.675Z,1676302860.675 [Startup:StartupSatComms] Running Loop=1
2023-02-13T15:41:00.675Z,1676302860.675 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-02-13T15:41:00.675Z,1676302860.675 [Startup:StartupSatComms:A] Running Loop=1
2023-02-13T15:41:01.061Z,1676302861.061 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-02-13T15:42:00.904Z,1676302920.904 [Startup:StartupSatComms:A](INFO): Timed out from 2023-02-13T15:41:00.7Z
2023-02-13T15:42:00.904Z,1676302920.904 [Startup:StartupSatComms:A] Stopped
2023-02-13T15:42:00.904Z,1676302920.904 [Startup:StartupSatComms:B] Running Loop=1
2023-02-13T15:42:01.160Z,1676302921.160 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004885
2023-02-13T15:42:01.244Z,1676302921.244 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-02-13T15:42:11.453Z,1676302931.453 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230213T153329/Courier0004.lzma
2023-02-13T15:42:12.455Z,1676302932.455 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153329/Courier0004.lzma.bak
2023-02-13T15:42:12.455Z,1676302932.455 [DataOverHttps](INFO): SBD MOMSN=17464401
2023-02-13T15:42:17.247Z,1676302937.247 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-02-13T15:42:17.247Z,1676302937.247 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T15:42:17.274Z,1676302937.274 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T15:42:17.685Z,1676302937.685 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T15:42:17.685Z,1676302937.685 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-02-13T15:42:33.973Z,1676302953.973 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230213T153905/Courier0000.lzma
2023-02-13T15:42:34.975Z,1676302954.975 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0000.lzma.bak
2023-02-13T15:42:34.975Z,1676302954.975 [DataOverHttps](INFO): SBD MOMSN=17464403
2023-02-13T15:42:50.950Z,1676302970.950 [DataOverHttps](INFO): Sending 744 bytes from file Logs/20230213T150252/Express0011.lzma
2023-02-13T15:42:51.951Z,1676302971.951 [DataOverHttps](INFO): Moved sent file to Logs/20230213T150252/Express0011.lzma.bak
2023-02-13T15:42:51.951Z,1676302971.951 [DataOverHttps](INFO): SBD MOMSN=17464406
2023-02-13T15:43:01.094Z,1676302981.094 [Startup:StartupSatComms:B](INFO): Timed out from 2023-02-13T15:42:00.9Z
2023-02-13T15:43:01.094Z,1676302981.094 [Startup:StartupSatComms:B] Stopped
2023-02-13T15:43:01.094Z,1676302981.094 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-02-13T15:43:01.094Z,1676302981.094 [Startup:StartupSatComms] Stopped
2023-02-13T15:43:01.094Z,1676302981.094 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-02-13T15:43:01.095Z,1676302981.095 [Startup](INFO): Completed Startup
2023-02-13T15:43:01.095Z,1676302981.095 [MissionManager](INFO): Startup is completed.
2023-02-13T15:43:01.095Z,1676302981.095 [MissionManager](INFO): Uninitializing Mission Startup
2023-02-13T15:43:01.095Z,1676302981.095 [Startup] Stopped
2023-02-13T15:43:01.095Z,1676302981.095 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-02-13T15:43:01.095Z,1676302981.095 [Startup:A.GoToSurface] Stopped
2023-02-13T15:43:01.095Z,1676302981.095 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-02-13T15:43:01.511Z,1676302981.511 [MissionManager](IMPORTANT): Started mission Default
2023-02-13T15:43:01.511Z,1676302981.511 [Default] Running Loop=1
2023-02-13T15:43:01.511Z,1676302981.511 [Default](DEBUG): Aggregate::initialize Default
2023-02-13T15:43:01.511Z,1676302981.511 [Default:B.GoToSurface] Running Loop=1
2023-02-13T15:43:01.511Z,1676302981.511 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-13T15:43:01.511Z,1676302981.511 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-13T15:43:01.512Z,1676302981.512 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-13T15:43:01.512Z,1676302981.512 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-13T15:43:01.512Z,1676302981.512 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-13T15:43:01.513Z,1676302981.513 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-13T15:43:01.513Z,1676302981.513 [Default:A.Wait] Running Loop=1
2023-02-13T15:43:01.513Z,1676302981.513 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-02-13T15:43:07.996Z,1676302987.996 [DataOverHttps](INFO): Sending 1246 bytes from file Logs/20230213T153329/Express0001.lzma
2023-02-13T15:43:08.995Z,1676302988.995 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153329/Express0001.lzma.bak
2023-02-13T15:43:08.995Z,1676302988.995 [DataOverHttps](INFO): SBD MOMSN=17464425
2023-02-13T15:43:14.826Z,1676302994.826 [Default:A.Wait](INFO): Done Waiting.
2023-02-13T15:43:14.826Z,1676302994.826 [Default:A.Wait] Stopped
2023-02-13T15:43:14.826Z,1676302994.826 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T15:43:15.223Z,1676302995.223 [Default:CheckIn] Running Loop=1
2023-02-13T15:43:15.223Z,1676302995.223 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T15:43:15.223Z,1676302995.223 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T15:43:15.707Z,1676302995.707 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-02-13T15:45:00.259Z,1676303100.259 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-13T15:45:18.077Z,1676303118.077 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-02-13T15:45:18.077Z,1676303118.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T15:45:18.088Z,1676303118.088 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T15:45:18.453Z,1676303118.453 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T15:45:18.453Z,1676303118.453 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-02-13T15:47:04.688Z,1676303224.688 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-02-13T15:47:04.693Z,1676303224.693 [BPC1](INFO): Received data from all battery sticks.
2023-02-13T15:48:15.452Z,1676303295.452 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-13T15:43:15.2Z
2023-02-13T15:48:15.452Z,1676303295.452 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T15:48:15.452Z,1676303295.453 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T15:48:15.845Z,1676303295.845 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-02-13T15:48:19.076Z,1676303299.076 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-02-13T15:48:19.076Z,1676303299.076 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T15:48:19.116Z,1676303299.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T15:48:19.562Z,1676303299.562 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T15:48:19.563Z,1676303299.563 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-02-13T15:48:22.952Z,1676303302.952 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230213T153905/Courier0004.lzma
2023-02-13T15:48:23.955Z,1676303303.955 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0004.lzma.bak
2023-02-13T15:48:23.955Z,1676303303.955 [DataOverHttps](INFO): SBD MOMSN=17464473
2023-02-13T15:48:39.992Z,1676303319.992 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230213T153329/Express0005.lzma
2023-02-13T15:48:40.995Z,1676303320.995 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153329/Express0005.lzma.bak
2023-02-13T15:48:40.995Z,1676303320.995 [DataOverHttps](INFO): SBD MOMSN=17464475
2023-02-13T15:48:56.858Z,1676303336.858 [DataOverHttps](INFO): Sending 1169 bytes from file Logs/20230213T153905/Express0001.lzma
2023-02-13T15:48:57.859Z,1676303337.859 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0001.lzma.bak
2023-02-13T15:48:57.859Z,1676303337.859 [DataOverHttps](INFO): SBD MOMSN=17464477
2023-02-13T15:49:45.501Z,1676303385.501 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230213T153905/Express0005.lzma
2023-02-13T15:49:46.503Z,1676303386.503 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0005.lzma.bak
2023-02-13T15:49:46.503Z,1676303386.503 [DataOverHttps](INFO): SBD MOMSN=17464514
2023-02-13T15:49:47.551Z,1676303387.551 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T15:49:47.551Z,1676303387.551 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T15:49:47.551Z,1676303387.551 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T15:49:59.215Z,1676303399.215 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-13T15:49:59.215Z,1676303399.215 [NAL9602] Data Fault, FailCount= 1
2023-02-13T15:49:59.215Z,1676303399.215 [NAL9602](ERROR): Data Fault
2023-02-13T15:49:59.256Z,1676303399.256 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-13T15:49:59.619Z,1676303399.619 [NAL9602](INFO): Powering down
2023-02-13T15:50:00.448Z,1676303400.448 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-13T15:50:00.448Z,1676303400.448 [NAL9602] No Fault, FailCount= 1
2023-02-13T15:50:29.923Z,1676303429.923 [NAL9602](INFO): Powering up NAL9602
2023-02-13T15:50:40.828Z,1676303440.828 [NAL9602](INFO): NAL9602 initialized
2023-02-13T15:51:20.050Z,1676303480.050 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-02-13T15:51:20.050Z,1676303480.050 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T15:51:20.061Z,1676303480.061 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T15:51:20.453Z,1676303480.453 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T15:51:20.454Z,1676303480.454 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-02-13T15:54:21.055Z,1676303661.055 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-02-13T15:54:21.055Z,1676303661.055 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T15:54:21.073Z,1676303661.073 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T15:54:21.536Z,1676303661.536 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T15:54:21.536Z,1676303661.536 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-02-13T15:54:48.138Z,1676303688.138 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T15:54:48.139Z,1676303688.139 [Default:CheckIn:C.Wait] Stopped
2023-02-13T15:54:48.139Z,1676303688.139 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T15:54:48.139Z,1676303688.139 [Default:CheckIn:D] Running Loop=1
2023-02-13T15:54:48.515Z,1676303688.515 [Default:CheckIn:D] Stopped
2023-02-13T15:54:48.516Z,1676303688.516 [Default:CheckIn:E] Running Loop=1
2023-02-13T15:54:48.922Z,1676303688.922 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.783410 min 
2023-02-13T15:54:48.922Z,1676303688.922 [Default:CheckIn:E] Stopped
2023-02-13T15:54:48.922Z,1676303688.922 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T15:54:48.922Z,1676303688.922 [Default:CheckIn] Stopped
2023-02-13T15:54:48.922Z,1676303688.922 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T15:54:48.922Z,1676303688.922 [Default:CheckIn](INFO): Running loop #2
2023-02-13T15:54:48.922Z,1676303688.922 [Default:CheckIn] Running Loop=2
2023-02-13T15:54:48.922Z,1676303688.922 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T15:54:48.922Z,1676303688.922 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T15:57:22.102Z,1676303842.102 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-02-13T15:57:22.102Z,1676303842.102 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-13T15:57:22.113Z,1676303842.113 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-13T15:57:22.495Z,1676303842.495 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-13T15:57:22.496Z,1676303842.496 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-02-13T15:57:30.965Z,1676303850.965 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155729.00,A,3648.16793,N,12147.26282,W,0.311,0.00,130223,,,A*7D
2023-02-13T15:57:30.968Z,1676303850.968 [NAL9602](INFO): GPS fix at 20230213T155729: (36.802799, -121.787714)
2023-02-13T15:57:30.982Z,1676303850.982 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T15:57:30.982Z,1676303850.982 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T15:57:38.946Z,1676303858.946 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230213T153905/Courier0007.lzma
2023-02-13T15:57:39.947Z,1676303859.947 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0007.lzma.bak
2023-02-13T15:57:39.947Z,1676303859.947 [DataOverHttps](INFO): SBD MOMSN=17464525
2023-02-13T15:57:57.117Z,1676303877.117 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20230213T153905/Express0008.lzma
2023-02-13T15:57:58.119Z,1676303878.119 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0008.lzma.bak
2023-02-13T15:57:58.119Z,1676303878.119 [DataOverHttps](INFO): SBD MOMSN=17464528
2023-02-13T15:57:59.252Z,1676303879.252 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T15:57:59.252Z,1676303879.252 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T15:57:59.252Z,1676303879.252 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T15:58:03.278Z,1676303883.278 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:02:59.881Z,1676304179.881 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:02:59.881Z,1676304179.881 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:02:59.881Z,1676304179.881 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:02:59.881Z,1676304179.881 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:03:00.285Z,1676304180.285 [Default:CheckIn:D] Stopped
2023-02-13T16:03:00.285Z,1676304180.285 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:03:00.690Z,1676304180.690 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.979561 min 
2023-02-13T16:03:00.690Z,1676304180.690 [Default:CheckIn:E] Stopped
2023-02-13T16:03:00.690Z,1676304180.690 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:03:00.691Z,1676304180.691 [Default:CheckIn] Stopped
2023-02-13T16:03:00.691Z,1676304180.691 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:03:00.691Z,1676304180.691 [Default:CheckIn](INFO): Running loop #3
2023-02-13T16:03:00.691Z,1676304180.691 [Default:CheckIn] Running Loop=3
2023-02-13T16:03:00.691Z,1676304180.691 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:03:00.691Z,1676304180.691 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:03:02.697Z,1676304182.697 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160300.00,A,3648.14116,N,12147.23109,W,0.039,52.94,130223,,,D*4E
2023-02-13T16:03:02.699Z,1676304182.699 [NAL9602](INFO): GPS fix at 20230213T160300: (36.802353, -121.787185)
2023-02-13T16:03:02.730Z,1676304182.730 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:03:02.730Z,1676304182.730 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:03:10.288Z,1676304190.288 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T153905/Courier0010.lzma
2023-02-13T16:03:11.291Z,1676304191.291 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0010.lzma.bak
2023-02-13T16:03:11.291Z,1676304191.291 [DataOverHttps](INFO): SBD MOMSN=17464532
2023-02-13T16:03:27.688Z,1676304207.688 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230213T153905/Express0011.lzma
2023-02-13T16:03:28.691Z,1676304208.691 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0011.lzma.bak
2023-02-13T16:03:28.691Z,1676304208.691 [DataOverHttps](INFO): SBD MOMSN=17464535
2023-02-13T16:03:29.801Z,1676304209.801 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:03:29.801Z,1676304209.801 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:03:29.801Z,1676304209.801 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:03:32.193Z,1676304212.193 [NAL9602](INFO): SBD MO Status=2, MOMSN=22012, MT Status=2, MTMSN=0
2023-02-13T16:03:32.193Z,1676304212.193 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T16:03:42.692Z,1676304222.692 [NAL9602](INFO): SBD MO Status=0, MOMSN=22012, MT Status=0, MTMSN=0
2023-02-13T16:03:42.692Z,1676304222.692 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:04:13.470Z,1676304253.470 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:04:48.898Z,1676304288.898 [DataOverHttps](IMPORTANT): SBD MTMSN=20230213T160447
2023-02-13T16:04:56.345Z,1676304296.345 [DataOverHttps](INFO): Received command: sched asap "ibit" 33bf 1 2
2023-02-13T16:04:56.351Z,1676304296.351 [CommandExec](IMPORTANT): got command schedule asap "ibit" 33bf 1 2.000000
2023-02-13T16:04:56.352Z,1676304296.352 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=33bf
2023-02-13T16:04:56.353Z,1676304296.353 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='33bf'): "ibit", ASAP
2023-02-13T16:04:57.347Z,1676304297.347 [DataOverHttps](IMPORTANT): SBD MTMSN=20230213T160456
2023-02-13T16:05:05.117Z,1676304305.117 [DataOverHttps](INFO): Received command: sched asap "" 33bf 2 2
2023-02-13T16:05:05.199Z,1676304305.199 [CommandExec](IMPORTANT): got command schedule asap "" 33bf 2 2.000000
2023-02-13T16:05:05.200Z,1676304305.200 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=33bf
2023-02-13T16:05:05.201Z,1676304305.201 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='33bf'): "", ASAP
2023-02-13T16:05:05.302Z,1676304305.302 [CommandExec](IMPORTANT): got command ibit
2023-02-13T16:05:05.580Z,1676304305.580 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-02-13T16:05:05.580Z,1676304305.580 [IBIT](IMPORTANT): Beginning control surface checks.
2023-02-13T16:05:05.588Z,1676304305.588 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T16:05:07.129Z,1676304307.129 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160505.00,A,3648.14233,N,12147.23226,W,0.039,52.94,130223,,,D*47
2023-02-13T16:05:07.131Z,1676304307.131 [NAL9602](INFO): GPS fix at 20230213T160505: (36.802372, -121.787204)
2023-02-13T16:05:13.615Z,1676304313.615 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2023-02-13T16:05:16.443Z,1676304316.443 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010855
CHAN A1 (24V): -0.000106
CHAN A2 (12V): -0.007389
CHAN A3 (5V): -0.002083
CHAN B0 (3.3V): 0.000756
CHAN B1 (3.15aV): -0.000744
CHAN B2 (3.15bV): -0.000116
CHAN B3 (GND): 0.002592
OPEN: 0.005130
Full Scale: +/- 1 mA
2023-02-13T16:05:43.084Z,1676304343.084 [NAL9602](INFO): SBD MO Status=2, MOMSN=22013, MT Status=2, MTMSN=0
2023-02-13T16:05:43.084Z,1676304343.084 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T16:05:51.972Z,1676304351.972 [NAL9602](INFO): SBD MO Status=0, MOMSN=22013, MT Status=0, MTMSN=0
2023-02-13T16:05:51.972Z,1676304351.972 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:05:51.989Z,1676304351.989 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802372 Longitude: -121.787201
2023-02-13T16:05:52.388Z,1676304352.388 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-02-13T16:05:52.388Z,1676304352.388 [IBIT](IMPORTANT): batteryCapacityThreshold: 24.000000 Ah
2023-02-13T16:05:52.389Z,1676304352.389 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.000000 V
2023-02-13T16:05:52.800Z,1676304352.800 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-02-13T16:05:52.801Z,1676304352.801 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-02-13T16:05:52.801Z,1676304352.801 [IBIT](IMPORTANT): Pressure:7.770375 PSI
2023-02-13T16:05:52.802Z,1676304352.802 [IBIT](IMPORTANT): Humidity:22.939812 %
2023-02-13T16:05:53.214Z,1676304353.214 [IBIT](IMPORTANT): Vehicle Pitch:0.227849 degrees
2023-02-13T16:05:53.214Z,1676304353.214 [IBIT](IMPORTANT): Vehicle  Roll:-3.677143 degrees
2023-02-13T16:05:53.215Z,1676304353.215 [IBIT](IMPORTANT): Vehicle  Heading:21.858665 degrees
2023-02-13T16:05:53.644Z,1676304353.644 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-02-13T16:05:53.644Z,1676304353.644 [IBIT](IMPORTANT): buoyancyNeutral: 172.109741 cc
2023-02-13T16:05:53.649Z,1676304353.649 [IBIT](IMPORTANT): massDefault: 0.732883 cm
2023-02-13T16:05:53.649Z,1676304353.649 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-02-13T16:05:53.650Z,1676304353.650 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2023-02-13T16:05:53.650Z,1676304353.650 [IBIT](IMPORTANT): IBIT PASSED
2023-02-13T16:06:22.670Z,1676304382.670 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:08:30.357Z,1676304510.357 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:08:30.357Z,1676304510.357 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:08:30.357Z,1676304510.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:08:30.357Z,1676304510.357 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:08:30.764Z,1676304510.764 [Default:CheckIn:D] Stopped
2023-02-13T16:08:30.764Z,1676304510.764 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:08:31.178Z,1676304511.178 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.487555 min 
2023-02-13T16:08:31.178Z,1676304511.178 [Default:CheckIn:E] Stopped
2023-02-13T16:08:31.178Z,1676304511.178 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:08:31.178Z,1676304511.178 [Default:CheckIn] Stopped
2023-02-13T16:08:31.178Z,1676304511.178 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:08:31.178Z,1676304511.178 [Default:CheckIn](INFO): Running loop #4
2023-02-13T16:08:31.179Z,1676304511.179 [Default:CheckIn] Running Loop=4
2023-02-13T16:08:31.179Z,1676304511.179 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:08:31.179Z,1676304511.179 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:08:33.171Z,1676304513.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160831.00,A,3648.14324,N,12147.23078,W,0.039,52.94,130223,,,D*43
2023-02-13T16:08:33.173Z,1676304513.173 [NAL9602](INFO): GPS fix at 20230213T160831: (36.802387, -121.787180)
2023-02-13T16:08:33.184Z,1676304513.184 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:08:33.184Z,1676304513.184 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:08:40.012Z,1676304520.012 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0013.lzma
2023-02-13T16:08:41.015Z,1676304521.015 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0013.lzma.bak
2023-02-13T16:08:41.015Z,1676304521.015 [DataOverHttps](INFO): SBD MOMSN=17464550
2023-02-13T16:08:50.139Z,1676304530.139 [NAL9602](INFO): SBD MO Status=2, MOMSN=22014, MT Status=2, MTMSN=0
2023-02-13T16:08:50.139Z,1676304530.139 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T16:08:57.386Z,1676304537.386 [DataOverHttps](INFO): Sending 920 bytes from file Logs/20230213T153905/Express0014.lzma
2023-02-13T16:08:57.438Z,1676304537.438 [NAL9602](INFO): SBD MO Status=0, MOMSN=22014, MT Status=0, MTMSN=0
2023-02-13T16:08:57.438Z,1676304537.438 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:08:58.387Z,1676304538.387 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0014.lzma.bak
2023-02-13T16:08:58.387Z,1676304538.387 [DataOverHttps](INFO): SBD MOMSN=17464554
2023-02-13T16:09:14.744Z,1676304554.744 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230213T153905/Express0017.lzma
2023-02-13T16:09:15.747Z,1676304555.747 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0017.lzma.bak
2023-02-13T16:09:15.747Z,1676304555.747 [DataOverHttps](INFO): SBD MOMSN=17464587
2023-02-13T16:09:16.817Z,1676304556.817 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:09:16.837Z,1676304556.837 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:09:16.837Z,1676304556.837 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:09:28.122Z,1676304568.122 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:14:17.609Z,1676304857.609 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:14:17.609Z,1676304857.609 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:14:17.609Z,1676304857.609 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:14:17.610Z,1676304857.610 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:14:17.866Z,1676304857.866 [Default:CheckIn:D] Stopped
2023-02-13T16:14:17.866Z,1676304857.866 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:14:18.268Z,1676304858.268 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.272585 min 
2023-02-13T16:14:18.268Z,1676304858.268 [Default:CheckIn:E] Stopped
2023-02-13T16:14:18.269Z,1676304858.269 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:14:18.281Z,1676304858.281 [Default:CheckIn] Stopped
2023-02-13T16:14:18.281Z,1676304858.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:14:18.281Z,1676304858.281 [Default:CheckIn](INFO): Running loop #5
2023-02-13T16:14:18.281Z,1676304858.281 [Default:CheckIn] Running Loop=5
2023-02-13T16:14:18.281Z,1676304858.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:14:18.282Z,1676304858.282 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:14:20.280Z,1676304860.280 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161419.00,A,3648.14398,N,12147.23348,W,0.078,52.94,130223,,,D*46
2023-02-13T16:14:20.283Z,1676304860.283 [NAL9602](INFO): GPS fix at 20230213T161419: (36.802400, -121.787225)
2023-02-13T16:14:20.302Z,1676304860.302 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:14:20.302Z,1676304860.302 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:14:30.896Z,1676304870.896 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230213T153905/Courier0019.lzma
2023-02-13T16:14:31.899Z,1676304871.899 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0019.lzma.bak
2023-02-13T16:14:31.899Z,1676304871.899 [DataOverHttps](INFO): SBD MOMSN=17464590
2023-02-13T16:14:48.301Z,1676304888.301 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230213T153905/Express0020.lzma
2023-02-13T16:14:49.303Z,1676304889.303 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0020.lzma.bak
2023-02-13T16:14:49.303Z,1676304889.303 [DataOverHttps](INFO): SBD MOMSN=17464593
2023-02-13T16:14:50.601Z,1676304890.601 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:14:50.601Z,1676304890.601 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:14:50.601Z,1676304890.601 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:14:59.487Z,1676304899.487 [NAL9602](INFO): SBD MO Status=0, MOMSN=22015, MT Status=0, MTMSN=0
2023-02-13T16:14:59.487Z,1676304899.487 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:15:30.183Z,1676304930.183 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:19:51.192Z,1676305191.192 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:19:51.192Z,1676305191.192 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:19:51.192Z,1676305191.192 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:19:51.192Z,1676305191.192 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:19:51.620Z,1676305191.620 [Default:CheckIn:D] Stopped
2023-02-13T16:19:51.620Z,1676305191.620 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:19:51.998Z,1676305191.998 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.835148 min 
2023-02-13T16:19:51.998Z,1676305191.998 [Default:CheckIn:E] Stopped
2023-02-13T16:19:51.998Z,1676305191.998 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:19:51.998Z,1676305191.998 [Default:CheckIn] Stopped
2023-02-13T16:19:51.999Z,1676305191.999 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:19:51.999Z,1676305191.999 [Default:CheckIn](INFO): Running loop #6
2023-02-13T16:19:51.999Z,1676305191.999 [Default:CheckIn] Running Loop=6
2023-02-13T16:19:51.999Z,1676305191.999 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:19:51.999Z,1676305191.999 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:19:54.008Z,1676305194.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161953.00,A,3648.14232,N,12147.23033,W,0.097,52.94,130223,,,D*4A
2023-02-13T16:19:54.011Z,1676305194.011 [NAL9602](INFO): GPS fix at 20230213T161953: (36.802372, -121.787172)
2023-02-13T16:19:54.034Z,1676305194.034 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:19:54.034Z,1676305194.034 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:20:01.248Z,1676305201.248 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T153905/Courier0022.lzma
2023-02-13T16:20:02.251Z,1676305202.251 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0022.lzma.bak
2023-02-13T16:20:02.251Z,1676305202.251 [DataOverHttps](INFO): SBD MOMSN=17464606
2023-02-13T16:20:08.144Z,1676305208.144 [NAL9602](INFO): SBD MO Status=0, MOMSN=22016, MT Status=0, MTMSN=0
2023-02-13T16:20:08.144Z,1676305208.144 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:20:19.170Z,1676305219.170 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230213T153905/Express0023.lzma
2023-02-13T16:20:20.171Z,1676305220.171 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0023.lzma.bak
2023-02-13T16:20:20.171Z,1676305220.171 [DataOverHttps](INFO): SBD MOMSN=17464609
2023-02-13T16:20:21.563Z,1676305221.563 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:20:21.563Z,1676305221.563 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:20:21.563Z,1676305221.563 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:20:38.907Z,1676305238.907 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:25:22.138Z,1676305522.138 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:25:22.138Z,1676305522.138 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:25:22.138Z,1676305522.138 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:25:22.138Z,1676305522.138 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:25:22.530Z,1676305522.530 [Default:CheckIn:D] Stopped
2023-02-13T16:25:22.530Z,1676305522.530 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:25:22.933Z,1676305522.933 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.350317 min 
2023-02-13T16:25:22.933Z,1676305522.933 [Default:CheckIn:E] Stopped
2023-02-13T16:25:22.933Z,1676305522.933 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:25:22.934Z,1676305522.934 [Default:CheckIn] Stopped
2023-02-13T16:25:22.934Z,1676305522.934 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:25:22.934Z,1676305522.934 [Default:CheckIn](INFO): Running loop #7
2023-02-13T16:25:22.934Z,1676305522.934 [Default:CheckIn] Running Loop=7
2023-02-13T16:25:22.934Z,1676305522.934 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:25:22.934Z,1676305522.934 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:25:24.952Z,1676305524.952 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162524.00,A,3648.14107,N,12147.23054,W,0.019,52.94,130223,,,D*47
2023-02-13T16:25:24.955Z,1676305524.955 [NAL9602](INFO): GPS fix at 20230213T162524: (36.802351, -121.787176)
2023-02-13T16:25:24.966Z,1676305524.966 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:25:24.967Z,1676305524.967 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:25:33.056Z,1676305533.056 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T153905/Courier0025.lzma
2023-02-13T16:25:34.059Z,1676305534.059 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0025.lzma.bak
2023-02-13T16:25:34.059Z,1676305534.059 [DataOverHttps](INFO): SBD MOMSN=17464613
2023-02-13T16:25:43.128Z,1676305543.128 [NAL9602](INFO): SBD MO Status=0, MOMSN=22017, MT Status=0, MTMSN=0
2023-02-13T16:25:43.128Z,1676305543.128 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:25:50.312Z,1676305550.312 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230213T153905/Express0026.lzma
2023-02-13T16:25:51.315Z,1676305551.315 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0026.lzma.bak
2023-02-13T16:25:51.315Z,1676305551.315 [DataOverHttps](INFO): SBD MOMSN=17464616
2023-02-13T16:25:52.436Z,1676305552.436 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:25:52.436Z,1676305552.436 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:25:52.436Z,1676305552.436 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:26:13.830Z,1676305573.830 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:30:53.099Z,1676305853.099 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:30:53.099Z,1676305853.099 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:30:53.099Z,1676305853.099 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:30:53.099Z,1676305853.099 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:30:53.526Z,1676305853.526 [Default:CheckIn:D] Stopped
2023-02-13T16:30:53.527Z,1676305853.527 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:30:53.895Z,1676305853.895 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.866927 min 
2023-02-13T16:30:53.895Z,1676305853.895 [Default:CheckIn:E] Stopped
2023-02-13T16:30:53.895Z,1676305853.895 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:30:53.895Z,1676305853.895 [Default:CheckIn] Stopped
2023-02-13T16:30:53.895Z,1676305853.895 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:30:53.896Z,1676305853.896 [Default:CheckIn](INFO): Running loop #8
2023-02-13T16:30:53.896Z,1676305853.896 [Default:CheckIn] Running Loop=8
2023-02-13T16:30:53.896Z,1676305853.896 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:30:53.896Z,1676305853.896 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:30:55.904Z,1676305855.904 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163055.00,A,3648.14128,N,12147.22905,W,0.078,52.94,130223,,,D*43
2023-02-13T16:30:55.907Z,1676305855.907 [NAL9602](INFO): GPS fix at 20230213T163055: (36.802355, -121.787151)
2023-02-13T16:30:55.918Z,1676305855.918 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:30:55.918Z,1676305855.918 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:31:04.284Z,1676305864.284 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0028.lzma
2023-02-13T16:31:05.287Z,1676305865.287 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0028.lzma.bak
2023-02-13T16:31:05.287Z,1676305865.287 [DataOverHttps](INFO): SBD MOMSN=17464629
2023-02-13T16:31:16.928Z,1676305876.928 [NAL9602](INFO): SBD MO Status=0, MOMSN=22018, MT Status=0, MTMSN=0
2023-02-13T16:31:16.928Z,1676305876.928 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:31:21.704Z,1676305881.704 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230213T153905/Express0029.lzma
2023-02-13T16:31:22.707Z,1676305882.707 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0029.lzma.bak
2023-02-13T16:31:22.707Z,1676305882.707 [DataOverHttps](INFO): SBD MOMSN=17464632
2023-02-13T16:31:23.837Z,1676305883.837 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:31:23.837Z,1676305883.837 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:31:23.838Z,1676305883.838 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:31:27.507Z,1676305887.507 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2023-02-13T16:31:47.635Z,1676305907.635 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:33:18.822Z,1676305998.822 [DataOverHttps](IMPORTANT): SBD MTMSN=20230213T163317
2023-02-13T16:33:26.621Z,1676306006.621 [DataOverHttps](INFO): Received command: failc
2023-02-13T16:33:26.694Z,1676306006.694 [CommandExec](IMPORTANT): got command failComponent
2023-02-13T16:33:26.694Z,1676306006.694 [CommandExec](IMPORTANT): Failed components:
2023-02-13T16:33:26.694Z,1676306006.694 [CommandExec](IMPORTANT): No failed Components.
2023-02-13T16:36:24.474Z,1676306184.474 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:36:24.474Z,1676306184.474 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:36:24.474Z,1676306184.474 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:36:24.474Z,1676306184.474 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:36:24.865Z,1676306184.865 [Default:CheckIn:D] Stopped
2023-02-13T16:36:24.865Z,1676306184.865 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:36:25.296Z,1676306185.296 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.389237 min 
2023-02-13T16:36:25.296Z,1676306185.296 [Default:CheckIn:E] Stopped
2023-02-13T16:36:25.296Z,1676306185.296 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:36:25.296Z,1676306185.296 [Default:CheckIn] Stopped
2023-02-13T16:36:25.296Z,1676306185.296 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:36:25.296Z,1676306185.296 [Default:CheckIn](INFO): Running loop #9
2023-02-13T16:36:25.297Z,1676306185.297 [Default:CheckIn] Running Loop=9
2023-02-13T16:36:25.297Z,1676306185.297 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:36:25.309Z,1676306185.309 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:36:27.269Z,1676306187.269 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163626.00,A,3648.14119,N,12147.22795,W,0.136,52.94,130223,,,D*4F
2023-02-13T16:36:27.272Z,1676306187.272 [NAL9602](INFO): GPS fix at 20230213T163626: (36.802353, -121.787132)
2023-02-13T16:36:27.310Z,1676306187.310 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:36:27.310Z,1676306187.310 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:36:28.594Z,1676306188.594 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0031.lzma
2023-02-13T16:36:28.891Z,1676306188.891 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0031.lzma.bak
2023-02-13T16:36:28.891Z,1676306188.891 [DataOverHttps](INFO): SBD MOMSN=17464638
2023-02-13T16:36:45.221Z,1676306205.221 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20230213T153905/Express0032.lzma
2023-02-13T16:36:46.223Z,1676306206.223 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0032.lzma.bak
2023-02-13T16:36:46.223Z,1676306206.223 [DataOverHttps](INFO): SBD MOMSN=17464641
2023-02-13T16:36:47.629Z,1676306207.629 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:36:47.629Z,1676306207.629 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:36:47.630Z,1676306207.630 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:36:54.852Z,1676306214.852 [NAL9602](INFO): SBD MO Status=2, MOMSN=22019, MT Status=2, MTMSN=0
2023-02-13T16:36:54.852Z,1676306214.852 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T16:37:08.180Z,1676306228.180 [NAL9602](INFO): SBD MO Status=0, MOMSN=22019, MT Status=0, MTMSN=0
2023-02-13T16:37:08.180Z,1676306228.180 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:37:38.882Z,1676306258.882 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:41:48.170Z,1676306508.170 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:41:48.170Z,1676306508.170 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:41:48.171Z,1676306508.171 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:41:48.171Z,1676306508.171 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:41:48.577Z,1676306508.577 [Default:CheckIn:D] Stopped
2023-02-13T16:41:48.577Z,1676306508.577 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:41:48.978Z,1676306508.978 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.784428 min 
2023-02-13T16:41:48.978Z,1676306508.978 [Default:CheckIn:E] Stopped
2023-02-13T16:41:48.978Z,1676306508.978 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:41:48.978Z,1676306508.978 [Default:CheckIn] Stopped
2023-02-13T16:41:48.978Z,1676306508.978 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:41:48.978Z,1676306508.978 [Default:CheckIn](INFO): Running loop #10
2023-02-13T16:41:48.978Z,1676306508.978 [Default:CheckIn] Running Loop=10
2023-02-13T16:41:48.979Z,1676306508.979 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:41:48.979Z,1676306508.979 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:41:50.992Z,1676306510.992 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164150.00,A,3648.14361,N,12147.22928,W,0.039,0.00,130223,,,D*7F
2023-02-13T16:41:51.003Z,1676306511.003 [NAL9602](INFO): GPS fix at 20230213T164150: (36.802394, -121.787155)
2023-02-13T16:41:51.014Z,1676306511.014 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:41:51.014Z,1676306511.014 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:41:58.640Z,1676306518.640 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0034.lzma
2023-02-13T16:41:59.647Z,1676306519.647 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0034.lzma.bak
2023-02-13T16:41:59.647Z,1676306519.647 [DataOverHttps](INFO): SBD MOMSN=17464698
2023-02-13T16:42:17.668Z,1676306537.668 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230213T153905/Express0036.lzma
2023-02-13T16:42:18.671Z,1676306538.671 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0036.lzma.bak
2023-02-13T16:42:18.671Z,1676306538.671 [DataOverHttps](INFO): SBD MOMSN=17464701
2023-02-13T16:42:19.768Z,1676306539.768 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:42:19.768Z,1676306539.768 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:42:19.768Z,1676306539.768 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:42:20.076Z,1676306540.076 [NAL9602](INFO): SBD MO Status=0, MOMSN=22020, MT Status=0, MTMSN=0
2023-02-13T16:42:20.076Z,1676306540.076 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:42:50.826Z,1676306570.826 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:47:20.318Z,1676306840.318 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:47:20.318Z,1676306840.318 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:47:20.318Z,1676306840.318 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:47:20.318Z,1676306840.318 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:47:20.710Z,1676306840.710 [Default:CheckIn:D] Stopped
2023-02-13T16:47:20.710Z,1676306840.710 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:47:21.116Z,1676306841.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.319987 min 
2023-02-13T16:47:21.116Z,1676306841.116 [Default:CheckIn:E] Stopped
2023-02-13T16:47:21.116Z,1676306841.116 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:47:21.117Z,1676306841.117 [Default:CheckIn] Stopped
2023-02-13T16:47:21.117Z,1676306841.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:47:21.118Z,1676306841.118 [Default:CheckIn](INFO): Running loop #11
2023-02-13T16:47:21.118Z,1676306841.118 [Default:CheckIn] Running Loop=11
2023-02-13T16:47:21.118Z,1676306841.118 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:47:21.118Z,1676306841.118 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:47:23.131Z,1676306843.131 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164722.00,A,3648.14198,N,12147.22906,W,0.019,0.00,130223,,,D*76
2023-02-13T16:47:23.134Z,1676306843.134 [NAL9602](INFO): GPS fix at 20230213T164722: (36.802366, -121.787151)
2023-02-13T16:47:23.150Z,1676306843.150 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:47:23.150Z,1676306843.150 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:47:31.196Z,1676306851.196 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0040.lzma
2023-02-13T16:47:32.199Z,1676306852.199 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0040.lzma.bak
2023-02-13T16:47:32.199Z,1676306852.199 [DataOverHttps](INFO): SBD MOMSN=17464716
2023-02-13T16:47:33.632Z,1676306853.632 [NAL9602](INFO): SBD MO Status=0, MOMSN=22021, MT Status=0, MTMSN=0
2023-02-13T16:47:33.632Z,1676306853.632 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:47:48.520Z,1676306868.520 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230213T153905/Express0041.lzma
2023-02-13T16:47:49.523Z,1676306869.523 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0041.lzma.bak
2023-02-13T16:47:49.523Z,1676306869.523 [DataOverHttps](INFO): SBD MOMSN=17464719
2023-02-13T16:47:50.610Z,1676306870.610 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:47:50.610Z,1676306870.610 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:47:50.610Z,1676306870.610 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:48:04.334Z,1676306884.334 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:49:21.091Z,1676306961.091 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2023-02-13T16:52:51.222Z,1676307171.222 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:52:51.222Z,1676307171.222 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:52:51.222Z,1676307171.222 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:52:51.222Z,1676307171.222 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:52:51.670Z,1676307171.670 [Default:CheckIn:D] Stopped
2023-02-13T16:52:51.670Z,1676307171.670 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:52:52.031Z,1676307172.031 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.835978 min 
2023-02-13T16:52:52.031Z,1676307172.031 [Default:CheckIn:E] Stopped
2023-02-13T16:52:52.031Z,1676307172.031 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:52:52.031Z,1676307172.031 [Default:CheckIn] Stopped
2023-02-13T16:52:52.031Z,1676307172.031 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:52:52.031Z,1676307172.031 [Default:CheckIn](INFO): Running loop #12
2023-02-13T16:52:52.031Z,1676307172.031 [Default:CheckIn] Running Loop=12
2023-02-13T16:52:52.032Z,1676307172.032 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:52:52.032Z,1676307172.032 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:52:54.056Z,1676307174.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165253.00,A,3648.14172,N,12147.22874,W,0.019,0.00,130223,,,D*74
2023-02-13T16:52:54.059Z,1676307174.059 [NAL9602](INFO): GPS fix at 20230213T165253: (36.802362, -121.787146)
2023-02-13T16:52:54.071Z,1676307174.071 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:52:54.071Z,1676307174.071 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:53:01.864Z,1676307181.864 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T153905/Courier0043.lzma
2023-02-13T16:53:02.867Z,1676307182.867 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0043.lzma.bak
2023-02-13T16:53:02.867Z,1676307182.867 [DataOverHttps](INFO): SBD MOMSN=17464730
2023-02-13T16:53:10.204Z,1676307190.204 [NAL9602](INFO): SBD MO Status=0, MOMSN=22022, MT Status=0, MTMSN=0
2023-02-13T16:53:10.204Z,1676307190.204 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:53:19.497Z,1676307199.497 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230213T153905/Express0044.lzma
2023-02-13T16:53:20.499Z,1676307200.499 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0044.lzma.bak
2023-02-13T16:53:20.499Z,1676307200.499 [DataOverHttps](INFO): SBD MOMSN=17464733
2023-02-13T16:53:21.558Z,1676307201.558 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:53:21.558Z,1676307201.558 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:53:21.558Z,1676307201.558 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:53:40.906Z,1676307220.906 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T16:54:31.810Z,1676307271.810 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-02-13T16:54:31.813Z,1676307271.813 [BPC1](INFO): Received data from all battery sticks.
2023-02-13T16:58:22.171Z,1676307502.171 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T16:58:22.171Z,1676307502.171 [Default:CheckIn:C.Wait] Stopped
2023-02-13T16:58:22.171Z,1676307502.171 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T16:58:22.171Z,1676307502.171 [Default:CheckIn:D] Running Loop=1
2023-02-13T16:58:22.585Z,1676307502.585 [Default:CheckIn:D] Stopped
2023-02-13T16:58:22.585Z,1676307502.585 [Default:CheckIn:E] Running Loop=1
2023-02-13T16:58:22.976Z,1676307502.976 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.351237 min 
2023-02-13T16:58:22.977Z,1676307502.977 [Default:CheckIn:E] Stopped
2023-02-13T16:58:22.977Z,1676307502.977 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T16:58:22.977Z,1676307502.977 [Default:CheckIn] Stopped
2023-02-13T16:58:22.977Z,1676307502.977 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T16:58:22.977Z,1676307502.977 [Default:CheckIn](INFO): Running loop #13
2023-02-13T16:58:22.977Z,1676307502.977 [Default:CheckIn] Running Loop=13
2023-02-13T16:58:22.977Z,1676307502.977 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T16:58:22.977Z,1676307502.977 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T16:58:24.985Z,1676307504.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165824.00,A,3648.14290,N,12147.22991,W,0.019,0.00,130223,,,D*7B
2023-02-13T16:58:24.987Z,1676307504.987 [NAL9602](INFO): GPS fix at 20230213T165824: (36.802382, -121.787165)
2023-02-13T16:58:24.998Z,1676307504.998 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T16:58:24.998Z,1676307504.998 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T16:58:32.940Z,1676307512.940 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230213T153905/Courier0046.lzma
2023-02-13T16:58:33.943Z,1676307513.943 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0046.lzma.bak
2023-02-13T16:58:33.943Z,1676307513.943 [DataOverHttps](INFO): SBD MOMSN=17464755
2023-02-13T16:58:45.626Z,1676307525.626 [NAL9602](INFO): SBD MO Status=0, MOMSN=22023, MT Status=0, MTMSN=0
2023-02-13T16:58:45.626Z,1676307525.626 [NAL9602](INFO): No messages in MT queue
2023-02-13T16:58:50.337Z,1676307530.337 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230213T153905/Express0047.lzma
2023-02-13T16:58:51.400Z,1676307531.400 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0047.lzma.bak
2023-02-13T16:58:51.400Z,1676307531.400 [DataOverHttps](INFO): SBD MOMSN=17464758
2023-02-13T16:58:52.466Z,1676307532.466 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T16:58:52.466Z,1676307532.466 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T16:58:52.466Z,1676307532.466 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T16:59:16.290Z,1676307556.290 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:03:53.047Z,1676307833.047 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:03:53.047Z,1676307833.047 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:03:53.047Z,1676307833.047 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:03:53.048Z,1676307833.048 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:03:53.496Z,1676307833.496 [Default:CheckIn:D] Stopped
2023-02-13T17:03:53.496Z,1676307833.496 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:03:53.879Z,1676307833.879 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.866414 min 
2023-02-13T17:03:53.879Z,1676307833.879 [Default:CheckIn:E] Stopped
2023-02-13T17:03:53.879Z,1676307833.879 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:03:53.879Z,1676307833.879 [Default:CheckIn] Stopped
2023-02-13T17:03:53.879Z,1676307833.879 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:03:53.879Z,1676307833.879 [Default:CheckIn](INFO): Running loop #14
2023-02-13T17:03:53.879Z,1676307833.879 [Default:CheckIn] Running Loop=14
2023-02-13T17:03:53.879Z,1676307833.879 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:03:53.879Z,1676307833.879 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:03:55.873Z,1676307835.873 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170355.00,A,3648.14147,N,12147.22894,W,0.000,0.00,130223,,,D*77
2023-02-13T17:03:55.875Z,1676307835.875 [NAL9602](INFO): GPS fix at 20230213T170355: (36.802358, -121.787149)
2023-02-13T17:03:55.906Z,1676307835.906 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:03:55.906Z,1676307835.906 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:04:03.152Z,1676307843.152 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0049.lzma
2023-02-13T17:04:04.155Z,1676307844.155 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0049.lzma.bak
2023-02-13T17:04:04.155Z,1676307844.155 [DataOverHttps](INFO): SBD MOMSN=17464771
2023-02-13T17:04:12.837Z,1676307852.837 [NAL9602](INFO): SBD MO Status=0, MOMSN=22024, MT Status=0, MTMSN=0
2023-02-13T17:04:12.837Z,1676307852.837 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:04:23.629Z,1676307863.629 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230213T153905/Express0050.lzma
2023-02-13T17:04:24.631Z,1676307864.631 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0050.lzma.bak
2023-02-13T17:04:24.631Z,1676307864.631 [DataOverHttps](INFO): SBD MOMSN=17464778
2023-02-13T17:04:25.910Z,1676307865.910 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:04:25.910Z,1676307865.910 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:04:25.910Z,1676307865.910 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:04:43.598Z,1676307883.598 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:09:26.477Z,1676308166.477 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:09:26.477Z,1676308166.477 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:09:26.477Z,1676308166.477 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:09:26.477Z,1676308166.477 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:09:26.879Z,1676308166.879 [Default:CheckIn:D] Stopped
2023-02-13T17:09:26.879Z,1676308166.879 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:09:27.286Z,1676308167.286 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.422795 min 
2023-02-13T17:09:27.286Z,1676308167.286 [Default:CheckIn:E] Stopped
2023-02-13T17:09:27.286Z,1676308167.286 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:09:27.286Z,1676308167.286 [Default:CheckIn] Stopped
2023-02-13T17:09:27.287Z,1676308167.287 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:09:27.287Z,1676308167.287 [Default:CheckIn](INFO): Running loop #15
2023-02-13T17:09:27.287Z,1676308167.287 [Default:CheckIn] Running Loop=15
2023-02-13T17:09:27.287Z,1676308167.287 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:09:27.287Z,1676308167.287 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:09:29.296Z,1676308169.296 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170928.00,A,3648.14069,N,12147.22953,W,0.039,0.00,130223,,,D*7A
2023-02-13T17:09:29.308Z,1676308169.308 [NAL9602](INFO): GPS fix at 20230213T170928: (36.802345, -121.787159)
2023-02-13T17:09:29.318Z,1676308169.318 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:09:29.318Z,1676308169.318 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:09:36.692Z,1676308176.692 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T153905/Courier0052.lzma
2023-02-13T17:09:37.695Z,1676308177.695 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0052.lzma.bak
2023-02-13T17:09:37.695Z,1676308177.695 [DataOverHttps](INFO): SBD MOMSN=17464789
2023-02-13T17:09:54.488Z,1676308194.488 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230213T153905/Express0053.lzma
2023-02-13T17:09:55.494Z,1676308195.494 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0053.lzma.bak
2023-02-13T17:09:55.494Z,1676308195.494 [DataOverHttps](INFO): SBD MOMSN=17464792
2023-02-13T17:09:56.810Z,1676308196.810 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:09:56.810Z,1676308196.810 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:09:56.811Z,1676308196.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:10:00.812Z,1676308200.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=22025, MT Status=2, MTMSN=0
2023-02-13T17:10:00.812Z,1676308200.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T17:10:26.260Z,1676308226.260 [NAL9602](INFO): SBD MO Status=0, MOMSN=22025, MT Status=0, MTMSN=0
2023-02-13T17:10:26.260Z,1676308226.260 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:10:56.967Z,1676308256.967 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:14:57.402Z,1676308497.402 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:14:57.402Z,1676308497.402 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:14:57.403Z,1676308497.403 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:14:57.403Z,1676308497.403 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:14:57.822Z,1676308497.822 [Default:CheckIn:D] Stopped
2023-02-13T17:14:57.822Z,1676308497.822 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:14:58.200Z,1676308498.200 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.938517 min 
2023-02-13T17:14:58.201Z,1676308498.201 [Default:CheckIn:E] Stopped
2023-02-13T17:14:58.201Z,1676308498.201 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:14:58.201Z,1676308498.201 [Default:CheckIn] Stopped
2023-02-13T17:14:58.201Z,1676308498.201 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:14:58.201Z,1676308498.201 [Default:CheckIn](INFO): Running loop #16
2023-02-13T17:14:58.201Z,1676308498.201 [Default:CheckIn] Running Loop=16
2023-02-13T17:14:58.201Z,1676308498.201 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:14:58.202Z,1676308498.202 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:15:00.218Z,1676308500.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171459.00,A,3648.14069,N,12147.22903,W,0.039,0.00,130223,,,D*75
2023-02-13T17:15:00.220Z,1676308500.220 [NAL9602](INFO): GPS fix at 20230213T171459: (36.802345, -121.787150)
2023-02-13T17:15:00.231Z,1676308500.231 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:15:00.231Z,1676308500.231 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:15:07.780Z,1676308507.780 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0055.lzma
2023-02-13T17:15:08.783Z,1676308508.783 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0055.lzma.bak
2023-02-13T17:15:08.783Z,1676308508.783 [DataOverHttps](INFO): SBD MOMSN=17464839
2023-02-13T17:15:25.193Z,1676308525.193 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230213T153905/Express0056.lzma
2023-02-13T17:15:26.195Z,1676308526.195 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0056.lzma.bak
2023-02-13T17:15:26.195Z,1676308526.195 [DataOverHttps](INFO): SBD MOMSN=17464845
2023-02-13T17:15:27.323Z,1676308527.323 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:15:27.323Z,1676308527.323 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:15:27.323Z,1676308527.323 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:15:28.108Z,1676308528.108 [NAL9602](INFO): SBD MO Status=0, MOMSN=22026, MT Status=0, MTMSN=0
2023-02-13T17:15:28.108Z,1676308528.108 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:15:58.814Z,1676308558.814 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:20:27.960Z,1676308827.960 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:20:27.960Z,1676308827.960 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:20:27.961Z,1676308827.961 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:20:27.961Z,1676308827.961 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:20:28.371Z,1676308828.371 [Default:CheckIn:D] Stopped
2023-02-13T17:20:28.371Z,1676308828.371 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:20:28.765Z,1676308828.765 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.447664 min 
2023-02-13T17:20:28.765Z,1676308828.765 [Default:CheckIn:E] Stopped
2023-02-13T17:20:28.765Z,1676308828.765 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:20:28.765Z,1676308828.765 [Default:CheckIn] Stopped
2023-02-13T17:20:28.766Z,1676308828.766 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:20:28.766Z,1676308828.766 [Default:CheckIn](INFO): Running loop #17
2023-02-13T17:20:28.766Z,1676308828.766 [Default:CheckIn] Running Loop=17
2023-02-13T17:20:28.766Z,1676308828.766 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:20:28.766Z,1676308828.766 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:20:30.773Z,1676308830.773 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172030.00,A,3648.14046,N,12147.22893,W,0.019,0.00,130223,,,D*7A
2023-02-13T17:20:30.776Z,1676308830.776 [NAL9602](INFO): GPS fix at 20230213T172030: (36.802341, -121.787149)
2023-02-13T17:20:30.794Z,1676308830.794 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:20:30.794Z,1676308830.794 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:20:38.760Z,1676308838.760 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230213T153905/Courier0058.lzma
2023-02-13T17:20:39.763Z,1676308839.763 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0058.lzma.bak
2023-02-13T17:20:39.763Z,1676308839.763 [DataOverHttps](INFO): SBD MOMSN=17464855
2023-02-13T17:20:48.952Z,1676308848.952 [NAL9602](INFO): SBD MO Status=0, MOMSN=22027, MT Status=0, MTMSN=0
2023-02-13T17:20:48.952Z,1676308848.952 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:20:56.164Z,1676308856.164 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230213T153905/Express0059.lzma
2023-02-13T17:20:57.167Z,1676308857.167 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0059.lzma.bak
2023-02-13T17:20:57.167Z,1676308857.167 [DataOverHttps](INFO): SBD MOMSN=17464865
2023-02-13T17:20:58.260Z,1676308858.260 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:20:58.260Z,1676308858.260 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:20:58.260Z,1676308858.260 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:21:19.654Z,1676308879.654 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:25:58.903Z,1676309158.903 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:25:58.903Z,1676309158.903 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:25:58.903Z,1676309158.903 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:25:58.903Z,1676309158.903 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:25:59.294Z,1676309159.294 [Default:CheckIn:D] Stopped
2023-02-13T17:25:59.294Z,1676309159.294 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:25:59.716Z,1676309159.716 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.963045 min 
2023-02-13T17:25:59.717Z,1676309159.717 [Default:CheckIn:E] Stopped
2023-02-13T17:25:59.733Z,1676309159.733 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:25:59.733Z,1676309159.733 [Default:CheckIn] Stopped
2023-02-13T17:25:59.733Z,1676309159.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:25:59.733Z,1676309159.733 [Default:CheckIn](INFO): Running loop #18
2023-02-13T17:25:59.733Z,1676309159.733 [Default:CheckIn] Running Loop=18
2023-02-13T17:25:59.733Z,1676309159.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:25:59.734Z,1676309159.734 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:26:01.711Z,1676309161.711 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172600.00,A,3648.14268,N,12147.22895,W,0.019,0.00,130223,,,D*77
2023-02-13T17:26:01.714Z,1676309161.714 [NAL9602](INFO): GPS fix at 20230213T172600: (36.802378, -121.787149)
2023-02-13T17:26:01.744Z,1676309161.744 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:26:01.745Z,1676309161.745 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:26:09.028Z,1676309169.028 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T153905/Courier0061.lzma
2023-02-13T17:26:10.031Z,1676309170.031 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0061.lzma.bak
2023-02-13T17:26:10.031Z,1676309170.031 [DataOverHttps](INFO): SBD MOMSN=17464877
2023-02-13T17:26:17.120Z,1676309177.120 [NAL9602](INFO): SBD MO Status=0, MOMSN=22028, MT Status=0, MTMSN=0
2023-02-13T17:26:17.120Z,1676309177.120 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:26:29.483Z,1676309189.483 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230213T153905/Express0062.lzma
2023-02-13T17:26:30.479Z,1676309190.479 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0062.lzma.bak
2023-02-13T17:26:30.479Z,1676309190.479 [DataOverHttps](INFO): SBD MOMSN=17464885
2023-02-13T17:26:31.709Z,1676309191.709 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:26:31.709Z,1676309191.709 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:26:31.709Z,1676309191.709 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:26:47.835Z,1676309207.835 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:28:46.813Z,1676309326.813 [CommandExec](IMPORTANT): got command failComponent
2023-02-13T17:28:46.814Z,1676309326.814 [CommandExec](IMPORTANT): Failed components:
2023-02-13T17:28:46.814Z,1676309326.814 [CommandExec](IMPORTANT): No failed Components.
2023-02-13T17:31:32.284Z,1676309492.284 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:31:32.284Z,1676309492.284 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:31:32.285Z,1676309492.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:31:32.285Z,1676309492.285 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:31:32.693Z,1676309492.693 [Default:CheckIn:D] Stopped
2023-02-13T17:31:32.693Z,1676309492.693 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:31:33.097Z,1676309493.097 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.519702 min 
2023-02-13T17:31:33.097Z,1676309493.097 [Default:CheckIn:E] Stopped
2023-02-13T17:31:33.097Z,1676309493.097 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:31:33.097Z,1676309493.097 [Default:CheckIn] Stopped
2023-02-13T17:31:33.097Z,1676309493.097 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:31:33.097Z,1676309493.097 [Default:CheckIn](INFO): Running loop #19
2023-02-13T17:31:33.097Z,1676309493.097 [Default:CheckIn] Running Loop=19
2023-02-13T17:31:33.098Z,1676309493.098 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:31:33.098Z,1676309493.098 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:31:35.108Z,1676309495.108 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173134.00,A,3648.14123,N,12147.22894,W,0.019,0.00,130223,,,D*7B
2023-02-13T17:31:35.119Z,1676309495.119 [NAL9602](INFO): GPS fix at 20230213T173134: (36.802354, -121.787149)
2023-02-13T17:31:35.131Z,1676309495.131 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:31:35.131Z,1676309495.131 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:31:42.864Z,1676309502.864 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0064.lzma
2023-02-13T17:31:43.867Z,1676309503.867 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0064.lzma.bak
2023-02-13T17:31:43.867Z,1676309503.867 [DataOverHttps](INFO): SBD MOMSN=17464908
2023-02-13T17:31:54.096Z,1676309514.096 [NAL9602](INFO): SBD MO Status=0, MOMSN=22029, MT Status=0, MTMSN=0
2023-02-13T17:31:54.096Z,1676309514.096 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:32:00.153Z,1676309520.153 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230213T153905/Express0065.lzma
2023-02-13T17:32:01.155Z,1676309521.155 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0065.lzma.bak
2023-02-13T17:32:01.155Z,1676309521.155 [DataOverHttps](INFO): SBD MOMSN=17464914
2023-02-13T17:32:02.209Z,1676309522.209 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:32:02.209Z,1676309522.209 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:32:02.209Z,1676309522.209 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:32:24.792Z,1676309544.792 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:37:02.810Z,1676309822.810 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:37:02.810Z,1676309822.810 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:37:02.810Z,1676309822.810 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:37:02.810Z,1676309822.810 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:37:03.219Z,1676309823.219 [Default:CheckIn:D] Stopped
2023-02-13T17:37:03.219Z,1676309823.219 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:37:03.650Z,1676309823.650 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.028475 min 
2023-02-13T17:37:03.650Z,1676309823.650 [Default:CheckIn:E] Stopped
2023-02-13T17:37:03.650Z,1676309823.650 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:37:03.650Z,1676309823.650 [Default:CheckIn] Stopped
2023-02-13T17:37:03.650Z,1676309823.650 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:37:03.650Z,1676309823.650 [Default:CheckIn](INFO): Running loop #20
2023-02-13T17:37:03.651Z,1676309823.651 [Default:CheckIn] Running Loop=20
2023-02-13T17:37:03.651Z,1676309823.651 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:37:03.651Z,1676309823.651 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:37:05.624Z,1676309825.624 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173704.00,A,3648.14335,N,12147.22911,W,0.058,0.00,130223,,,D*72
2023-02-13T17:37:05.627Z,1676309825.627 [NAL9602](INFO): GPS fix at 20230213T173704: (36.802389, -121.787152)
2023-02-13T17:37:05.674Z,1676309825.674 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:37:05.675Z,1676309825.675 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:37:13.729Z,1676309833.729 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T153905/Courier0067.lzma
2023-02-13T17:37:14.731Z,1676309834.731 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0067.lzma.bak
2023-02-13T17:37:14.731Z,1676309834.731 [DataOverHttps](INFO): SBD MOMSN=17464927
2023-02-13T17:37:19.764Z,1676309839.764 [NAL9602](INFO): SBD MO Status=0, MOMSN=22030, MT Status=0, MTMSN=0
2023-02-13T17:37:19.764Z,1676309839.764 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:37:31.065Z,1676309851.065 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230213T153905/Express0068.lzma
2023-02-13T17:37:32.067Z,1676309852.067 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0068.lzma.bak
2023-02-13T17:37:32.067Z,1676309852.067 [DataOverHttps](INFO): SBD MOMSN=17464937
2023-02-13T17:37:33.120Z,1676309853.120 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:37:33.120Z,1676309853.120 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:37:33.120Z,1676309853.120 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:37:50.474Z,1676309870.474 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:42:33.813Z,1676310153.813 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:42:33.829Z,1676310153.829 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:42:33.829Z,1676310153.829 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:42:33.830Z,1676310153.830 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:42:34.199Z,1676310154.199 [Default:CheckIn:D] Stopped
2023-02-13T17:42:34.199Z,1676310154.199 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:42:34.614Z,1676310154.614 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.544792 min 
2023-02-13T17:42:34.614Z,1676310154.614 [Default:CheckIn:E] Stopped
2023-02-13T17:42:34.615Z,1676310154.615 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:42:34.615Z,1676310154.615 [Default:CheckIn] Stopped
2023-02-13T17:42:34.615Z,1676310154.615 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:42:34.615Z,1676310154.615 [Default:CheckIn](INFO): Running loop #21
2023-02-13T17:42:34.615Z,1676310154.615 [Default:CheckIn] Running Loop=21
2023-02-13T17:42:34.615Z,1676310154.615 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:42:34.615Z,1676310154.615 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:42:36.612Z,1676310156.612 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174235.00,A,3648.14100,N,12147.22936,W,0.058,0.00,130223,,,D*73
2023-02-13T17:42:36.614Z,1676310156.614 [NAL9602](INFO): GPS fix at 20230213T174235: (36.802350, -121.787156)
2023-02-13T17:42:36.625Z,1676310156.625 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:42:36.625Z,1676310156.625 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:42:44.224Z,1676310164.224 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0070.lzma
2023-02-13T17:42:45.227Z,1676310165.227 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0070.lzma.bak
2023-02-13T17:42:45.227Z,1676310165.227 [DataOverHttps](INFO): SBD MOMSN=17464954
2023-02-13T17:42:49.948Z,1676310169.948 [NAL9602](INFO): SBD MO Status=0, MOMSN=22031, MT Status=0, MTMSN=0
2023-02-13T17:42:49.948Z,1676310169.948 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:43:01.604Z,1676310181.604 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230213T153905/Express0071.lzma
2023-02-13T17:43:02.607Z,1676310182.607 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0071.lzma.bak
2023-02-13T17:43:02.607Z,1676310182.607 [DataOverHttps](INFO): SBD MOMSN=17464963
2023-02-13T17:43:03.729Z,1676310183.729 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:43:03.729Z,1676310183.729 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:43:03.729Z,1676310183.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:43:20.651Z,1676310200.651 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:44:25.862Z,1676310265.862 [DataOverHttps](IMPORTANT): SBD MTMSN=20230213T174424
2023-02-13T17:44:33.455Z,1676310273.455 [DataOverHttps](INFO): Received command: stobe off
2023-02-13T17:44:33.498Z,1676310273.498 [CommandExec](IMPORTANT): got command stop 
2023-02-13T17:44:33.498Z,1676310273.498 [CommandExec](IMPORTANT): Scheduling is paused
2023-02-13T17:44:33.499Z,1676310273.499 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-02-13T17:46:57.546Z,1676310417.546 [DataOverHttps](IMPORTANT): SBD MTMSN=20230213T174656
2023-02-13T17:47:05.308Z,1676310425.308 [DataOverHttps](INFO): Received command: stobe off
2023-02-13T17:47:05.394Z,1676310425.394 [CommandExec](IMPORTANT): got command stop 
2023-02-13T17:48:04.373Z,1676310484.373 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:48:04.373Z,1676310484.373 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:48:04.373Z,1676310484.373 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:48:04.373Z,1676310484.373 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:48:04.773Z,1676310484.773 [Default:CheckIn:D] Stopped
2023-02-13T17:48:04.773Z,1676310484.773 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:48:05.179Z,1676310485.179 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.054362 min 
2023-02-13T17:48:05.179Z,1676310485.179 [Default:CheckIn:E] Stopped
2023-02-13T17:48:05.179Z,1676310485.179 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:48:05.179Z,1676310485.179 [Default:CheckIn] Stopped
2023-02-13T17:48:05.180Z,1676310485.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:48:05.180Z,1676310485.180 [Default:CheckIn](INFO): Running loop #22
2023-02-13T17:48:05.180Z,1676310485.180 [Default:CheckIn] Running Loop=22
2023-02-13T17:48:05.180Z,1676310485.180 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:48:05.180Z,1676310485.180 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:48:07.189Z,1676310487.189 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174806.00,A,3648.14029,N,12147.22600,W,0.019,115.78,130223,,,D*76
2023-02-13T17:48:07.192Z,1676310487.192 [NAL9602](INFO): GPS fix at 20230213T174806: (36.802338, -121.787100)
2023-02-13T17:48:07.222Z,1676310487.222 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:48:07.222Z,1676310487.222 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:48:14.960Z,1676310494.960 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T153905/Courier0073.lzma
2023-02-13T17:48:15.963Z,1676310495.963 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0073.lzma.bak
2023-02-13T17:48:15.963Z,1676310495.963 [DataOverHttps](INFO): SBD MOMSN=17464982
2023-02-13T17:48:27.471Z,1676310507.471 [NAL9602](INFO): SBD MO Status=2, MOMSN=22032, MT Status=2, MTMSN=0
2023-02-13T17:48:27.472Z,1676310507.472 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T17:48:35.553Z,1676310515.553 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20230213T153905/Express0074.lzma
2023-02-13T17:48:36.555Z,1676310516.555 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0074.lzma.bak
2023-02-13T17:48:36.555Z,1676310516.555 [DataOverHttps](INFO): SBD MOMSN=17464991
2023-02-13T17:48:53.028Z,1676310533.028 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20230213T153905/Express0077.lzma
2023-02-13T17:48:54.031Z,1676310534.031 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0077.lzma.bak
2023-02-13T17:48:54.031Z,1676310534.031 [DataOverHttps](INFO): SBD MOMSN=17465001
2023-02-13T17:48:55.333Z,1676310535.333 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:48:55.333Z,1676310535.333 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:48:55.333Z,1676310535.333 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T17:48:56.934Z,1676310536.934 [NAL9602](INFO): SBD MO Status=2, MOMSN=22032, MT Status=2, MTMSN=0
2023-02-13T17:48:56.935Z,1676310536.935 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T17:49:29.672Z,1676310569.672 [NAL9602](INFO): SBD MO Status=0, MOMSN=22032, MT Status=0, MTMSN=0
2023-02-13T17:49:29.672Z,1676310569.672 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:50:00.361Z,1676310600.361 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:50:08.243Z,1676310608.243 [CommandExec](IMPORTANT): got command strobe off
2023-02-13T17:50:08.243Z,1676310608.243 [CommandExec](IMPORTANT): Deactivating strobe
2023-02-13T17:51:25.830Z,1676310685.830 [DataOverHttps](IMPORTANT): SBD MTMSN=20230213T175124
2023-02-13T17:51:33.577Z,1676310693.577 [DataOverHttps](INFO): Received command: ibit
2023-02-13T17:51:33.674Z,1676310693.674 [CommandExec](IMPORTANT): got command ibit
2023-02-13T17:51:33.762Z,1676310693.762 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-02-13T17:51:33.762Z,1676310693.762 [IBIT](IMPORTANT): Beginning control surface checks.
2023-02-13T17:51:33.766Z,1676310693.766 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-13T17:51:35.320Z,1676310695.320 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175134.00,A,3648.13972,N,12147.22731,W,0.019,115.78,130223,,,D*7C
2023-02-13T17:51:35.323Z,1676310695.323 [NAL9602](INFO): GPS fix at 20230213T175134: (36.802329, -121.787122)
2023-02-13T17:51:44.645Z,1676310704.645 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009174
CHAN A1 (24V): 0.000457
CHAN A2 (12V): -0.007706
CHAN A3 (5V): -0.002346
CHAN B0 (3.3V): 0.000243
CHAN B1 (3.15aV): -0.000340
CHAN B2 (3.15bV): 0.000019
CHAN B3 (GND): 0.002280
OPEN: 0.005270
Full Scale: +/- 1 mA
2023-02-13T17:51:51.884Z,1676310711.884 [NAL9602](INFO): SBD MO Status=0, MOMSN=22033, MT Status=0, MTMSN=0
2023-02-13T17:51:51.884Z,1676310711.884 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:51:53.091Z,1676310713.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175152.00,A,3648.13899,N,12147.22706,W,0.019,115.78,130223,,,D*7C
2023-02-13T17:51:53.093Z,1676310713.093 [NAL9602](INFO): GPS fix at 20230213T175152: (36.802317, -121.787118)
2023-02-13T17:51:55.919Z,1676310715.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175155.00,A,3648.13895,N,12147.22706,W,0.019,115.78,130223,,,D*77
2023-02-13T17:51:55.921Z,1676310715.921 [NAL9602](INFO): GPS fix at 20230213T175155: (36.802316, -121.787118)
2023-02-13T17:51:59.152Z,1676310719.152 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175158.00,A,3648.13904,N,12147.22713,W,0.097,115.78,130223,,,D*71
2023-02-13T17:51:59.155Z,1676310719.155 [NAL9602](INFO): GPS fix at 20230213T175158: (36.802317, -121.787119)
2023-02-13T17:52:01.984Z,1676310721.984 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175201.00,A,3648.13921,N,12147.22726,W,0.039,115.78,130223,,,D*7B
2023-02-13T17:52:01.987Z,1676310721.987 [NAL9602](INFO): GPS fix at 20230213T175201: (36.802320, -121.787121)
2023-02-13T17:52:04.812Z,1676310724.812 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175204.00,A,3648.13930,N,12147.22728,W,0.039,115.78,130223,,,D*70
2023-02-13T17:52:04.815Z,1676310724.815 [NAL9602](INFO): GPS fix at 20230213T175204: (36.802322, -121.787121)
2023-02-13T17:52:08.040Z,1676310728.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175207.00,A,3648.13946,N,12147.22733,W,0.078,115.78,130223,,,D*7D
2023-02-13T17:52:08.043Z,1676310728.043 [NAL9602](INFO): GPS fix at 20230213T175207: (36.802324, -121.787122)
2023-02-13T17:52:10.875Z,1676310730.875 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175210.00,A,3648.13953,N,12147.22725,W,0.019,115.78,130223,,,D*7F
2023-02-13T17:52:10.877Z,1676310730.877 [NAL9602](INFO): GPS fix at 20230213T175210: (36.802326, -121.787121)
2023-02-13T17:52:14.116Z,1676310734.116 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175213.00,A,3648.13954,N,12147.22716,W,0.039,115.78,130223,,,D*79
2023-02-13T17:52:14.119Z,1676310734.119 [NAL9602](INFO): GPS fix at 20230213T175213: (36.802326, -121.787119)
2023-02-13T17:52:16.942Z,1676310736.942 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175216.00,A,3648.13958,N,12147.22713,W,0.039,115.78,130223,,,D*75
2023-02-13T17:52:16.945Z,1676310736.945 [NAL9602](INFO): GPS fix at 20230213T175216: (36.802326, -121.787119)
2023-02-13T17:52:19.855Z,1676310739.855 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802326 Longitude: -121.787117
2023-02-13T17:52:20.174Z,1676310740.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175219.00,A,3648.13963,N,12147.22705,W,0.019,115.78,130223,,,D*77
2023-02-13T17:52:20.176Z,1676310740.176 [NAL9602](INFO): GPS fix at 20230213T175219: (36.802327, -121.787117)
2023-02-13T17:52:20.201Z,1676310740.201 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.690001
2023-02-13T17:52:20.202Z,1676310740.202 [IBIT](IMPORTANT): batteryCapacityThreshold: 24.000000 Ah
2023-02-13T17:52:20.202Z,1676310740.202 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.000000 V
2023-02-13T17:52:20.606Z,1676310740.606 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-02-13T17:52:20.607Z,1676310740.607 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-02-13T17:52:20.607Z,1676310740.607 [IBIT](IMPORTANT): Pressure:7.702481 PSI
2023-02-13T17:52:20.607Z,1676310740.607 [IBIT](IMPORTANT): Humidity:23.373215 %
2023-02-13T17:52:21.000Z,1676310741.000 [IBIT](IMPORTANT): Vehicle Pitch:0.230124 degrees
2023-02-13T17:52:21.000Z,1676310741.000 [IBIT](IMPORTANT): Vehicle  Roll:-3.710799 degrees
2023-02-13T17:52:21.001Z,1676310741.001 [IBIT](IMPORTANT): Vehicle  Heading:21.940180 degrees
2023-02-13T17:52:21.494Z,1676310741.494 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-02-13T17:52:21.495Z,1676310741.495 [IBIT](IMPORTANT): buoyancyNeutral: 172.109741 cc
2023-02-13T17:52:21.495Z,1676310741.495 [IBIT](IMPORTANT): massDefault: 0.732883 cm
2023-02-13T17:52:21.495Z,1676310741.495 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-02-13T17:52:21.495Z,1676310741.495 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2023-02-13T17:52:21.495Z,1676310741.495 [IBIT](IMPORTANT): IBIT PASSED
2023-02-13T17:52:52.486Z,1676310772.486 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:53:55.921Z,1676310835.921 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T17:53:55.921Z,1676310835.921 [Default:CheckIn:C.Wait] Stopped
2023-02-13T17:53:55.921Z,1676310835.921 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T17:53:55.921Z,1676310835.921 [Default:CheckIn:D] Running Loop=1
2023-02-13T17:53:56.323Z,1676310836.323 [Default:CheckIn:D] Stopped
2023-02-13T17:53:56.323Z,1676310836.323 [Default:CheckIn:E] Running Loop=1
2023-02-13T17:53:56.733Z,1676310836.733 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.913534 min 
2023-02-13T17:53:56.733Z,1676310836.733 [Default:CheckIn:E] Stopped
2023-02-13T17:53:56.733Z,1676310836.733 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T17:53:56.733Z,1676310836.733 [Default:CheckIn] Stopped
2023-02-13T17:53:56.733Z,1676310836.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T17:53:56.733Z,1676310836.733 [Default:CheckIn](INFO): Running loop #23
2023-02-13T17:53:56.734Z,1676310836.734 [Default:CheckIn] Running Loop=23
2023-02-13T17:53:56.734Z,1676310836.734 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T17:53:56.734Z,1676310836.734 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T17:53:58.740Z,1676310838.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175358.00,A,3648.15406,N,12147.22079,W,0.039,18.66,130223,,,D*46
2023-02-13T17:53:58.751Z,1676310838.751 [NAL9602](INFO): GPS fix at 20230213T175358: (36.802568, -121.787013)
2023-02-13T17:53:58.762Z,1676310838.762 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T17:53:58.762Z,1676310838.762 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T17:54:06.836Z,1676310846.836 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0079.lzma
2023-02-13T17:54:07.839Z,1676310847.839 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0079.lzma.bak
2023-02-13T17:54:07.839Z,1676310847.839 [DataOverHttps](INFO): SBD MOMSN=17465017
2023-02-13T17:54:14.128Z,1676310854.128 [NAL9602](INFO): SBD MO Status=0, MOMSN=22034, MT Status=0, MTMSN=0
2023-02-13T17:54:14.128Z,1676310854.128 [NAL9602](INFO): No messages in MT queue
2023-02-13T17:54:28.040Z,1676310868.040 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230213T153905/Courier0082.lzma
2023-02-13T17:54:29.043Z,1676310869.043 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0082.lzma.bak
2023-02-13T17:54:29.043Z,1676310869.043 [DataOverHttps](INFO): SBD MOMSN=17465024
2023-02-13T17:54:44.850Z,1676310884.850 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T17:54:45.394Z,1676310885.394 [DataOverHttps](INFO): Sending 799 bytes from file Logs/20230213T153905/Express0080.lzma
2023-02-13T17:54:46.395Z,1676310886.395 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0080.lzma.bak
2023-02-13T17:54:46.395Z,1676310886.395 [DataOverHttps](INFO): SBD MOMSN=17465026
2023-02-13T17:55:02.736Z,1676310902.736 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230213T153905/Express0083.lzma
2023-02-13T17:55:03.740Z,1676310903.740 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0083.lzma.bak
2023-02-13T17:55:03.741Z,1676310903.741 [DataOverHttps](INFO): SBD MOMSN=17465055
2023-02-13T17:55:05.070Z,1676310905.070 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T17:55:05.070Z,1676310905.070 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T17:55:05.070Z,1676310905.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:00:05.530Z,1676311205.530 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:00:05.530Z,1676311205.530 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:00:05.530Z,1676311205.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:00:05.530Z,1676311205.530 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:00:05.898Z,1676311205.898 [Default:CheckIn:D] Stopped
2023-02-13T18:00:05.898Z,1676311205.898 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:00:06.303Z,1676311206.303 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.073128 min 
2023-02-13T18:00:06.303Z,1676311206.303 [Default:CheckIn:E] Stopped
2023-02-13T18:00:06.303Z,1676311206.303 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:00:06.303Z,1676311206.303 [Default:CheckIn] Stopped
2023-02-13T18:00:06.303Z,1676311206.303 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:00:06.303Z,1676311206.303 [Default:CheckIn](INFO): Running loop #24
2023-02-13T18:00:06.304Z,1676311206.304 [Default:CheckIn] Running Loop=24
2023-02-13T18:00:06.304Z,1676311206.304 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:00:06.304Z,1676311206.304 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:00:08.316Z,1676311208.316 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180007.00,A,3648.15382,N,12147.21773,W,0.019,74.44,130223,,,D*48
2023-02-13T18:00:08.319Z,1676311208.319 [NAL9602](INFO): GPS fix at 20230213T180007: (36.802564, -121.786962)
2023-02-13T18:00:08.330Z,1676311208.330 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:00:08.330Z,1676311208.330 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:00:18.956Z,1676311218.956 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230213T153905/Courier0085.lzma
2023-02-13T18:00:19.959Z,1676311219.959 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0085.lzma.bak
2023-02-13T18:00:19.959Z,1676311219.959 [DataOverHttps](INFO): SBD MOMSN=17465064
2023-02-13T18:00:37.016Z,1676311237.016 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230213T153905/Express0086.lzma
2023-02-13T18:00:38.019Z,1676311238.019 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0086.lzma.bak
2023-02-13T18:00:38.019Z,1676311238.019 [DataOverHttps](INFO): SBD MOMSN=17465067
2023-02-13T18:00:39.070Z,1676311239.070 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:00:39.070Z,1676311239.070 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:00:39.070Z,1676311239.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:00:40.282Z,1676311240.282 [NAL9602](INFO): SBD MO Status=2, MOMSN=22035, MT Status=2, MTMSN=0
2023-02-13T18:00:40.282Z,1676311240.282 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T18:01:01.700Z,1676311261.700 [NAL9602](INFO): SBD MO Status=0, MOMSN=22035, MT Status=0, MTMSN=0
2023-02-13T18:01:01.700Z,1676311261.700 [NAL9602](INFO): No messages in MT queue
2023-02-13T18:01:32.390Z,1676311292.390 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:02:00.266Z,1676311320.266 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-02-13T18:02:00.268Z,1676311320.268 [BPC1](INFO): Received data from all battery sticks.
2023-02-13T18:05:39.708Z,1676311539.708 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:05:39.708Z,1676311539.708 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:05:39.708Z,1676311539.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:05:39.708Z,1676311539.708 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:05:40.094Z,1676311540.094 [Default:CheckIn:D] Stopped
2023-02-13T18:05:40.094Z,1676311540.094 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:05:40.494Z,1676311540.494 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.643050 min 
2023-02-13T18:05:40.494Z,1676311540.494 [Default:CheckIn:E] Stopped
2023-02-13T18:05:40.494Z,1676311540.494 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:05:40.495Z,1676311540.495 [Default:CheckIn] Stopped
2023-02-13T18:05:40.495Z,1676311540.495 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:05:40.495Z,1676311540.495 [Default:CheckIn](INFO): Running loop #25
2023-02-13T18:05:40.495Z,1676311540.495 [Default:CheckIn] Running Loop=25
2023-02-13T18:05:40.495Z,1676311540.495 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:05:40.495Z,1676311540.495 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:05:42.505Z,1676311542.505 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180541.00,A,3648.38053,N,12147.11774,W,5.734,6.80,130223,,,D*7B
2023-02-13T18:05:42.508Z,1676311542.508 [NAL9602](INFO): GPS fix at 20230213T180541: (36.806342, -121.785296)
2023-02-13T18:05:42.547Z,1676311542.547 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:05:42.547Z,1676311542.547 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:05:50.176Z,1676311550.176 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230213T153905/Courier0088.lzma
2023-02-13T18:05:51.179Z,1676311551.179 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0088.lzma.bak
2023-02-13T18:05:51.179Z,1676311551.179 [DataOverHttps](INFO): SBD MOMSN=17465088
2023-02-13T18:05:58.284Z,1676311558.284 [NAL9602](INFO): SBD MO Status=0, MOMSN=22036, MT Status=0, MTMSN=0
2023-02-13T18:05:58.284Z,1676311558.284 [NAL9602](INFO): No messages in MT queue
2023-02-13T18:06:07.528Z,1676311567.528 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230213T153905/Express0089.lzma
2023-02-13T18:06:08.531Z,1676311568.531 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0089.lzma.bak
2023-02-13T18:06:08.531Z,1676311568.531 [DataOverHttps](INFO): SBD MOMSN=17465091
2023-02-13T18:06:09.628Z,1676311569.628 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:06:09.628Z,1676311569.628 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:06:09.628Z,1676311569.628 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:06:29.035Z,1676311589.035 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:11:10.330Z,1676311870.330 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:11:10.330Z,1676311870.330 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:11:10.330Z,1676311870.330 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:11:10.330Z,1676311870.330 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:11:10.732Z,1676311870.732 [Default:CheckIn:D] Stopped
2023-02-13T18:11:10.732Z,1676311870.732 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:11:11.143Z,1676311871.143 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.153695 min 
2023-02-13T18:11:11.143Z,1676311871.143 [Default:CheckIn:E] Stopped
2023-02-13T18:11:11.143Z,1676311871.143 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:11:11.143Z,1676311871.143 [Default:CheckIn] Stopped
2023-02-13T18:11:11.143Z,1676311871.143 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:11:11.143Z,1676311871.143 [Default:CheckIn](INFO): Running loop #26
2023-02-13T18:11:11.143Z,1676311871.143 [Default:CheckIn] Running Loop=26
2023-02-13T18:11:11.143Z,1676311871.143 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:11:11.143Z,1676311871.143 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:11:13.148Z,1676311873.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181112.00,A,3648.34844,N,12147.74285,W,10.536,280.18,130223,,,D*4B
2023-02-13T18:11:13.151Z,1676311873.151 [NAL9602](INFO): GPS fix at 20230213T181112: (36.805807, -121.795714)
2023-02-13T18:11:13.187Z,1676311873.187 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:11:13.187Z,1676311873.187 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:11:20.692Z,1676311880.692 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230213T153905/Courier0091.lzma
2023-02-13T18:11:21.695Z,1676311881.695 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Courier0091.lzma.bak
2023-02-13T18:11:21.695Z,1676311881.695 [DataOverHttps](INFO): SBD MOMSN=17465102
2023-02-13T18:11:27.692Z,1676311887.692 [NAL9602](INFO): SBD MO Status=0, MOMSN=22037, MT Status=0, MTMSN=0
2023-02-13T18:11:27.692Z,1676311887.692 [NAL9602](INFO): No messages in MT queue
2023-02-13T18:11:38.181Z,1676311898.181 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230213T153905/Express0092.lzma
2023-02-13T18:11:39.177Z,1676311899.177 [DataOverHttps](INFO): Moved sent file to Logs/20230213T153905/Express0092.lzma.bak
2023-02-13T18:11:39.177Z,1676311899.177 [DataOverHttps](INFO): SBD MOMSN=17465105
2023-02-13T18:11:40.232Z,1676311900.232 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:11:40.232Z,1676311900.232 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:11:40.233Z,1676311900.233 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:11:58.398Z,1676311918.398 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:12:46.201Z,1676311966.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:12:51.222Z,1676311971.222 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.267761
2023-02-13T18:13:56.498Z,1676312036.498 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:15:06.777Z,1676312106.777 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:15:41.925Z,1676312141.925 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:16:17.066Z,1676312177.066 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:16:40.822Z,1676312200.822 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:16:40.822Z,1676312200.822 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:16:40.823Z,1676312200.823 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:16:40.823Z,1676312200.823 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:16:41.199Z,1676312201.199 [Default:CheckIn:D] Stopped
2023-02-13T18:16:41.199Z,1676312201.199 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:16:41.630Z,1676312201.630 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.661475 min 
2023-02-13T18:16:41.630Z,1676312201.630 [Default:CheckIn:E] Stopped
2023-02-13T18:16:41.630Z,1676312201.630 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:16:41.630Z,1676312201.630 [Default:CheckIn] Stopped
2023-02-13T18:16:41.630Z,1676312201.630 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:16:41.630Z,1676312201.630 [Default:CheckIn](INFO): Running loop #27
2023-02-13T18:16:41.630Z,1676312201.630 [Default:CheckIn] Running Loop=27
2023-02-13T18:16:41.630Z,1676312201.630 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:16:41.631Z,1676312201.631 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:16:43.616Z,1676312203.616 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181642.00,A,3648.53941,N,12149.06588,W,11.294,246.12,130223,,,D*43
2023-02-13T18:16:43.619Z,1676312203.619 [NAL9602](INFO): GPS fix at 20230213T181642: (36.808990, -121.817765)
2023-02-13T18:16:43.672Z,1676312203.672 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:16:43.672Z,1676312203.672 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:16:52.205Z,1676312212.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:17:12.824Z,1676312232.824 [NAL9602](INFO): SBD MO Status=2, MOMSN=22038, MT Status=2, MTMSN=0
2023-02-13T18:17:12.824Z,1676312232.824 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T18:17:24.224Z,1676312244.224 [NAL9602](INFO): SBD MO Status=1, MOMSN=22038, MT Status=0, MTMSN=0
2023-02-13T18:17:24.273Z,1676312244.273 [NAL9602](INFO): Sent 73 bytes from file Logs/20230213T153905/Courier0094.lzma
2023-02-13T18:17:24.273Z,1676312244.273 [NAL9602](INFO): Packets left to send: 0
2023-02-13T18:17:27.361Z,1676312247.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:17:33.179Z,1676312253.179 [NAL9602](INFO): SBD MO Status=1, MOMSN=22039, MT Status=0, MTMSN=0
2023-02-13T18:17:33.233Z,1676312253.233 [NAL9602](INFO): Sent 141 bytes from file Logs/20230213T153905/Express0095.lzma
2023-02-13T18:17:33.234Z,1676312253.234 [NAL9602](INFO): Packets left to send: 0
2023-02-13T18:17:43.280Z,1676312263.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=22040, MT Status=0, MTMSN=0
2023-02-13T18:17:43.374Z,1676312263.374 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:17:43.374Z,1676312263.374 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:17:43.374Z,1676312263.374 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:18:02.501Z,1676312282.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:18:13.986Z,1676312293.986 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:18:37.641Z,1676312317.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:19:12.781Z,1676312352.781 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:19:47.921Z,1676312387.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:20:23.061Z,1676312423.061 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:20:58.202Z,1676312458.202 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:21:33.345Z,1676312493.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:22:08.509Z,1676312528.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:22:43.649Z,1676312563.649 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:22:43.885Z,1676312563.885 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:22:43.885Z,1676312563.885 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:22:43.885Z,1676312563.885 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:22:43.885Z,1676312563.885 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:22:44.278Z,1676312564.278 [Default:CheckIn:D] Stopped
2023-02-13T18:22:44.278Z,1676312564.278 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:22:44.677Z,1676312564.677 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.712793 min 
2023-02-13T18:22:44.693Z,1676312564.693 [Default:CheckIn:E] Stopped
2023-02-13T18:22:44.693Z,1676312564.693 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:22:44.693Z,1676312564.693 [Default:CheckIn] Stopped
2023-02-13T18:22:44.693Z,1676312564.693 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:22:44.693Z,1676312564.693 [Default:CheckIn](INFO): Running loop #28
2023-02-13T18:22:44.694Z,1676312564.694 [Default:CheckIn] Running Loop=28
2023-02-13T18:22:44.694Z,1676312564.694 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:22:44.694Z,1676312564.694 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:22:46.685Z,1676312566.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182245.00,A,3648.25057,N,12149.75388,W,0.214,305.80,130223,,,D*7B
2023-02-13T18:22:46.687Z,1676312566.687 [NAL9602](INFO): GPS fix at 20230213T182245: (36.804176, -121.829231)
2023-02-13T18:22:46.698Z,1676312566.698 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:22:46.698Z,1676312566.698 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:23:17.856Z,1676312597.856 [NAL9602](INFO): SBD MO Status=2, MOMSN=22041, MT Status=2, MTMSN=0
2023-02-13T18:23:17.856Z,1676312597.856 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T18:23:18.789Z,1676312598.789 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:23:43.000Z,1676312623.000 [NAL9602](INFO): SBD MO Status=2, MOMSN=22041, MT Status=2, MTMSN=0
2023-02-13T18:23:43.001Z,1676312623.001 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-13T18:23:52.755Z,1676312632.755 [NAL9602](INFO): SBD MO Status=1, MOMSN=22041, MT Status=0, MTMSN=0
2023-02-13T18:23:52.821Z,1676312632.821 [NAL9602](INFO): Sent 73 bytes from file Logs/20230213T153905/Courier0097.lzma
2023-02-13T18:23:52.822Z,1676312632.822 [NAL9602](INFO): Packets left to send: 0
2023-02-13T18:23:53.941Z,1676312633.941 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:24:01.748Z,1676312641.748 [NAL9602](INFO): SBD MO Status=1, MOMSN=22042, MT Status=0, MTMSN=0
2023-02-13T18:24:01.813Z,1676312641.813 [NAL9602](INFO): Sent 139 bytes from file Logs/20230213T153905/Express0098.lzma
2023-02-13T18:24:01.813Z,1676312641.813 [NAL9602](INFO): Packets left to send: 0
2023-02-13T18:24:10.638Z,1676312650.638 [NAL9602](INFO): SBD MO Status=0, MOMSN=22043, MT Status=0, MTMSN=0
2023-02-13T18:24:10.734Z,1676312650.734 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:24:10.734Z,1676312650.734 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:24:10.734Z,1676312650.734 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:24:29.081Z,1676312669.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:24:41.336Z,1676312681.336 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:25:04.229Z,1676312704.229 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:25:39.369Z,1676312739.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:26:14.509Z,1676312774.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:26:49.653Z,1676312809.653 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:27:24.797Z,1676312844.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:27:59.938Z,1676312879.938 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:28:35.077Z,1676312915.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:29:10.217Z,1676312950.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:29:11.215Z,1676312951.215 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:29:11.215Z,1676312951.215 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:29:11.215Z,1676312951.215 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:29:11.215Z,1676312951.215 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:29:11.644Z,1676312951.644 [Default:CheckIn:D] Stopped
2023-02-13T18:29:11.644Z,1676312951.644 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:29:12.042Z,1676312952.042 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.168880 min 
2023-02-13T18:29:12.042Z,1676312952.042 [Default:CheckIn:E] Stopped
2023-02-13T18:29:12.043Z,1676312952.043 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:29:12.043Z,1676312952.043 [Default:CheckIn] Stopped
2023-02-13T18:29:12.043Z,1676312952.043 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:29:12.043Z,1676312952.043 [Default:CheckIn](INFO): Running loop #29
2023-02-13T18:29:12.043Z,1676312952.043 [Default:CheckIn] Running Loop=29
2023-02-13T18:29:12.043Z,1676312952.043 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:29:12.043Z,1676312952.043 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:29:14.035Z,1676312954.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182913.00,A,3648.28479,N,12149.73688,W,1.594,81.57,130223,,,D*4E
2023-02-13T18:29:14.037Z,1676312954.037 [NAL9602](INFO): GPS fix at 20230213T182913: (36.804747, -121.828948)
2023-02-13T18:29:14.082Z,1676312954.082 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:29:14.082Z,1676312954.082 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:29:25.027Z,1676312965.027 [NAL9602](INFO): SBD MO Status=1, MOMSN=22044, MT Status=0, MTMSN=0
2023-02-13T18:29:25.081Z,1676312965.081 [NAL9602](INFO): Sent 73 bytes from file Logs/20230213T153905/Courier0100.lzma
2023-02-13T18:29:25.081Z,1676312965.081 [NAL9602](INFO): Packets left to send: 0
2023-02-13T18:29:45.373Z,1676312985.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:29:53.881Z,1676312993.881 [NAL9602](INFO): SBD MO Status=1, MOMSN=22045, MT Status=0, MTMSN=0
2023-02-13T18:29:53.933Z,1676312993.933 [NAL9602](INFO): Sent 130 bytes from file Logs/20230213T153905/Express0101.lzma
2023-02-13T18:29:53.933Z,1676312993.933 [NAL9602](INFO): Packets left to send: 0
2023-02-13T18:30:06.396Z,1676313006.396 [NAL9602](INFO): SBD MO Status=0, MOMSN=22046, MT Status=0, MTMSN=0
2023-02-13T18:30:06.497Z,1676313006.497 [Default:CheckIn:Read_Iridium] Stopped
2023-02-13T18:30:06.497Z,1676313006.497 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-13T18:30:06.498Z,1676313006.498 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-13T18:30:20.534Z,1676313020.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:30:37.098Z,1676313037.098 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-13T18:30:55.661Z,1676313055.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:31:30.801Z,1676313090.801 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:32:05.941Z,1676313125.941 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:32:41.109Z,1676313161.109 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:33:16.249Z,1676313196.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:33:51.397Z,1676313231.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:34:26.553Z,1676313266.553 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:35:01.701Z,1676313301.701 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-02-13T18:35:06.982Z,1676313306.982 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-13T18:35:06.982Z,1676313306.982 [Default:CheckIn:C.Wait] Stopped
2023-02-13T18:35:06.982Z,1676313306.982 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-13T18:35:06.982Z,1676313306.982 [Default:CheckIn:D] Running Loop=1
2023-02-13T18:35:07.391Z,1676313307.391 [Default:CheckIn:D] Stopped
2023-02-13T18:35:07.391Z,1676313307.391 [Default:CheckIn:E] Running Loop=1
2023-02-13T18:35:07.811Z,1676313307.811 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 172.097998 min 
2023-02-13T18:35:07.811Z,1676313307.811 [Default:CheckIn:E] Stopped
2023-02-13T18:35:07.811Z,1676313307.811 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-13T18:35:07.811Z,1676313307.811 [Default:CheckIn] Stopped
2023-02-13T18:35:07.811Z,1676313307.811 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-13T18:35:07.811Z,1676313307.811 [Default:CheckIn](INFO): Running loop #30
2023-02-13T18:35:07.811Z,1676313307.811 [Default:CheckIn] Running Loop=30
2023-02-13T18:35:07.812Z,1676313307.812 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-13T18:35:07.812Z,1676313307.812 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-13T18:35:09.797Z,1676313309.797 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183509.00,A,3648.30758,N,12149.72558,W,0.175,57.61,130223,,,D*4A
2023-02-13T18:35:09.799Z,1676313309.799 [NAL9602](INFO): GPS fix at 20230213T183509: (36.805126, -121.828760)
2023-02-13T18:35:09.846Z,1676313309.846 [Default:CheckIn:Read_GPS] Stopped
2023-02-13T18:35:09.846Z,1676313309.846 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-13T18:35:35.332Z,1676313335.332 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22047, MT Status=1, MTMSN=1169
2023-02-13T18:35:35.389Z,1676313335.389 [NAL9602](INFO): Sent 74 bytes from file Logs/20230213T153905/Courier0103.lzma
2023-02-13T18:35:35.389Z,1676313335.389 [NAL9602](INFO): Packets left to send: 0
2023-02-13T18:35:35.822Z,1676313335.822 [NAL9602](INFO): Received command: restart logs