2023-09-19T20:46:19.378Z,1695156379.378 [Supervisor](DEBUG): Initializing supervisor.
2023-09-19T20:46:19.382Z,1695156379.382 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-19T20:46:19.383Z,1695156379.383 [SyncHandler](INFO): Protected caller Thread ID is 1826
2023-09-19T20:46:19.384Z,1695156379.384 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-19T20:46:19.384Z,1695156379.384 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-19T20:46:19.385Z,1695156379.385 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1827
2023-09-19T20:46:19.389Z,1695156379.389 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-19T20:46:19.407Z,1695156379.407 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-19T20:46:19.408Z,1695156379.408 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-19T20:46:19.408Z,1695156379.408 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1828
2023-09-19T20:46:19.413Z,1695156379.413 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-19T20:46:19.414Z,1695156379.414 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-19T20:46:19.414Z,1695156379.414 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1829
2023-09-19T20:46:19.416Z,1695156379.416 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-19T20:46:19.417Z,1695156379.417 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-19T20:46:19.417Z,1695156379.417 [logger ThreadHandler](INFO): Protected caller Thread ID is 1830
2023-09-19T20:46:19.421Z,1695156379.421 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-19T20:46:19.422Z,1695156379.422 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-19T20:46:19.423Z,1695156379.423 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-19T20:46:19.639Z,1695156379.639 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-19T20:46:19.640Z,1695156379.640 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-19T20:46:19.748Z,1695156379.748 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-19T20:46:19.749Z,1695156379.749 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-19T20:46:19.846Z,1695156379.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-19T20:46:19.846Z,1695156379.846 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-19T20:46:20.337Z,1695156380.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-19T20:46:20.338Z,1695156380.338 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-19T20:46:20.696Z,1695156380.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-19T20:46:20.697Z,1695156380.697 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-19T20:46:21.039Z,1695156381.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-19T20:46:21.040Z,1695156381.040 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-19T20:46:21.235Z,1695156381.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-19T20:46:21.236Z,1695156381.236 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-19T20:46:21.317Z,1695156381.317 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-19T20:46:21.399Z,1695156381.399 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-19T20:46:21.815Z,1695156381.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-19T20:46:21.815Z,1695156381.815 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-19T20:46:22.104Z,1695156382.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-19T20:46:22.104Z,1695156382.104 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-19T20:46:22.658Z,1695156382.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-19T20:46:22.658Z,1695156382.658 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-19T20:46:22.887Z,1695156382.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-19T20:46:22.887Z,1695156382.887 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-19T20:46:23.131Z,1695156383.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-19T20:46:23.131Z,1695156383.131 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-19T20:46:23.277Z,1695156383.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-19T20:46:23.277Z,1695156383.277 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-19T20:46:24.611Z,1695156384.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-19T20:46:24.613Z,1695156384.613 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2023-09-19T20:46:24.613Z,1695156384.613 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2023-09-19T20:46:24.702Z,1695156384.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2023-09-19T20:46:24.837Z,1695156384.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2023-09-19T20:46:25.112Z,1695156385.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-19T20:46:25.112Z,1695156385.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2023-09-19T20:46:25.241Z,1695156385.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2023-09-19T20:46:25.344Z,1695156385.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2023-09-19T20:46:25.440Z,1695156385.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2023-09-19T20:46:25.551Z,1695156385.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2023-09-19T20:46:25.648Z,1695156385.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2023-09-19T20:46:25.737Z,1695156385.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2023-09-19T20:46:25.840Z,1695156385.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2023-09-19T20:46:26.011Z,1695156386.011 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2023-09-19T20:46:26.011Z,1695156386.011 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-19T20:46:26.029Z,1695156386.029 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-19T20:46:26.079Z,1695156386.079 [DepthRateCalculator] Loaded
2023-09-19T20:46:26.080Z,1695156386.080 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-19T20:46:26.085Z,1695156386.085 [PitchRateCalculator] Loaded
2023-09-19T20:46:26.086Z,1695156386.086 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-19T20:46:26.096Z,1695156386.096 [SpeedCalculator] Loaded
2023-09-19T20:46:26.097Z,1695156386.097 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-19T20:46:26.102Z,1695156386.102 [YawRateCalculator] Loaded
2023-09-19T20:46:26.102Z,1695156386.102 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-19T20:46:26.124Z,1695156386.124 [ElevatorOffsetCalculator] Loaded
2023-09-19T20:46:26.124Z,1695156386.124 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-19T20:46:26.125Z,1695156386.125 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-19T20:46:26.125Z,1695156386.125 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-19T20:46:26.151Z,1695156386.151 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-19T20:46:26.152Z,1695156386.152 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-19T20:46:26.162Z,1695156386.162 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-19T20:46:26.163Z,1695156386.163 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-19T20:46:26.604Z,1695156386.604 [AHRS_M2] Loaded
2023-09-19T20:46:26.605Z,1695156386.605 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-19T20:46:26.640Z,1695156386.640 [BackseatComponent] Loaded
2023-09-19T20:46:26.640Z,1695156386.640 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-19T20:46:26.641Z,1695156386.641 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0
2023-09-19T20:46:26.641Z,1695156386.641 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1912
2023-09-19T20:46:26.644Z,1695156386.644 [LcmUniversalReporter] Loaded
2023-09-19T20:46:26.644Z,1695156386.644 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-19T20:46:27.449Z,1695156387.449 [BPC1] Loaded
2023-09-19T20:46:27.449Z,1695156387.449 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-19T20:46:27.522Z,1695156387.522 [DataOverHttps] Loaded
2023-09-19T20:46:27.523Z,1695156387.523 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-19T20:46:27.524Z,1695156387.524 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0
2023-09-19T20:46:27.524Z,1695156387.524 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1913
2023-09-19T20:46:27.545Z,1695156387.545 [Depth_Keller] Loaded
2023-09-19T20:46:27.545Z,1695156387.545 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-19T20:46:27.550Z,1695156387.550 [DropWeight] Loaded
2023-09-19T20:46:27.550Z,1695156387.550 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-09-19T20:46:27.569Z,1695156387.569 [MultiRay] Loaded
2023-09-19T20:46:27.569Z,1695156387.569 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-09-19T20:46:27.787Z,1695156387.787 [NAL9602] Loaded
2023-09-19T20:46:27.788Z,1695156387.788 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-19T20:46:27.818Z,1695156387.818 [Onboard] Loaded
2023-09-19T20:46:27.819Z,1695156387.819 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-19T20:46:27.820Z,1695156387.820 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0
2023-09-19T20:46:27.820Z,1695156387.820 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1914
2023-09-19T20:46:27.826Z,1695156387.826 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2023-09-19T20:46:27.837Z,1695156387.837 [PowerOnly] Loaded
2023-09-19T20:46:27.837Z,1695156387.837 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-09-19T20:46:27.849Z,1695156387.849 [Power24vConverter] Loaded
2023-09-19T20:46:27.850Z,1695156387.850 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-19T20:46:27.864Z,1695156387.864 [Radio_Surface] Loaded
2023-09-19T20:46:27.864Z,1695156387.864 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-19T20:46:27.865Z,1695156387.865 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0
2023-09-19T20:46:27.865Z,1695156387.865 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1915
2023-09-19T20:46:27.867Z,1695156387.867 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-19T20:46:27.867Z,1695156387.867 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-19T20:46:27.978Z,1695156387.978 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-09-19T20:46:27.978Z,1695156387.978 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-09-19T20:46:28.021Z,1695156388.021 [DeadReckonUsingSpeedCalculator] Loaded
2023-09-19T20:46:28.021Z,1695156388.021 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-09-19T20:46:28.035Z,1695156388.035 [NavChart] Loaded
2023-09-19T20:46:28.036Z,1695156388.036 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-19T20:46:28.041Z,1695156388.041 [UniversalFixResidualReporter] Loaded
2023-09-19T20:46:28.042Z,1695156388.042 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-19T20:46:28.042Z,1695156388.042 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-19T20:46:28.043Z,1695156388.043 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-19T20:46:28.182Z,1695156388.182 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-19T20:46:28.197Z,1695156388.197 [SBIT] Loaded
2023-09-19T20:46:28.197Z,1695156388.197 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-19T20:46:28.200Z,1695156388.200 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-19T20:46:28.213Z,1695156388.213 [IBIT] Loaded
2023-09-19T20:46:28.213Z,1695156388.213 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-19T20:46:28.219Z,1695156388.219 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-19T20:46:28.325Z,1695156388.325 [CBIT] Loaded
2023-09-19T20:46:28.325Z,1695156388.325 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-19T20:46:28.325Z,1695156388.325 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-19T20:46:28.326Z,1695156388.326 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-19T20:46:28.497Z,1695156388.497 [CTD_Seabird] Loaded
2023-09-19T20:46:28.497Z,1695156388.497 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-19T20:46:28.498Z,1695156388.498 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0
2023-09-19T20:46:28.499Z,1695156388.499 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1916
2023-09-19T20:46:28.519Z,1695156388.519 [PAR_Licor] Loaded
2023-09-19T20:46:28.519Z,1695156388.519 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-19T20:46:28.561Z,1695156388.561 [WetLabsBB2FL] Loaded
2023-09-19T20:46:28.561Z,1695156388.561 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-19T20:46:28.562Z,1695156388.562 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2023-09-19T20:46:28.563Z,1695156388.563 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1917
2023-09-19T20:46:28.564Z,1695156388.564 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-19T20:46:28.564Z,1695156388.564 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-19T20:46:28.697Z,1695156388.697 [BuoyancyServo] Loaded
2023-09-19T20:46:28.697Z,1695156388.697 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-19T20:46:28.719Z,1695156388.719 [ElevatorServo] Loaded
2023-09-19T20:46:28.719Z,1695156388.719 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-19T20:46:28.741Z,1695156388.741 [MassServo] Loaded
2023-09-19T20:46:28.742Z,1695156388.742 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-19T20:46:28.763Z,1695156388.763 [RudderServo] Loaded
2023-09-19T20:46:28.763Z,1695156388.763 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-19T20:46:28.779Z,1695156388.779 [ThrusterHE] Loaded
2023-09-19T20:46:28.779Z,1695156388.779 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-19T20:46:28.780Z,1695156388.780 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-19T20:46:28.780Z,1695156388.780 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-19T20:46:28.830Z,1695156388.830 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-19T20:46:28.882Z,1695156388.882 [VerticalControl] Loaded
2023-09-19T20:46:28.882Z,1695156388.882 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-19T20:46:28.885Z,1695156388.885 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-19T20:46:28.923Z,1695156388.923 [HorizontalControl] Loaded
2023-09-19T20:46:28.923Z,1695156388.923 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-19T20:46:28.926Z,1695156388.926 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-19T20:46:28.927Z,1695156388.927 [SpeedControl] Loaded
2023-09-19T20:46:28.928Z,1695156388.928 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-19T20:46:28.931Z,1695156388.931 [LoopControl](DEBUG): Construct LoopControl.
2023-09-19T20:46:28.931Z,1695156388.931 [LoopControl] Loaded
2023-09-19T20:46:28.931Z,1695156388.931 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-19T20:46:28.932Z,1695156388.932 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-19T20:46:28.932Z,1695156388.932 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-19T20:46:29.036Z,1695156389.036 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-19T20:46:29.037Z,1695156389.037 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-19T20:46:29.185Z,1695156389.185 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-19T20:46:29.185Z,1695156389.185 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-19T20:46:29.865Z,1695156389.865 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-19T20:46:29.865Z,1695156389.865 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-19T20:46:29.937Z,1695156389.937 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC
2023-09-19T20:46:29.937Z,1695156389.937 [StratificationFrontDetector](DEBUG): (re)initializing
2023-09-19T20:46:29.938Z,1695156389.938 [StratificationFrontDetector] Loaded
2023-09-19T20:46:29.938Z,1695156389.938 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2023-09-19T20:46:29.938Z,1695156389.938 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-19T20:46:29.945Z,1695156389.945 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-19T20:46:29.948Z,1695156389.948 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-19T20:46:29.959Z,1695156389.959 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-19T20:46:29.960Z,1695156389.960 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-09-19T20:46:29.960Z,1695156389.960 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1918
2023-09-19T20:46:29.965Z,1695156389.965 [Supervisor](INFO): Main Thread ID is 829
2023-09-19T20:46:29.965Z,1695156389.965 [Supervisor](DEBUG): Running supervisor.
2023-09-19T20:46:29.966Z,1695156389.966 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1919
2023-09-19T20:46:29.966Z,1695156389.966 [CommandExec](INFO): Initializing the command executive.
2023-09-19T20:46:29.968Z,1695156389.968 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1920
2023-09-19T20:46:29.971Z,1695156389.971 [controlThread ThreadHandler](INFO): Handler Thread ID is 1921
2023-09-19T20:46:29.972Z,1695156389.972 [controlThread](DEBUG): Initializing ControlThread
2023-09-19T20:46:29.972Z,1695156389.972 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-19T20:46:29.973Z,1695156389.973 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-19T20:46:29.973Z,1695156389.973 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-19T20:46:29.974Z,1695156389.974 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-19T20:46:29.974Z,1695156389.974 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-19T20:46:29.981Z,1695156389.981 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:46:29.982Z,1695156389.982 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-19T20:46:29.982Z,1695156389.982 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-19T20:46:29.983Z,1695156389.983 [SBIT](INFO): Initialize SBIT Component.
2023-09-19T20:46:29.983Z,1695156389.983 [SBIT](IMPORTANT): git: 2023-09-18-3-g7ee55e0de
2023-09-19T20:46:29.983Z,1695156389.983 [SBIT](INFO): git hash: 7ee55e0ded82548f5c252ba0d9a41930617440e4
2023-09-19T20:46:29.984Z,1695156389.984 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-19T20:46:29.985Z,1695156389.985 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-09-19T20:46:29.986Z,1695156389.986 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2023-09-19T20:46:29.986Z,1695156389.986 [IBIT](INFO): Initialize IBIT Component.
2023-09-19T20:46:29.987Z,1695156389.987 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-19T20:46:29.988Z,1695156389.988 [logger ThreadHandler](INFO): Handler Thread ID is 1922
2023-09-19T20:46:29.998Z,1695156389.998 [CBIT](DEBUG): Initialized mux pins.
2023-09-19T20:46:29.998Z,1695156389.998 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-19T20:46:30.007Z,1695156390.007 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1923
2023-09-19T20:46:30.019Z,1695156390.019 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1924
2023-09-19T20:46:30.019Z,1695156390.019 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-19T20:46:30.022Z,1695156390.022 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-19T20:46:30.023Z,1695156390.023 [CBIT](DEBUG): Initializing heartbeat.
2023-09-19T20:46:30.031Z,1695156390.031 [Onboard ThreadHandler](INFO): Handler Thread ID is 1925
2023-09-19T20:46:30.060Z,1695156390.060 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1926
2023-09-19T20:46:30.071Z,1695156390.071 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1927
2023-09-19T20:46:30.072Z,1695156390.072 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-19T20:46:30.076Z,1695156390.076 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1929
2023-09-19T20:46:30.079Z,1695156390.079 [WetLabsBB2FL](INFO): Powering up
2023-09-19T20:46:30.080Z,1695156390.080 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1931
2023-09-19T20:46:30.082Z,1695156390.082 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-09-19T20:46:30.082Z,1695156390.082 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-09-19T20:46:30.083Z,1695156390.083 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-09-19T20:46:30.083Z,1695156390.083 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-09-19T20:46:30.083Z,1695156390.083 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-09-19T20:46:30.083Z,1695156390.083 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-09-19T20:46:30.083Z,1695156390.083 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-09-19T20:46:30.083Z,1695156390.083 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-09-19T20:46:30.094Z,1695156390.094 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-19T20:46:30.094Z,1695156390.094 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-19T20:46:30.134Z,1695156390.134 [CBIT](DEBUG): Backplane powered.
2023-09-19T20:46:30.140Z,1695156390.140 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-19T20:46:30.141Z,1695156390.141 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-19T20:46:30.142Z,1695156390.142 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-19T20:46:30.143Z,1695156390.143 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-19T20:46:30.144Z,1695156390.144 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-19T20:46:30.161Z,1695156390.161 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-19T20:46:30.211Z,1695156390.211 [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-09-19T20:46:30.212Z,1695156390.212 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-19T20:46:30.261Z,1695156390.261 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-19T20:46:30.262Z,1695156390.262 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-19T20:46:30.264Z,1695156390.264 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-19T20:46:30.300Z,1695156390.300 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-19T20:46:30.320Z,1695156390.320 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-19T20:46:30.325Z,1695156390.325 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-19T20:46:30.329Z,1695156390.329 [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-09-19T20:46:30.351Z,1695156390.351 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-09-19T20:46:30.368Z,1695156390.368 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-19T20:46:30.421Z,1695156390.421 [MultiRay](INFO): Powering up MultiRay Lights
2023-09-19T20:46:30.455Z,1695156390.455 [Power24vConverter](INFO): Powering up.
2023-09-19T20:46:30.462Z,1695156390.462 [Radio_Surface](INFO): Powering up
2023-09-19T20:46:30.493Z,1695156390.493 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-19T20:46:30.498Z,1695156390.498 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-19T20:46:30.499Z,1695156390.499 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-19T20:46:30.506Z,1695156390.506 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-19T20:46:30.507Z,1695156390.507 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-19T20:46:30.514Z,1695156390.514 [MassServo](DEBUG): Initializing MassServo.
2023-09-19T20:46:30.515Z,1695156390.515 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-19T20:46:30.522Z,1695156390.522 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-19T20:46:30.523Z,1695156390.523 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-19T20:46:30.530Z,1695156390.530 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-19T20:46:32.690Z,1695156392.690 [WetLabsBB2FL](INFO): Powering down
2023-09-19T20:46:37.102Z,1695156397.102 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-09-19T20:46:57.897Z,1695156417.897 [NAL9602](INFO): Powering up NAL9602
2023-09-19T20:47:08.804Z,1695156428.804 [NAL9602](INFO): NAL9602 initialized
2023-09-19T20:47:29.423Z,1695156449.423 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-19T20:47:29.427Z,1695156449.427 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-19T20:47:40.371Z,1695156460.371 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006484
CHAN A1 (24V): 0.000255
CHAN A2 (12V): -0.005543
CHAN A3 (5V): -0.003078
CHAN B0 (3.3V): -0.001170
CHAN B1 (3.15aV): -0.001696
CHAN B2 (3.15bV): -0.001222
CHAN B3 (GND): -0.000568
OPEN: 0.004376
Full Scale: +/- 1 mA
2023-09-19T20:48:23.558Z,1695156503.558 [SBIT](IMPORTANT): SBIT PASSED
2023-09-19T20:48:23.559Z,1695156503.559 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-19T20:48:23.559Z,1695156503.559 [SBIT](IMPORTANT): BackseatComponent.missionCritical=1 bool;

2023-09-19T20:48:23.560Z,1695156503.560 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour;

2023-09-19T20:48:23.560Z,1695156503.560 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute;

2023-09-19T20:48:23.560Z,1695156503.560 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-09-19T20:48:23.560Z,1695156503.560 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;

2023-09-19T20:48:23.560Z,1695156503.560 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-09-19T20:48:23.560Z,1695156503.560 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-09-19T20:48:23.560Z,1695156503.560 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte;

2023-09-19T20:48:23.560Z,1695156503.560 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second;

2023-09-19T20:48:23.561Z,1695156503.561 [SBIT](IMPORTANT): Express none _.ayeris_particle_shape;

2023-09-19T20:48:23.561Z,1695156503.561 [SBIT](IMPORTANT): Express none _.ayeris_particle_slope;

2023-09-19T20:48:23.561Z,1695156503.561 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2023-09-19T20:48:23.561Z,1695156503.561 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2023-09-19T20:48:23.561Z,1695156503.561 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2023-09-19T20:48:23.561Z,1695156503.561 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;

2023-09-19T20:48:23.561Z,1695156503.561 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=200 cubic_centimeter;

2023-09-19T20:48:23.561Z,1695156503.561 [SBIT](IMPORTANT): VerticalControl.massDeadband=1 millimeter;

2023-09-19T20:48:23.561Z,1695156503.561 [SBIT](IMPORTANT): VerticalControl.massDefault=1 centimeter;

2023-09-19T20:48:23.562Z,1695156503.562 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-09-19T20:48:23.562Z,1695156503.562 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-09-19T20:48:23.960Z,1695156503.960 [MissionManager](IMPORTANT): Started mission Startup
2023-09-19T20:48:23.960Z,1695156503.960 [Startup] Running Loop=1
2023-09-19T20:48:23.961Z,1695156503.961 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-19T20:48:23.961Z,1695156503.961 [Startup:A.GoToSurface] Running Loop=1
2023-09-19T20:48:23.961Z,1695156503.961 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-19T20:48:23.961Z,1695156503.961 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-19T20:48:23.962Z,1695156503.962 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-19T20:48:23.962Z,1695156503.962 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-19T20:48:23.963Z,1695156503.963 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-19T20:48:23.963Z,1695156503.963 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-19T20:48:23.965Z,1695156503.965 [Startup:StartupSatComms] Running Loop=1
2023-09-19T20:48:23.965Z,1695156503.965 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-19T20:48:23.965Z,1695156503.965 [Startup:StartupSatComms:A] Running Loop=1
2023-09-19T20:48:24.374Z,1695156504.374 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-19T20:49:24.153Z,1695156564.153 [Startup:StartupSatComms:A](INFO): Timed out from 2023-09-19T20:48:23.0Z
2023-09-19T20:49:24.153Z,1695156564.153 [Startup:StartupSatComms:A] Stopped
2023-09-19T20:49:24.153Z,1695156564.153 [Startup:StartupSatComms:B] Running Loop=1
2023-09-19T20:49:24.556Z,1695156564.556 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-19T20:49:30.232Z,1695156570.232 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-09-19T20:49:30.232Z,1695156570.232 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:49:30.233Z,1695156570.233 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-09-19T20:49:30.233Z,1695156570.233 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:49:30.243Z,1695156570.243 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:49:30.244Z,1695156570.244 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:49:30.636Z,1695156570.636 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:49:30.636Z,1695156570.636 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-09-19T20:49:30.636Z,1695156570.636 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:49:30.636Z,1695156570.636 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-09-19T20:49:31.019Z,1695156571.019 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:49:55.881Z,1695156595.881 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005191
2023-09-19T20:50:05.134Z,1695156605.134 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T201945/Courier0010.lzma
2023-09-19T20:50:06.136Z,1695156606.136 [DataOverHttps](INFO): Moved sent file to Logs/20230919T201945/Courier0010.lzma.bak
2023-09-19T20:50:06.136Z,1695156606.136 [DataOverHttps](INFO): SBD MOMSN=18798586
2023-09-19T20:50:22.770Z,1695156622.770 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230919T204619/Courier0000.lzma
2023-09-19T20:50:23.772Z,1695156623.772 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0000.lzma.bak
2023-09-19T20:50:23.772Z,1695156623.772 [DataOverHttps](INFO): SBD MOMSN=18798588
2023-09-19T20:50:24.448Z,1695156624.448 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-19T20:49:24.2Z
2023-09-19T20:50:24.448Z,1695156624.448 [Startup:StartupSatComms:B] Stopped
2023-09-19T20:50:24.448Z,1695156624.448 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-19T20:50:24.448Z,1695156624.448 [Startup:StartupSatComms] Stopped
2023-09-19T20:50:24.448Z,1695156624.448 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-19T20:50:24.449Z,1695156624.449 [Startup](INFO): Completed Startup
2023-09-19T20:50:24.449Z,1695156624.449 [MissionManager](INFO): Startup is completed.
2023-09-19T20:50:24.449Z,1695156624.449 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-19T20:50:24.449Z,1695156624.449 [Startup] Stopped
2023-09-19T20:50:24.449Z,1695156624.449 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-19T20:50:24.449Z,1695156624.449 [Startup:A.GoToSurface] Stopped
2023-09-19T20:50:24.449Z,1695156624.449 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-19T20:50:24.868Z,1695156624.868 [MissionManager](IMPORTANT): Started mission Default
2023-09-19T20:50:24.869Z,1695156624.869 [Default] Running Loop=1
2023-09-19T20:50:24.869Z,1695156624.869 [Default](DEBUG): Aggregate::initialize Default
2023-09-19T20:50:24.869Z,1695156624.869 [Default:B.GoToSurface] Running Loop=1
2023-09-19T20:50:24.869Z,1695156624.869 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-19T20:50:24.869Z,1695156624.869 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-19T20:50:24.869Z,1695156624.869 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-19T20:50:24.870Z,1695156624.870 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-19T20:50:24.870Z,1695156624.870 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-19T20:50:24.871Z,1695156624.871 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-19T20:50:24.871Z,1695156624.871 [Default:A.Wait] Running Loop=1
2023-09-19T20:50:24.871Z,1695156624.871 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-19T20:50:38.180Z,1695156638.180 [Default:A.Wait](INFO): Done Waiting.
2023-09-19T20:50:38.181Z,1695156638.181 [Default:A.Wait] Stopped
2023-09-19T20:50:38.181Z,1695156638.181 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T20:50:38.571Z,1695156638.571 [Default:CheckIn] Running Loop=1
2023-09-19T20:50:38.571Z,1695156638.571 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T20:50:38.571Z,1695156638.571 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T20:50:38.981Z,1695156638.981 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-19T20:52:12.296Z,1695156732.296 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-09-19T20:52:31.291Z,1695156751.291 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-09-19T20:52:31.291Z,1695156751.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:52:31.292Z,1695156751.292 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2023-09-19T20:52:31.292Z,1695156751.292 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:52:31.304Z,1695156751.304 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:52:31.304Z,1695156751.304 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:52:31.739Z,1695156751.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:52:31.739Z,1695156751.739 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-09-19T20:52:31.739Z,1695156751.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:52:31.739Z,1695156751.739 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2023-09-19T20:52:32.113Z,1695156752.113 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:53:42.038Z,1695156822.038 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-09-19T20:53:55.718Z,1695156835.718 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-19T20:53:55.721Z,1695156835.721 [BPC1](INFO): Received data from all battery sticks.
2023-09-19T20:55:32.291Z,1695156932.291 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-09-19T20:55:32.291Z,1695156932.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:55:32.292Z,1695156932.292 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2023-09-19T20:55:32.292Z,1695156932.292 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:55:32.303Z,1695156932.303 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:55:32.303Z,1695156932.303 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:55:32.723Z,1695156932.723 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:55:32.723Z,1695156932.723 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-09-19T20:55:32.723Z,1695156932.723 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:55:32.723Z,1695156932.723 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2023-09-19T20:55:33.123Z,1695156933.123 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:55:38.784Z,1695156938.784 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T20:50:38.6Z
2023-09-19T20:55:38.784Z,1695156938.784 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T20:55:38.784Z,1695156938.784 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T20:55:39.180Z,1695156939.180 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-19T20:55:47.310Z,1695156947.310 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20230919T204619/Courier0004.lzma
2023-09-19T20:55:48.312Z,1695156948.312 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0004.lzma.bak
2023-09-19T20:55:48.312Z,1695156948.312 [DataOverHttps](INFO): SBD MOMSN=18798619
2023-09-19T20:56:10.394Z,1695156970.394 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20230919T201945/Express0011.lzma
2023-09-19T20:56:11.396Z,1695156971.396 [DataOverHttps](INFO): Moved sent file to Logs/20230919T201945/Express0011.lzma.bak
2023-09-19T20:56:11.396Z,1695156971.396 [DataOverHttps](INFO): SBD MOMSN=18798628
2023-09-19T20:56:30.771Z,1695156990.771 [DataOverHttps](INFO): Sending 1026 bytes from file Logs/20230919T204619/Express0001.lzma
2023-09-19T20:56:31.311Z,1695156991.311 [Power24vConverter](INFO): Powering down.
2023-09-19T20:56:31.772Z,1695156991.772 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0001.lzma.bak
2023-09-19T20:56:31.772Z,1695156991.772 [DataOverHttps](INFO): SBD MOMSN=18798714
2023-09-19T20:56:49.230Z,1695157009.230 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230919T204619/Express0005.lzma
2023-09-19T20:56:50.232Z,1695157010.232 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0005.lzma.bak
2023-09-19T20:56:50.232Z,1695157010.232 [DataOverHttps](INFO): SBD MOMSN=18798745
2023-09-19T20:56:53.525Z,1695157013.525 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T20:56:53.525Z,1695157013.525 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T20:56:53.526Z,1695157013.526 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T20:57:11.287Z,1695157031.287 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-19T20:57:11.287Z,1695157031.287 [NAL9602] Data Fault, FailCount= 1
2023-09-19T20:57:11.287Z,1695157031.287 [NAL9602](ERROR): Data Fault
2023-09-19T20:57:11.305Z,1695157031.305 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-19T20:57:11.696Z,1695157031.696 [NAL9602](INFO): Powering down
2023-09-19T20:57:12.530Z,1695157032.530 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-19T20:57:12.530Z,1695157032.530 [NAL9602] No Fault, FailCount= 1
2023-09-19T20:57:41.992Z,1695157061.992 [NAL9602](INFO): Powering up NAL9602
2023-09-19T20:57:52.900Z,1695157072.900 [NAL9602](INFO): NAL9602 initialized
2023-09-19T20:58:33.321Z,1695157113.321 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-09-19T20:58:33.321Z,1695157113.321 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:58:33.322Z,1695157113.322 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2023-09-19T20:58:33.322Z,1695157113.322 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:58:33.366Z,1695157113.366 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:58:33.366Z,1695157113.366 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:58:33.717Z,1695157113.717 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:58:33.717Z,1695157113.717 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-09-19T20:58:33.718Z,1695157113.718 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:58:33.718Z,1695157113.718 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2023-09-19T20:58:34.120Z,1695157114.120 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:01:34.296Z,1695157294.296 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-09-19T21:01:34.296Z,1695157294.296 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:01:34.297Z,1695157294.297 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2023-09-19T21:01:34.297Z,1695157294.297 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:01:34.307Z,1695157294.307 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:01:34.308Z,1695157294.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:01:34.718Z,1695157294.718 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:01:34.719Z,1695157294.719 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-09-19T21:01:34.719Z,1695157294.719 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:01:34.719Z,1695157294.719 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2023-09-19T21:01:35.117Z,1695157295.117 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:01:54.136Z,1695157314.136 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T21:01:54.136Z,1695157314.136 [Default:CheckIn:C.Wait] Stopped
2023-09-19T21:01:54.137Z,1695157314.137 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T21:01:54.137Z,1695157314.137 [Default:CheckIn:D] Running Loop=1
2023-09-19T21:01:54.555Z,1695157314.555 [Default:CheckIn:D] Stopped
2023-09-19T21:01:54.555Z,1695157314.555 [Default:CheckIn:E] Running Loop=1
2023-09-19T21:01:54.959Z,1695157314.959 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.494766 min 
2023-09-19T21:01:54.960Z,1695157314.960 [Default:CheckIn:E] Stopped
2023-09-19T21:01:54.960Z,1695157314.960 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T21:01:54.960Z,1695157314.960 [Default:CheckIn] Stopped
2023-09-19T21:01:54.960Z,1695157314.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T21:01:54.960Z,1695157314.960 [Default:CheckIn](INFO): Running loop #2
2023-09-19T21:01:54.960Z,1695157314.960 [Default:CheckIn] Running Loop=2
2023-09-19T21:01:54.960Z,1695157314.960 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T21:01:54.960Z,1695157314.960 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T21:04:35.337Z,1695157475.337 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-09-19T21:04:35.337Z,1695157475.337 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:04:35.338Z,1695157475.338 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2023-09-19T21:04:35.338Z,1695157475.338 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:04:35.349Z,1695157475.349 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:04:35.349Z,1695157475.349 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:04:35.761Z,1695157475.761 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:04:35.761Z,1695157475.761 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-09-19T21:04:35.762Z,1695157475.762 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:04:35.762Z,1695157475.762 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2023-09-19T21:04:36.167Z,1695157476.167 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:06:55.127Z,1695157615.127 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T21:01:54.0Z
2023-09-19T21:06:55.127Z,1695157615.127 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T21:06:55.128Z,1695157615.128 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T21:07:02.945Z,1695157622.945 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T204619/Courier0007.lzma
2023-09-19T21:07:03.948Z,1695157623.948 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0007.lzma.bak
2023-09-19T21:07:03.948Z,1695157623.948 [DataOverHttps](INFO): SBD MOMSN=18798846
2023-09-19T21:07:21.398Z,1695157641.398 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20230919T204619/Express0008.lzma
2023-09-19T21:07:22.400Z,1695157642.400 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0008.lzma.bak
2023-09-19T21:07:22.400Z,1695157642.400 [DataOverHttps](INFO): SBD MOMSN=18798848
2023-09-19T21:07:25.853Z,1695157645.853 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T21:07:25.853Z,1695157645.853 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T21:07:25.853Z,1695157645.853 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T21:07:36.340Z,1695157656.340 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-09-19T21:07:36.340Z,1695157656.340 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:07:36.341Z,1695157656.341 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2023-09-19T21:07:36.341Z,1695157656.341 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:07:36.392Z,1695157656.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:07:36.392Z,1695157656.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:07:36.758Z,1695157656.758 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:07:36.758Z,1695157656.758 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-09-19T21:07:36.759Z,1695157656.759 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:07:36.759Z,1695157656.759 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2023-09-19T21:07:37.171Z,1695157657.171 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:07:54.508Z,1695157674.508 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-19T21:07:54.508Z,1695157674.508 [NAL9602] Data Fault, FailCount= 2
2023-09-19T21:07:54.508Z,1695157674.508 [NAL9602](ERROR): Data Fault
2023-09-19T21:07:54.585Z,1695157674.585 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-19T21:07:54.912Z,1695157674.912 [NAL9602](INFO): Powering down
2023-09-19T21:07:55.745Z,1695157675.745 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-19T21:07:55.745Z,1695157675.745 [NAL9602] No Fault, FailCount= 2
2023-09-19T21:08:25.216Z,1695157705.216 [NAL9602](INFO): Powering up NAL9602
2023-09-19T21:08:36.124Z,1695157716.124 [NAL9602](INFO): NAL9602 initialized
2023-09-19T21:10:37.330Z,1695157837.330 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-09-19T21:10:37.330Z,1695157837.330 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:10:37.332Z,1695157837.332 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2023-09-19T21:10:37.332Z,1695157837.332 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:10:37.342Z,1695157837.342 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:10:37.342Z,1695157837.342 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:10:37.745Z,1695157837.745 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:10:37.745Z,1695157837.745 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-09-19T21:10:37.746Z,1695157837.746 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:10:37.746Z,1695157837.746 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2023-09-19T21:10:38.149Z,1695157838.149 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:12:26.454Z,1695157946.454 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T21:12:26.454Z,1695157946.454 [Default:CheckIn:C.Wait] Stopped
2023-09-19T21:12:26.454Z,1695157946.454 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T21:12:26.455Z,1695157946.455 [Default:CheckIn:D] Running Loop=1
2023-09-19T21:12:26.855Z,1695157946.855 [Default:CheckIn:D] Stopped
2023-09-19T21:12:26.856Z,1695157946.856 [Default:CheckIn:E] Running Loop=1
2023-09-19T21:12:27.268Z,1695157947.268 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.033114 min 
2023-09-19T21:12:27.268Z,1695157947.268 [Default:CheckIn:E] Stopped
2023-09-19T21:12:27.268Z,1695157947.268 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T21:12:27.268Z,1695157947.268 [Default:CheckIn] Stopped
2023-09-19T21:12:27.268Z,1695157947.268 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T21:12:27.268Z,1695157947.268 [Default:CheckIn](INFO): Running loop #3
2023-09-19T21:12:27.268Z,1695157947.268 [Default:CheckIn] Running Loop=3
2023-09-19T21:12:27.268Z,1695157947.268 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T21:12:27.268Z,1695157947.268 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T21:13:38.383Z,1695158018.383 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-09-19T21:13:38.383Z,1695158018.383 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:13:38.384Z,1695158018.384 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2023-09-19T21:13:38.384Z,1695158018.384 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:13:38.395Z,1695158018.395 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:13:38.395Z,1695158018.395 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:13:38.774Z,1695158018.774 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:13:38.774Z,1695158018.774 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-09-19T21:13:38.774Z,1695158018.774 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:13:38.774Z,1695158018.774 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2023-09-19T21:13:39.171Z,1695158019.171 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:16:39.398Z,1695158199.398 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-09-19T21:16:39.398Z,1695158199.398 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:16:39.399Z,1695158199.399 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2023-09-19T21:16:39.399Z,1695158199.399 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:16:39.410Z,1695158199.410 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:16:39.411Z,1695158199.411 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:16:39.805Z,1695158199.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:16:39.805Z,1695158199.805 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-09-19T21:16:39.805Z,1695158199.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:16:39.805Z,1695158199.805 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2023-09-19T21:16:40.203Z,1695158200.203 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:17:27.455Z,1695158247.455 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T21:12:27.3Z
2023-09-19T21:17:27.456Z,1695158247.456 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T21:17:27.456Z,1695158247.456 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T21:17:35.597Z,1695158255.597 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T204619/Courier0010.lzma
2023-09-19T21:17:36.600Z,1695158256.600 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0010.lzma.bak
2023-09-19T21:17:36.600Z,1695158256.600 [DataOverHttps](INFO): SBD MOMSN=18798969
2023-09-19T21:17:56.762Z,1695158276.762 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230919T204619/Express0011.lzma
2023-09-19T21:17:57.764Z,1695158277.764 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0011.lzma.bak
2023-09-19T21:17:57.764Z,1695158277.764 [DataOverHttps](INFO): SBD MOMSN=18798971
2023-09-19T21:18:03.495Z,1695158283.495 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T21:18:03.495Z,1695158283.495 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T21:18:03.495Z,1695158283.495 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T21:18:37.402Z,1695158317.402 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-19T21:18:37.402Z,1695158317.402 [NAL9602] Data Fault, FailCount= 3
2023-09-19T21:18:37.402Z,1695158317.402 [NAL9602](ERROR): Data Fault
2023-09-19T21:18:37.420Z,1695158317.420 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-19T21:18:37.812Z,1695158317.812 [NAL9602](INFO): Powering down
2023-09-19T21:18:38.631Z,1695158318.631 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-19T21:18:38.631Z,1695158318.631 [NAL9602] No Fault, FailCount= 3
2023-09-19T21:19:08.112Z,1695158348.112 [NAL9602](INFO): Powering up NAL9602
2023-09-19T21:19:19.020Z,1695158359.020 [NAL9602](INFO): NAL9602 initialized
2023-09-19T21:19:40.432Z,1695158380.432 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-09-19T21:19:40.432Z,1695158380.432 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:19:40.433Z,1695158380.433 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2023-09-19T21:19:40.433Z,1695158380.433 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:19:40.444Z,1695158380.444 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:19:40.444Z,1695158380.444 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:19:40.885Z,1695158380.885 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:19:40.885Z,1695158380.885 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-09-19T21:19:40.886Z,1695158380.886 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:19:40.886Z,1695158380.886 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2023-09-19T21:19:41.271Z,1695158381.271 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:22:41.515Z,1695158561.515 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-09-19T21:22:41.515Z,1695158561.515 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:22:41.516Z,1695158561.516 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2023-09-19T21:22:41.516Z,1695158561.516 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:22:41.527Z,1695158561.527 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:22:41.527Z,1695158561.527 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:22:41.943Z,1695158561.943 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:22:41.943Z,1695158561.943 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-09-19T21:22:41.944Z,1695158561.944 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:22:41.944Z,1695158561.944 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2023-09-19T21:22:42.307Z,1695158562.307 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:23:04.114Z,1695158584.114 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T21:23:04.114Z,1695158584.114 [Default:CheckIn:C.Wait] Stopped
2023-09-19T21:23:04.115Z,1695158584.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T21:23:04.115Z,1695158584.115 [Default:CheckIn:D] Running Loop=1
2023-09-19T21:23:04.533Z,1695158584.533 [Default:CheckIn:D] Stopped
2023-09-19T21:23:04.533Z,1695158584.533 [Default:CheckIn:E] Running Loop=1
2023-09-19T21:23:04.927Z,1695158584.927 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.661078 min 
2023-09-19T21:23:04.927Z,1695158584.927 [Default:CheckIn:E] Stopped
2023-09-19T21:23:04.927Z,1695158584.927 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T21:23:04.927Z,1695158584.927 [Default:CheckIn] Stopped
2023-09-19T21:23:04.928Z,1695158584.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T21:23:04.928Z,1695158584.928 [Default:CheckIn](INFO): Running loop #4
2023-09-19T21:23:04.928Z,1695158584.928 [Default:CheckIn] Running Loop=4
2023-09-19T21:23:04.928Z,1695158584.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T21:23:04.928Z,1695158584.928 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T21:25:42.472Z,1695158742.472 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-09-19T21:25:42.472Z,1695158742.472 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:25:42.473Z,1695158742.473 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2023-09-19T21:25:42.473Z,1695158742.473 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:25:42.484Z,1695158742.484 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:25:42.484Z,1695158742.484 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:25:42.887Z,1695158742.887 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:25:42.887Z,1695158742.887 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-09-19T21:25:42.888Z,1695158742.888 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:25:42.888Z,1695158742.888 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2023-09-19T21:25:43.303Z,1695158743.303 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:28:05.159Z,1695158885.159 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T21:23:04.9Z
2023-09-19T21:28:05.159Z,1695158885.159 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T21:28:05.159Z,1695158885.159 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T21:28:13.093Z,1695158893.093 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T204619/Courier0013.lzma
2023-09-19T21:28:14.096Z,1695158894.096 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0013.lzma.bak
2023-09-19T21:28:14.096Z,1695158894.096 [DataOverHttps](INFO): SBD MOMSN=18799081
2023-09-19T21:28:31.434Z,1695158911.434 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230919T204619/Express0014.lzma
2023-09-19T21:28:32.436Z,1695158912.436 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0014.lzma.bak
2023-09-19T21:28:32.436Z,1695158912.436 [DataOverHttps](INFO): SBD MOMSN=18799083
2023-09-19T21:28:35.812Z,1695158915.812 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T21:28:35.812Z,1695158915.812 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T21:28:35.812Z,1695158915.812 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T21:28:43.469Z,1695158923.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2023-09-19T21:28:43.469Z,1695158923.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:28:43.470Z,1695158923.470 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2023-09-19T21:28:43.470Z,1695158923.470 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:28:43.480Z,1695158923.480 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:28:43.481Z,1695158923.481 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:28:43.893Z,1695158923.893 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:28:43.893Z,1695158923.893 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2023-09-19T21:28:43.894Z,1695158923.894 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:28:43.894Z,1695158923.894 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2023-09-19T21:28:44.276Z,1695158924.276 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:29:22.240Z,1695158962.240 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-19T21:29:22.240Z,1695158962.240 [NAL9602] Data Fault, FailCount= 4
2023-09-19T21:29:22.240Z,1695158962.240 [NAL9602](ERROR): Data Fault
2023-09-19T21:29:22.264Z,1695158962.264 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-19T21:29:22.649Z,1695158962.649 [NAL9602](INFO): Powering down
2023-09-19T21:29:23.517Z,1695158963.517 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-19T21:29:23.517Z,1695158963.517 [NAL9602] No Fault, FailCount= 4
2023-09-19T21:29:52.948Z,1695158992.948 [NAL9602](INFO): Powering up NAL9602
2023-09-19T21:30:03.852Z,1695159003.852 [NAL9602](INFO): NAL9602 initialized
2023-09-19T21:31:44.503Z,1695159104.503 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2023-09-19T21:31:44.503Z,1695159104.503 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:31:44.504Z,1695159104.504 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2023-09-19T21:31:44.504Z,1695159104.504 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:31:44.575Z,1695159104.575 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:31:44.575Z,1695159104.575 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:31:44.933Z,1695159104.933 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:31:44.933Z,1695159104.933 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2023-09-19T21:31:44.933Z,1695159104.933 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:31:44.933Z,1695159104.933 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2023-09-19T21:31:45.321Z,1695159105.321 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:33:36.410Z,1695159216.410 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T21:33:36.410Z,1695159216.410 [Default:CheckIn:C.Wait] Stopped
2023-09-19T21:33:36.410Z,1695159216.410 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T21:33:36.410Z,1695159216.410 [Default:CheckIn:D] Running Loop=1
2023-09-19T21:33:36.810Z,1695159216.810 [Default:CheckIn:D] Stopped
2023-09-19T21:33:36.810Z,1695159216.810 [Default:CheckIn:E] Running Loop=1
2023-09-19T21:33:37.226Z,1695159217.226 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.199028 min 
2023-09-19T21:33:37.226Z,1695159217.226 [Default:CheckIn:E] Stopped
2023-09-19T21:33:37.226Z,1695159217.226 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T21:33:37.226Z,1695159217.226 [Default:CheckIn] Stopped
2023-09-19T21:33:37.226Z,1695159217.226 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T21:33:37.226Z,1695159217.226 [Default:CheckIn](INFO): Running loop #5
2023-09-19T21:33:37.227Z,1695159217.227 [Default:CheckIn] Running Loop=5
2023-09-19T21:33:37.227Z,1695159217.227 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T21:33:37.227Z,1695159217.227 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T21:34:45.487Z,1695159285.487 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2023-09-19T21:34:45.487Z,1695159285.487 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:34:45.489Z,1695159285.489 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2023-09-19T21:34:45.489Z,1695159285.489 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:34:45.499Z,1695159285.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:34:45.499Z,1695159285.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:34:45.912Z,1695159285.912 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:34:45.912Z,1695159285.912 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2023-09-19T21:34:45.913Z,1695159285.913 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:34:45.913Z,1695159285.913 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2023-09-19T21:34:46.291Z,1695159286.291 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:37:46.501Z,1695159466.501 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2023-09-19T21:37:46.501Z,1695159466.501 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:37:46.502Z,1695159466.502 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2023-09-19T21:37:46.503Z,1695159466.503 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:37:46.513Z,1695159466.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:37:46.513Z,1695159466.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:37:46.916Z,1695159466.916 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:37:46.916Z,1695159466.916 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2023-09-19T21:37:46.917Z,1695159466.917 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:37:46.917Z,1695159466.917 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2023-09-19T21:37:47.295Z,1695159467.295 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:38:37.415Z,1695159517.415 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T21:33:37.2Z
2023-09-19T21:38:37.415Z,1695159517.415 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T21:38:37.415Z,1695159517.415 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T21:38:45.409Z,1695159525.409 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T204619/Courier0016.lzma
2023-09-19T21:38:46.412Z,1695159526.412 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0016.lzma.bak
2023-09-19T21:38:46.413Z,1695159526.413 [DataOverHttps](INFO): SBD MOMSN=18799228
2023-09-19T21:39:03.926Z,1695159543.926 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230919T204619/Express0017.lzma
2023-09-19T21:39:04.928Z,1695159544.928 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0017.lzma.bak
2023-09-19T21:39:04.928Z,1695159544.928 [DataOverHttps](INFO): SBD MOMSN=18799230
2023-09-19T21:39:08.125Z,1695159548.125 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T21:39:08.125Z,1695159548.125 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T21:39:08.125Z,1695159548.125 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T21:40:05.460Z,1695159605.460 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-19T21:40:05.460Z,1695159605.460 [NAL9602] Data Fault, FailCount= 5
2023-09-19T21:40:05.460Z,1695159605.460 [NAL9602](ERROR): Data Fault
2023-09-19T21:40:05.514Z,1695159605.514 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-19T21:40:05.514Z,1695159605.514 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-09-19T21:40:05.857Z,1695159605.857 [NAL9602](INFO): Powering down
2023-09-19T21:40:05.893Z,1695159605.893 [CommandExec](FAULT): Scheduling is paused
2023-09-19T21:40:05.893Z,1695159605.893 [CBIT](INFO): Critical error at 20230919T214005
2023-09-19T21:40:05.894Z,1695159605.894 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-09-19T21:40:47.482Z,1695159647.482 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2023-09-19T21:40:47.482Z,1695159647.482 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:40:47.483Z,1695159647.483 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2023-09-19T21:40:47.483Z,1695159647.483 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:40:47.494Z,1695159647.494 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:40:47.494Z,1695159647.494 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:40:47.897Z,1695159647.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:40:47.897Z,1695159647.897 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2023-09-19T21:40:47.897Z,1695159647.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:40:47.897Z,1695159647.897 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2023-09-19T21:40:48.290Z,1695159648.290 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:42:05.468Z,1695159725.468 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-19T21:42:05.468Z,1695159725.468 [NAL9602] No Fault, FailCount= 5
2023-09-19T21:42:05.849Z,1695159725.849 [NAL9602](INFO): Powering up NAL9602
2023-09-19T21:42:16.764Z,1695159736.764 [NAL9602](INFO): NAL9602 initialized
2023-09-19T21:43:48.491Z,1695159828.491 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2023-09-19T21:43:48.491Z,1695159828.491 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:43:48.492Z,1695159828.492 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2023-09-19T21:43:48.492Z,1695159828.492 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:43:48.511Z,1695159828.511 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:43:48.511Z,1695159828.511 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:43:48.911Z,1695159828.911 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:43:48.911Z,1695159828.911 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2023-09-19T21:43:48.911Z,1695159828.911 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:43:48.911Z,1695159828.911 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2023-09-19T21:43:49.315Z,1695159829.315 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:44:08.728Z,1695159848.728 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T21:44:08.728Z,1695159848.728 [Default:CheckIn:C.Wait] Stopped
2023-09-19T21:44:08.728Z,1695159848.728 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T21:44:08.728Z,1695159848.728 [Default:CheckIn:D] Running Loop=1
2023-09-19T21:44:09.129Z,1695159849.129 [Default:CheckIn:D] Stopped
2023-09-19T21:44:09.129Z,1695159849.129 [Default:CheckIn:E] Running Loop=1
2023-09-19T21:44:09.512Z,1695159849.512 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.737667 min 
2023-09-19T21:44:09.512Z,1695159849.512 [Default:CheckIn:E] Stopped
2023-09-19T21:44:09.512Z,1695159849.512 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T21:44:09.512Z,1695159849.512 [Default:CheckIn] Stopped
2023-09-19T21:44:09.512Z,1695159849.512 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T21:44:09.512Z,1695159849.512 [Default:CheckIn](INFO): Running loop #6
2023-09-19T21:44:09.512Z,1695159849.512 [Default:CheckIn] Running Loop=6
2023-09-19T21:44:09.512Z,1695159849.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T21:44:09.513Z,1695159849.513 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T21:45:06.358Z,1695159906.358 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T21:45:11.380Z,1695159911.380 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.240517
2023-09-19T21:45:56.066Z,1695159956.066 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003007
2023-09-19T21:46:49.540Z,1695160009.540 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2023-09-19T21:46:49.540Z,1695160009.540 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:46:49.541Z,1695160009.541 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2023-09-19T21:46:49.541Z,1695160009.541 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:46:49.551Z,1695160009.551 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:46:49.552Z,1695160009.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:46:49.956Z,1695160009.956 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:46:49.956Z,1695160009.956 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2023-09-19T21:46:49.956Z,1695160009.956 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:46:49.956Z,1695160009.956 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2023-09-19T21:46:50.367Z,1695160010.367 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:47:40.463Z,1695160060.463 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-19T21:47:43.287Z,1695160063.287 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-09-19T21:47:51.383Z,1695160071.383 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006210
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.005272
CHAN A3 (5V): -0.002690
CHAN B0 (3.3V): -0.000640
CHAN B1 (3.15aV): -0.001331
CHAN B2 (3.15bV): -0.001256
CHAN B3 (GND): -0.000371
OPEN: 0.004556
Full Scale: +/- 1 mA
2023-09-19T21:49:09.733Z,1695160149.733 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T21:44:09.5Z
2023-09-19T21:49:09.733Z,1695160149.733 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T21:49:09.734Z,1695160149.734 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T21:49:17.734Z,1695160157.734 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20230919T204619/Courier0019.lzma
2023-09-19T21:49:18.736Z,1695160158.736 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0019.lzma.bak
2023-09-19T21:49:18.736Z,1695160158.736 [DataOverHttps](INFO): SBD MOMSN=18799341
2023-09-19T21:49:36.371Z,1695160176.371 [DataOverHttps](INFO): Sending 383 bytes from file Logs/20230919T204619/Express0020.lzma
2023-09-19T21:49:37.368Z,1695160177.368 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0020.lzma.bak
2023-09-19T21:49:37.368Z,1695160177.368 [DataOverHttps](INFO): SBD MOMSN=18799344
2023-09-19T21:49:40.863Z,1695160180.863 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T21:49:40.863Z,1695160180.863 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T21:49:40.863Z,1695160180.863 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T21:49:50.576Z,1695160190.576 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2023-09-19T21:49:50.576Z,1695160190.576 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:49:50.577Z,1695160190.577 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 21
2023-09-19T21:49:50.577Z,1695160190.577 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:49:50.588Z,1695160190.588 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:49:50.588Z,1695160190.588 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:49:50.993Z,1695160190.993 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:49:50.993Z,1695160190.993 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2023-09-19T21:49:50.994Z,1695160190.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:49:50.994Z,1695160190.994 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 21
2023-09-19T21:49:51.373Z,1695160191.373 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:52:17.640Z,1695160337.640 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-19T21:52:17.640Z,1695160337.640 [NAL9602] Data Fault, FailCount= 1
2023-09-19T21:52:17.640Z,1695160337.640 [NAL9602](ERROR): Data Fault
2023-09-19T21:52:17.708Z,1695160337.708 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-19T21:52:18.044Z,1695160338.044 [NAL9602](INFO): Powering down
2023-09-19T21:52:18.873Z,1695160338.873 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-19T21:52:18.873Z,1695160338.873 [NAL9602] No Fault, FailCount= 1
2023-09-19T21:52:48.341Z,1695160368.341 [NAL9602](INFO): Powering up NAL9602
2023-09-19T21:52:51.598Z,1695160371.598 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2023-09-19T21:52:51.598Z,1695160371.598 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:52:51.599Z,1695160371.599 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 22
2023-09-19T21:52:51.599Z,1695160371.599 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:52:51.656Z,1695160371.656 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:52:51.656Z,1695160371.656 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:52:52.024Z,1695160372.024 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:52:52.024Z,1695160372.024 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2023-09-19T21:52:52.024Z,1695160372.024 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:52:52.024Z,1695160372.024 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 22
2023-09-19T21:52:52.396Z,1695160372.396 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:52:55.527Z,1695160375.527 [DataOverHttps](IMPORTANT): SBD MTMSN=20230919T215254
2023-09-19T21:52:59.252Z,1695160379.252 [NAL9602](INFO): NAL9602 initialized
2023-09-19T21:53:02.634Z,1695160382.634 [DataOverHttps](INFO): Received command: strobe off
2023-09-19T21:53:02.715Z,1695160382.715 [CommandExec](IMPORTANT): got command strobe off
2023-09-19T21:53:02.716Z,1695160382.716 [CommandExec](IMPORTANT): Deactivating strobe
2023-09-19T21:54:41.498Z,1695160481.498 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T21:54:41.498Z,1695160481.498 [Default:CheckIn:C.Wait] Stopped
2023-09-19T21:54:41.498Z,1695160481.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T21:54:41.498Z,1695160481.498 [Default:CheckIn:D] Running Loop=1
2023-09-19T21:54:41.913Z,1695160481.913 [Default:CheckIn:D] Stopped
2023-09-19T21:54:41.913Z,1695160481.913 [Default:CheckIn:E] Running Loop=1
2023-09-19T21:54:42.288Z,1695160482.288 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.284074 min 
2023-09-19T21:54:42.288Z,1695160482.288 [Default:CheckIn:E] Stopped
2023-09-19T21:54:42.289Z,1695160482.289 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T21:54:42.289Z,1695160482.289 [Default:CheckIn] Stopped
2023-09-19T21:54:42.289Z,1695160482.289 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T21:54:42.289Z,1695160482.289 [Default:CheckIn](INFO): Running loop #7
2023-09-19T21:54:42.289Z,1695160482.289 [Default:CheckIn] Running Loop=7
2023-09-19T21:54:42.289Z,1695160482.289 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T21:54:42.289Z,1695160482.289 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T21:55:52.604Z,1695160552.604 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2023-09-19T21:55:52.604Z,1695160552.604 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:55:52.605Z,1695160552.605 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 23
2023-09-19T21:55:52.605Z,1695160552.605 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:55:52.637Z,1695160552.637 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:55:52.637Z,1695160552.637 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:55:53.003Z,1695160553.003 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:55:53.003Z,1695160553.003 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2023-09-19T21:55:53.003Z,1695160553.003 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:55:53.003Z,1695160553.003 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 23
2023-09-19T21:55:53.391Z,1695160553.391 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:56:22.886Z,1695160582.886 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T21:56:24.895Z,1695160584.895 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247360
2023-09-19T21:56:58.026Z,1695160618.026 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T21:57:22.383Z,1695160642.383 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003472
2023-09-19T21:58:53.608Z,1695160733.608 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2023-09-19T21:58:53.608Z,1695160733.608 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T21:58:53.609Z,1695160733.609 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 24
2023-09-19T21:58:53.609Z,1695160733.609 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T21:58:53.660Z,1695160733.660 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T21:58:53.660Z,1695160733.660 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T21:58:54.030Z,1695160734.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T21:58:54.030Z,1695160734.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2023-09-19T21:58:54.031Z,1695160734.031 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T21:58:54.031Z,1695160734.031 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 24
2023-09-19T21:58:54.387Z,1695160734.387 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T21:59:42.482Z,1695160782.482 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T21:54:42.3Z
2023-09-19T21:59:42.482Z,1695160782.482 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T21:59:42.482Z,1695160782.482 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T21:59:56.350Z,1695160796.350 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T204619/Courier0022.lzma
2023-09-19T21:59:57.352Z,1695160797.352 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0022.lzma.bak
2023-09-19T21:59:57.352Z,1695160797.352 [DataOverHttps](INFO): SBD MOMSN=18799467
2023-09-19T22:00:16.710Z,1695160816.710 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20230919T204619/Express0023.lzma
2023-09-19T22:00:17.713Z,1695160817.713 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0023.lzma.bak
2023-09-19T22:00:17.713Z,1695160817.713 [DataOverHttps](INFO): SBD MOMSN=18799469
2023-09-19T22:00:37.361Z,1695160837.361 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T204619/Express0026.lzma
2023-09-19T22:00:38.364Z,1695160838.364 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0026.lzma.bak
2023-09-19T22:00:38.364Z,1695160838.364 [DataOverHttps](INFO): SBD MOMSN=18799476
2023-09-19T22:00:41.513Z,1695160841.513 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:00:41.514Z,1695160841.514 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:00:41.514Z,1695160841.514 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:01:23.894Z,1695160883.894 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-19T22:01:23.897Z,1695160883.897 [BPC1](INFO): Received data from all battery sticks.
2023-09-19T22:01:54.621Z,1695160914.621 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2023-09-19T22:01:54.621Z,1695160914.621 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T22:01:54.622Z,1695160914.622 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 25
2023-09-19T22:01:54.622Z,1695160914.622 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T22:01:54.633Z,1695160914.633 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T22:01:54.633Z,1695160914.633 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T22:01:55.048Z,1695160915.048 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T22:01:55.048Z,1695160915.048 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2023-09-19T22:01:55.048Z,1695160915.048 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T22:01:55.048Z,1695160915.048 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 25
2023-09-19T22:01:55.439Z,1695160915.439 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T22:01:59.046Z,1695160919.046 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220157.00,A,3648.15929,N,12147.24660,W,2.819,165.35,190923,,,A*71
2023-09-19T22:01:59.049Z,1695160919.049 [NAL9602](INFO): GPS fix at 20230919T220157: (36.802655, -121.787443)
2023-09-19T22:02:31.362Z,1695160951.362 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:05:42.111Z,1695161142.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:05:42.111Z,1695161142.111 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:05:42.111Z,1695161142.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:05:42.111Z,1695161142.111 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:05:42.515Z,1695161142.515 [Default:CheckIn:D] Stopped
2023-09-19T22:05:42.515Z,1695161142.515 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:05:42.916Z,1695161142.916 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.294100 min 
2023-09-19T22:05:42.916Z,1695161142.916 [Default:CheckIn:E] Stopped
2023-09-19T22:05:42.916Z,1695161142.916 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:05:42.917Z,1695161142.917 [Default:CheckIn] Stopped
2023-09-19T22:05:42.917Z,1695161142.917 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:05:42.917Z,1695161142.917 [Default:CheckIn](INFO): Running loop #8
2023-09-19T22:05:42.917Z,1695161142.917 [Default:CheckIn] Running Loop=8
2023-09-19T22:05:42.917Z,1695161142.917 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:05:42.917Z,1695161142.917 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:05:44.925Z,1695161144.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220543.00,A,3648.13909,N,12147.20568,W,0.078,102.42,190923,,,A*77
2023-09-19T22:05:44.927Z,1695161144.927 [NAL9602](INFO): GPS fix at 20230919T220543: (36.802318, -121.786761)
2023-09-19T22:05:44.962Z,1695161144.962 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:05:44.962Z,1695161144.962 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:05:57.466Z,1695161157.466 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20230919T204619/Courier0028.lzma
2023-09-19T22:05:58.472Z,1695161158.472 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0028.lzma.bak
2023-09-19T22:05:58.472Z,1695161158.472 [DataOverHttps](INFO): SBD MOMSN=18799478
2023-09-19T22:06:19.478Z,1695161179.478 [DataOverHttps](INFO): Sending 261 bytes from file Logs/20230919T204619/Express0029.lzma
2023-09-19T22:06:20.480Z,1695161180.480 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0029.lzma.bak
2023-09-19T22:06:20.480Z,1695161180.480 [DataOverHttps](INFO): SBD MOMSN=18799481
2023-09-19T22:06:23.801Z,1695161183.801 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:06:23.801Z,1695161183.801 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:06:23.801Z,1695161183.801 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:07:02.564Z,1695161222.564 [NAL9602](INFO): SBD MO Status=2, MOMSN=13559, MT Status=2, MTMSN=0
2023-09-19T22:07:02.564Z,1695161222.564 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-19T22:07:10.247Z,1695161230.247 [NAL9602](INFO): SBD MO Status=0, MOMSN=13559, MT Status=0, MTMSN=0
2023-09-19T22:07:10.247Z,1695161230.247 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:07:40.938Z,1695161260.938 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:11:24.433Z,1695161484.433 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:11:24.433Z,1695161484.433 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:11:24.433Z,1695161484.433 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:11:24.433Z,1695161484.433 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:11:24.822Z,1695161484.822 [Default:CheckIn:D] Stopped
2023-09-19T22:11:24.822Z,1695161484.822 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:11:25.239Z,1695161485.239 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.999227 min 
2023-09-19T22:11:25.239Z,1695161485.239 [Default:CheckIn:E] Stopped
2023-09-19T22:11:25.240Z,1695161485.240 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:11:25.240Z,1695161485.240 [Default:CheckIn] Stopped
2023-09-19T22:11:25.240Z,1695161485.240 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:11:25.240Z,1695161485.240 [Default:CheckIn](INFO): Running loop #9
2023-09-19T22:11:25.240Z,1695161485.240 [Default:CheckIn] Running Loop=9
2023-09-19T22:11:25.240Z,1695161485.240 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:11:25.240Z,1695161485.240 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:11:27.233Z,1695161487.233 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221126.00,A,3648.13735,N,12147.20537,W,0.039,102.42,190923,,,D*7A
2023-09-19T22:11:27.235Z,1695161487.235 [NAL9602](INFO): GPS fix at 20230919T221126: (36.802289, -121.786756)
2023-09-19T22:11:27.247Z,1695161487.247 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:11:27.247Z,1695161487.247 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:11:35.590Z,1695161495.590 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0031.lzma
2023-09-19T22:11:36.592Z,1695161496.592 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0031.lzma.bak
2023-09-19T22:11:36.592Z,1695161496.592 [DataOverHttps](INFO): SBD MOMSN=18799590
2023-09-19T22:11:42.993Z,1695161502.993 [NAL9602](INFO): SBD MO Status=0, MOMSN=13560, MT Status=0, MTMSN=0
2023-09-19T22:11:42.993Z,1695161502.993 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:11:42.993Z,1695161502.993 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2023-09-19T22:11:58.310Z,1695161518.310 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230919T204619/Express0032.lzma
2023-09-19T22:11:59.312Z,1695161519.312 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0032.lzma.bak
2023-09-19T22:11:59.312Z,1695161519.312 [DataOverHttps](INFO): SBD MOMSN=18799593
2023-09-19T22:12:02.845Z,1695161522.845 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:12:02.845Z,1695161522.845 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:12:02.846Z,1695161522.846 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:12:13.711Z,1695161533.711 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:17:03.451Z,1695161823.451 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:17:03.452Z,1695161823.452 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:17:03.452Z,1695161823.452 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:17:03.452Z,1695161823.452 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:17:03.871Z,1695161823.871 [Default:CheckIn:D] Stopped
2023-09-19T22:17:03.871Z,1695161823.871 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:17:04.251Z,1695161824.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.650041 min 
2023-09-19T22:17:04.251Z,1695161824.251 [Default:CheckIn:E] Stopped
2023-09-19T22:17:04.251Z,1695161824.251 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:17:04.251Z,1695161824.251 [Default:CheckIn] Stopped
2023-09-19T22:17:04.251Z,1695161824.251 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:17:04.252Z,1695161824.252 [Default:CheckIn](INFO): Running loop #10
2023-09-19T22:17:04.252Z,1695161824.252 [Default:CheckIn] Running Loop=10
2023-09-19T22:17:04.252Z,1695161824.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:17:04.252Z,1695161824.252 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:17:06.258Z,1695161826.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221705.00,A,3648.13726,N,12147.20597,W,0.058,102.42,190923,,,D*72
2023-09-19T22:17:06.260Z,1695161826.260 [NAL9602](INFO): GPS fix at 20230919T221705: (36.802288, -121.786766)
2023-09-19T22:17:06.273Z,1695161826.273 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:17:06.273Z,1695161826.273 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:17:14.453Z,1695161834.453 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0034.lzma
2023-09-19T22:17:15.456Z,1695161835.456 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0034.lzma.bak
2023-09-19T22:17:15.456Z,1695161835.456 [DataOverHttps](INFO): SBD MOMSN=18799597
2023-09-19T22:17:34.141Z,1695161854.141 [NAL9602](INFO): SBD MO Status=0, MOMSN=13561, MT Status=0, MTMSN=0
2023-09-19T22:17:34.141Z,1695161854.141 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:17:34.490Z,1695161854.490 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230919T204619/Express0035.lzma
2023-09-19T22:17:35.492Z,1695161855.492 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0035.lzma.bak
2023-09-19T22:17:35.492Z,1695161855.492 [DataOverHttps](INFO): SBD MOMSN=18799600
2023-09-19T22:17:39.041Z,1695161859.041 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:17:39.041Z,1695161859.041 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:17:39.041Z,1695161859.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:18:04.846Z,1695161884.846 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:22:39.586Z,1695162159.586 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:22:39.586Z,1695162159.586 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:22:39.586Z,1695162159.586 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:22:39.586Z,1695162159.586 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:22:40.016Z,1695162160.016 [Default:CheckIn:D] Stopped
2023-09-19T22:22:40.016Z,1695162160.016 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:22:40.387Z,1695162160.387 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.252458 min 
2023-09-19T22:22:40.387Z,1695162160.387 [Default:CheckIn:E] Stopped
2023-09-19T22:22:40.387Z,1695162160.387 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:22:40.387Z,1695162160.387 [Default:CheckIn] Stopped
2023-09-19T22:22:40.388Z,1695162160.388 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:22:40.388Z,1695162160.388 [Default:CheckIn](INFO): Running loop #11
2023-09-19T22:22:40.388Z,1695162160.388 [Default:CheckIn] Running Loop=11
2023-09-19T22:22:40.388Z,1695162160.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:22:40.388Z,1695162160.388 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:22:42.405Z,1695162162.405 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222241.00,A,3648.13864,N,12147.20675,W,0.058,102.42,190923,,,D*72
2023-09-19T22:22:42.408Z,1695162162.408 [NAL9602](INFO): GPS fix at 20230919T222241: (36.802311, -121.786779)
2023-09-19T22:22:42.420Z,1695162162.420 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:22:42.420Z,1695162162.420 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:22:50.530Z,1695162170.530 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0037.lzma
2023-09-19T22:22:51.532Z,1695162171.532 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0037.lzma.bak
2023-09-19T22:22:51.532Z,1695162171.532 [DataOverHttps](INFO): SBD MOMSN=18799715
2023-09-19T22:23:09.373Z,1695162189.373 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230919T204619/Express0038.lzma
2023-09-19T22:23:10.376Z,1695162190.376 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0038.lzma.bak
2023-09-19T22:23:10.376Z,1695162190.376 [DataOverHttps](INFO): SBD MOMSN=18799718
2023-09-19T22:23:13.956Z,1695162193.956 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:23:13.956Z,1695162193.956 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:23:13.956Z,1695162193.956 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:23:38.553Z,1695162218.553 [NAL9602](INFO): SBD MO Status=2, MOMSN=13562, MT Status=2, MTMSN=0
2023-09-19T22:23:38.553Z,1695162218.553 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-19T22:24:33.509Z,1695162273.509 [NAL9602](INFO): SBD MO Status=0, MOMSN=13562, MT Status=0, MTMSN=0
2023-09-19T22:24:33.509Z,1695162273.509 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:25:04.208Z,1695162304.208 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:28:14.645Z,1695162494.645 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:28:14.645Z,1695162494.645 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:28:14.645Z,1695162494.645 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:28:14.645Z,1695162494.645 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:28:15.007Z,1695162495.007 [Default:CheckIn:D] Stopped
2023-09-19T22:28:15.007Z,1695162495.007 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:28:15.383Z,1695162495.383 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.835612 min 
2023-09-19T22:28:15.383Z,1695162495.383 [Default:CheckIn:E] Stopped
2023-09-19T22:28:15.383Z,1695162495.383 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:28:15.383Z,1695162495.383 [Default:CheckIn] Stopped
2023-09-19T22:28:15.384Z,1695162495.384 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:28:15.384Z,1695162495.384 [Default:CheckIn](INFO): Running loop #12
2023-09-19T22:28:15.384Z,1695162495.384 [Default:CheckIn] Running Loop=12
2023-09-19T22:28:15.384Z,1695162495.384 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:28:15.384Z,1695162495.384 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:28:17.381Z,1695162497.381 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222816.00,A,3648.13755,N,12147.20590,W,0.097,102.42,190923,,,D*7C
2023-09-19T22:28:17.391Z,1695162497.391 [NAL9602](INFO): GPS fix at 20230919T222816: (36.802293, -121.786765)
2023-09-19T22:28:17.404Z,1695162497.404 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:28:17.404Z,1695162497.404 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:28:25.626Z,1695162505.626 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230919T204619/Courier0040.lzma
2023-09-19T22:28:26.628Z,1695162506.628 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0040.lzma.bak
2023-09-19T22:28:26.628Z,1695162506.628 [DataOverHttps](INFO): SBD MOMSN=18799722
2023-09-19T22:28:36.773Z,1695162516.773 [NAL9602](INFO): SBD MO Status=2, MOMSN=13563, MT Status=2, MTMSN=0
2023-09-19T22:28:36.773Z,1695162516.773 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-19T22:28:44.510Z,1695162524.510 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230919T204619/Express0041.lzma
2023-09-19T22:28:45.512Z,1695162525.512 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0041.lzma.bak
2023-09-19T22:28:45.512Z,1695162525.512 [DataOverHttps](INFO): SBD MOMSN=18799725
2023-09-19T22:28:47.681Z,1695162527.681 [NAL9602](INFO): SBD MO Status=0, MOMSN=13563, MT Status=0, MTMSN=0
2023-09-19T22:28:47.681Z,1695162527.681 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:28:48.916Z,1695162528.916 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:28:48.916Z,1695162528.916 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:28:48.917Z,1695162528.917 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:29:18.383Z,1695162558.383 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:33:49.585Z,1695162829.585 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:33:49.586Z,1695162829.586 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:33:49.586Z,1695162829.586 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:33:49.634Z,1695162829.634 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:33:50.004Z,1695162830.004 [Default:CheckIn:D] Stopped
2023-09-19T22:33:50.004Z,1695162830.004 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:33:50.365Z,1695162830.365 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.418921 min 
2023-09-19T22:33:50.365Z,1695162830.365 [Default:CheckIn:E] Stopped
2023-09-19T22:33:50.366Z,1695162830.366 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:33:50.366Z,1695162830.366 [Default:CheckIn] Stopped
2023-09-19T22:33:50.366Z,1695162830.366 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:33:50.366Z,1695162830.366 [Default:CheckIn](INFO): Running loop #13
2023-09-19T22:33:50.366Z,1695162830.366 [Default:CheckIn] Running Loop=13
2023-09-19T22:33:50.366Z,1695162830.366 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:33:50.366Z,1695162830.366 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:33:52.361Z,1695162832.361 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223351.00,A,3648.36130,N,12147.12142,W,4.782,7.35,190923,,,D*7E
2023-09-19T22:33:52.364Z,1695162832.364 [NAL9602](INFO): GPS fix at 20230919T223351: (36.806022, -121.785357)
2023-09-19T22:33:52.427Z,1695162832.427 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:33:52.427Z,1695162832.427 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:34:03.789Z,1695162843.789 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0043.lzma
2023-09-19T22:34:04.792Z,1695162844.792 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0043.lzma.bak
2023-09-19T22:34:04.792Z,1695162844.792 [DataOverHttps](INFO): SBD MOMSN=18799837
2023-09-19T22:34:08.953Z,1695162848.953 [NAL9602](INFO): SBD MO Status=0, MOMSN=13564, MT Status=0, MTMSN=0
2023-09-19T22:34:08.953Z,1695162848.953 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:34:24.562Z,1695162864.562 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230919T204619/Express0044.lzma
2023-09-19T22:34:25.564Z,1695162865.564 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0044.lzma.bak
2023-09-19T22:34:25.565Z,1695162865.565 [DataOverHttps](INFO): SBD MOMSN=18799840
2023-09-19T22:34:28.777Z,1695162868.777 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:34:28.777Z,1695162868.777 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:34:28.777Z,1695162868.777 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:34:39.651Z,1695162879.651 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:39:29.346Z,1695163169.346 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:39:29.346Z,1695163169.346 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:39:29.346Z,1695163169.346 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:39:29.347Z,1695163169.347 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:39:29.739Z,1695163169.739 [Default:CheckIn:D] Stopped
2023-09-19T22:39:29.739Z,1695163169.739 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:39:30.139Z,1695163170.139 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.081169 min 
2023-09-19T22:39:30.139Z,1695163170.139 [Default:CheckIn:E] Stopped
2023-09-19T22:39:30.139Z,1695163170.139 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:39:30.139Z,1695163170.139 [Default:CheckIn] Stopped
2023-09-19T22:39:30.139Z,1695163170.139 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:39:30.140Z,1695163170.140 [Default:CheckIn](INFO): Running loop #14
2023-09-19T22:39:30.140Z,1695163170.140 [Default:CheckIn] Running Loop=14
2023-09-19T22:39:30.140Z,1695163170.140 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:39:30.140Z,1695163170.140 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:39:32.150Z,1695163172.150 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223931.00,A,3648.33784,N,12147.56451,W,6.376,260.29,190923,,,D*7A
2023-09-19T22:39:32.152Z,1695163172.152 [NAL9602](INFO): GPS fix at 20230919T223931: (36.805631, -121.792742)
2023-09-19T22:39:32.186Z,1695163172.186 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:39:32.186Z,1695163172.186 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:39:40.265Z,1695163180.265 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0046.lzma
2023-09-19T22:39:42.272Z,1695163182.272 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0046.lzma.bak
2023-09-19T22:39:42.272Z,1695163182.272 [DataOverHttps](INFO): SBD MOMSN=18799844
2023-09-19T22:39:55.305Z,1695163195.305 [NAL9602](INFO): SBD MO Status=0, MOMSN=13565, MT Status=0, MTMSN=0
2023-09-19T22:39:55.305Z,1695163195.305 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:40:05.854Z,1695163205.854 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230919T204619/Express0047.lzma
2023-09-19T22:40:06.856Z,1695163206.856 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0047.lzma.bak
2023-09-19T22:40:06.856Z,1695163206.856 [DataOverHttps](INFO): SBD MOMSN=18799849
2023-09-19T22:40:10.269Z,1695163210.269 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:40:10.269Z,1695163210.269 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:40:10.269Z,1695163210.269 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:40:25.998Z,1695163225.998 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:41:02.135Z,1695163262.135 [DataOverHttps](IMPORTANT): SBD MTMSN=20230919T224101
2023-09-19T22:41:09.638Z,1695163269.638 [DataOverHttps](INFO): Received command: ibit
2023-09-19T22:41:09.676Z,1695163269.676 [CommandExec](IMPORTANT): got command ibit
2023-09-19T22:41:10.057Z,1695163270.057 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-09-19T22:41:10.057Z,1695163270.057 [IBIT](IMPORTANT): Beginning control surface checks.
2023-09-19T22:41:10.060Z,1695163270.060 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-19T22:41:11.657Z,1695163271.657 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224110.00,A,3648.29951,N,12147.80272,W,6.512,264.45,190923,,,D*7D
2023-09-19T22:41:11.659Z,1695163271.659 [NAL9602](INFO): GPS fix at 20230919T224110: (36.804992, -121.796712)
2023-09-19T22:41:12.939Z,1695163272.939 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-09-19T22:41:21.017Z,1695163281.017 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006866
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.005169
CHAN A3 (5V): -0.002460
CHAN B0 (3.3V): -0.001232
CHAN B1 (3.15aV): -0.001368
CHAN B2 (3.15bV): -0.001194
CHAN B3 (GND): -0.000557
OPEN: 0.004149
Full Scale: +/- 1 mA
2023-09-19T22:41:27.408Z,1695163287.408 [NAL9602](INFO): SBD MO Status=0, MOMSN=13566, MT Status=0, MTMSN=0
2023-09-19T22:41:27.408Z,1695163287.408 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:41:28.620Z,1695163288.620 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224127.00,A,3648.29710,N,12147.84407,W,7.775,270.18,190923,,,D*7D
2023-09-19T22:41:28.623Z,1695163288.623 [NAL9602](INFO): GPS fix at 20230919T224127: (36.804952, -121.797401)
2023-09-19T22:41:31.045Z,1695163291.045 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224130.00,A,3648.29696,N,12147.85245,W,8.281,269.44,190923,,,D*75
2023-09-19T22:41:31.047Z,1695163291.047 [NAL9602](INFO): GPS fix at 20230919T224130: (36.804949, -121.797541)
2023-09-19T22:41:33.881Z,1695163293.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224133.00,A,3648.29677,N,12147.86111,W,8.514,269.68,190923,,,D*7D
2023-09-19T22:41:33.883Z,1695163293.883 [NAL9602](INFO): GPS fix at 20230919T224133: (36.804946, -121.797685)
2023-09-19T22:41:37.105Z,1695163297.105 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224136.00,A,3648.29667,N,12147.87032,W,8.728,270.10,190923,,,D*72
2023-09-19T22:41:37.107Z,1695163297.107 [NAL9602](INFO): GPS fix at 20230919T224136: (36.804944, -121.797839)
2023-09-19T22:41:39.937Z,1695163299.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224139.00,A,3648.29662,N,12147.87931,W,8.611,269.99,190923,,,D*70
2023-09-19T22:41:39.948Z,1695163299.948 [NAL9602](INFO): GPS fix at 20230919T224139: (36.804944, -121.797989)
2023-09-19T22:41:43.165Z,1695163303.165 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224142.00,A,3648.29671,N,12147.88851,W,8.650,271.37,190923,,,D*7E
2023-09-19T22:41:43.176Z,1695163303.176 [NAL9602](INFO): GPS fix at 20230919T224142: (36.804945, -121.798142)
2023-09-19T22:41:45.997Z,1695163305.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224145.00,A,3648.29702,N,12147.89747,W,9.253,272.43,190923,,,D*73
2023-09-19T22:41:46.007Z,1695163306.007 [NAL9602](INFO): GPS fix at 20230919T224145: (36.804950, -121.798291)
2023-09-19T22:41:49.225Z,1695163309.225 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224148.00,A,3648.29728,N,12147.90730,W,9.292,271.19,190923,,,D*7F
2023-09-19T22:41:49.227Z,1695163309.227 [NAL9602](INFO): GPS fix at 20230919T224148: (36.804955, -121.798455)
2023-09-19T22:41:52.054Z,1695163312.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224151.00,A,3648.29723,N,12147.91717,W,10.030,268.80,190923,,,D*42
2023-09-19T22:41:52.056Z,1695163312.056 [NAL9602](INFO): GPS fix at 20230919T224151: (36.804954, -121.798620)
2023-09-19T22:41:54.885Z,1695163314.885 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224154.00,A,3648.29698,N,12147.92752,W,9.583,267.94,190923,,,D*7B
2023-09-19T22:41:54.892Z,1695163314.892 [NAL9602](INFO): GPS fix at 20230919T224154: (36.804950, -121.798792)
2023-09-19T22:41:56.526Z,1695163316.526 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.804951 Longitude: -121.798790
2023-09-19T22:41:56.941Z,1695163316.941 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.818000
2023-09-19T22:41:56.941Z,1695163316.941 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-09-19T22:41:56.941Z,1695163316.941 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-09-19T22:41:57.330Z,1695163317.330 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-09-19T22:41:57.330Z,1695163317.330 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-09-19T22:41:57.331Z,1695163317.331 [IBIT](IMPORTANT): Pressure:10.070959 PSI
2023-09-19T22:41:57.331Z,1695163317.331 [IBIT](IMPORTANT): Humidity:29.410326 %
2023-09-19T22:41:57.748Z,1695163317.748 [IBIT](IMPORTANT): Vehicle Pitch:11.972939 degrees
2023-09-19T22:41:57.748Z,1695163317.748 [IBIT](IMPORTANT): Vehicle  Roll:-9.099443 degrees
2023-09-19T22:41:57.748Z,1695163317.748 [IBIT](IMPORTANT): Vehicle  Heading:269.744324 degrees
2023-09-19T22:41:58.132Z,1695163318.132 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-09-19T22:41:58.133Z,1695163318.133 [IBIT](IMPORTANT): buoyancyNeutral: 200.000000 cc
2023-09-19T22:41:58.133Z,1695163318.133 [IBIT](IMPORTANT): massDefault: 1.000000 cm
2023-09-19T22:41:58.133Z,1695163318.133 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2023-09-19T22:41:58.134Z,1695163318.134 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2023-09-19T22:41:58.134Z,1695163318.134 [IBIT](IMPORTANT): IBIT PASSED
2023-09-19T22:42:27.607Z,1695163347.607 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:45:10.831Z,1695163510.831 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:45:10.832Z,1695163510.832 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:45:10.832Z,1695163510.832 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:45:10.832Z,1695163510.832 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:45:11.241Z,1695163511.241 [Default:CheckIn:D] Stopped
2023-09-19T22:45:11.241Z,1695163511.241 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:45:11.643Z,1695163511.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.772868 min 
2023-09-19T22:45:11.643Z,1695163511.643 [Default:CheckIn:E] Stopped
2023-09-19T22:45:11.643Z,1695163511.643 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:45:11.643Z,1695163511.643 [Default:CheckIn] Stopped
2023-09-19T22:45:11.643Z,1695163511.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:45:11.643Z,1695163511.643 [Default:CheckIn](INFO): Running loop #15
2023-09-19T22:45:11.644Z,1695163511.644 [Default:CheckIn] Running Loop=15
2023-09-19T22:45:11.644Z,1695163511.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:45:11.644Z,1695163511.644 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:45:13.658Z,1695163513.658 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224512.00,A,3648.26950,N,12148.61863,W,3.032,263.04,190923,,,D*7F
2023-09-19T22:45:13.660Z,1695163513.660 [NAL9602](INFO): GPS fix at 20230919T224512: (36.804492, -121.810310)
2023-09-19T22:45:13.672Z,1695163513.672 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:45:13.672Z,1695163513.672 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:45:18.670Z,1695163518.670 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230919T204619/Courier0049.lzma
2023-09-19T22:45:19.676Z,1695163519.676 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0049.lzma.bak
2023-09-19T22:45:19.676Z,1695163519.676 [DataOverHttps](INFO): SBD MOMSN=18799952
2023-09-19T22:45:36.429Z,1695163536.429 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T204619/Courier0052.lzma
2023-09-19T22:45:37.432Z,1695163537.432 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0052.lzma.bak
2023-09-19T22:45:37.432Z,1695163537.432 [DataOverHttps](INFO): SBD MOMSN=18799955
2023-09-19T22:46:02.707Z,1695163562.707 [DataOverHttps](INFO): Sending 815 bytes from file Logs/20230919T204619/Express0050.lzma
2023-09-19T22:46:03.708Z,1695163563.708 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0050.lzma.bak
2023-09-19T22:46:03.708Z,1695163563.708 [DataOverHttps](INFO): SBD MOMSN=18799957
2023-09-19T22:46:22.933Z,1695163582.933 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20230919T204619/Express0053.lzma
2023-09-19T22:46:23.936Z,1695163583.936 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0053.lzma.bak
2023-09-19T22:46:23.936Z,1695163583.936 [DataOverHttps](INFO): SBD MOMSN=18799983
2023-09-19T22:46:27.457Z,1695163587.457 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:46:27.457Z,1695163587.457 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:46:27.457Z,1695163587.457 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:46:37.937Z,1695163597.937 [NAL9602](INFO): SBD MO Status=2, MOMSN=13567, MT Status=2, MTMSN=0
2023-09-19T22:46:37.937Z,1695163597.937 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-19T22:47:16.321Z,1695163636.321 [NAL9602](INFO): SBD MO Status=0, MOMSN=13567, MT Status=0, MTMSN=0
2023-09-19T22:47:16.321Z,1695163636.321 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:47:47.023Z,1695163667.023 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:51:28.035Z,1695163888.035 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:51:28.035Z,1695163888.035 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:51:28.035Z,1695163888.035 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:51:28.036Z,1695163888.036 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:51:28.446Z,1695163888.446 [Default:CheckIn:D] Stopped
2023-09-19T22:51:28.457Z,1695163888.457 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:51:28.839Z,1695163888.839 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.059619 min 
2023-09-19T22:51:28.839Z,1695163888.839 [Default:CheckIn:E] Stopped
2023-09-19T22:51:28.839Z,1695163888.839 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:51:28.839Z,1695163888.839 [Default:CheckIn] Stopped
2023-09-19T22:51:28.840Z,1695163888.840 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:51:28.840Z,1695163888.840 [Default:CheckIn](INFO): Running loop #16
2023-09-19T22:51:28.840Z,1695163888.840 [Default:CheckIn] Running Loop=16
2023-09-19T22:51:28.840Z,1695163888.840 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:51:28.840Z,1695163888.840 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:51:30.849Z,1695163890.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225130.00,A,3648.39551,N,12148.88577,W,2.158,266.32,190923,,,D*7A
2023-09-19T22:51:30.851Z,1695163890.851 [NAL9602](INFO): GPS fix at 20230919T225130: (36.806592, -121.814763)
2023-09-19T22:51:30.919Z,1695163890.919 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:51:30.919Z,1695163890.919 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:51:34.492Z,1695163894.492 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0055.lzma
2023-09-19T22:51:35.500Z,1695163895.500 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0055.lzma.bak
2023-09-19T22:51:35.500Z,1695163895.500 [DataOverHttps](INFO): SBD MOMSN=18800009
2023-09-19T22:51:51.971Z,1695163911.971 [NAL9602](INFO): SBD MO Status=0, MOMSN=13568, MT Status=0, MTMSN=0
2023-09-19T22:51:51.971Z,1695163911.971 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:52:02.162Z,1695163922.162 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230919T204619/Express0056.lzma
2023-09-19T22:52:03.164Z,1695163923.164 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0056.lzma.bak
2023-09-19T22:52:03.164Z,1695163923.164 [DataOverHttps](INFO): SBD MOMSN=18800012
2023-09-19T22:52:06.638Z,1695163926.638 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:52:06.638Z,1695163926.638 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:52:06.638Z,1695163926.638 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:52:22.739Z,1695163942.739 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T22:57:07.221Z,1695164227.221 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T22:57:07.221Z,1695164227.221 [Default:CheckIn:C.Wait] Stopped
2023-09-19T22:57:07.221Z,1695164227.221 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T22:57:07.222Z,1695164227.222 [Default:CheckIn:D] Running Loop=1
2023-09-19T22:57:07.602Z,1695164227.602 [Default:CheckIn:D] Stopped
2023-09-19T22:57:07.602Z,1695164227.602 [Default:CheckIn:E] Running Loop=1
2023-09-19T22:57:08.025Z,1695164228.025 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.712231 min 
2023-09-19T22:57:08.025Z,1695164228.025 [Default:CheckIn:E] Stopped
2023-09-19T22:57:08.025Z,1695164228.025 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T22:57:08.025Z,1695164228.025 [Default:CheckIn] Stopped
2023-09-19T22:57:08.025Z,1695164228.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T22:57:08.026Z,1695164228.026 [Default:CheckIn](INFO): Running loop #17
2023-09-19T22:57:08.026Z,1695164228.026 [Default:CheckIn] Running Loop=17
2023-09-19T22:57:08.026Z,1695164228.026 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T22:57:08.030Z,1695164228.030 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T22:57:10.017Z,1695164230.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225709.00,A,3648.50929,N,12147.16920,W,7.173,83.32,190923,,,D*49
2023-09-19T22:57:10.019Z,1695164230.019 [NAL9602](INFO): GPS fix at 20230919T225709: (36.808488, -121.786153)
2023-09-19T22:57:10.060Z,1695164230.060 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T22:57:10.060Z,1695164230.060 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T22:57:11.894Z,1695164231.894 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0058.lzma
2023-09-19T22:57:12.900Z,1695164232.900 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0058.lzma.bak
2023-09-19T22:57:12.900Z,1695164232.900 [DataOverHttps](INFO): SBD MOMSN=18800105
2023-09-19T22:57:26.581Z,1695164246.581 [NAL9602](INFO): SBD MO Status=0, MOMSN=13569, MT Status=0, MTMSN=0
2023-09-19T22:57:26.581Z,1695164246.581 [NAL9602](INFO): No messages in MT queue
2023-09-19T22:57:30.970Z,1695164250.970 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230919T204619/Express0059.lzma
2023-09-19T22:57:31.972Z,1695164251.972 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0059.lzma.bak
2023-09-19T22:57:31.972Z,1695164251.972 [DataOverHttps](INFO): SBD MOMSN=18800108
2023-09-19T22:57:35.478Z,1695164255.478 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T22:57:35.478Z,1695164255.478 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T22:57:35.478Z,1695164255.478 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T22:57:57.323Z,1695164277.323 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T23:02:36.110Z,1695164556.110 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T23:02:36.111Z,1695164556.111 [Default:CheckIn:C.Wait] Stopped
2023-09-19T23:02:36.111Z,1695164556.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T23:02:36.111Z,1695164556.111 [Default:CheckIn:D] Running Loop=1
2023-09-19T23:02:36.512Z,1695164556.512 [Default:CheckIn:D] Stopped
2023-09-19T23:02:36.512Z,1695164556.512 [Default:CheckIn:E] Running Loop=1
2023-09-19T23:02:36.926Z,1695164556.926 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.194059 min 
2023-09-19T23:02:36.926Z,1695164556.926 [Default:CheckIn:E] Stopped
2023-09-19T23:02:36.926Z,1695164556.926 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T23:02:36.926Z,1695164556.926 [Default:CheckIn] Stopped
2023-09-19T23:02:36.926Z,1695164556.926 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T23:02:36.926Z,1695164556.926 [Default:CheckIn](INFO): Running loop #18
2023-09-19T23:02:36.926Z,1695164556.926 [Default:CheckIn] Running Loop=18
2023-09-19T23:02:36.926Z,1695164556.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T23:02:36.927Z,1695164556.927 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T23:02:38.921Z,1695164558.921 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230238.00,A,3648.14875,N,12147.22159,W,0.078,217.06,190923,,,D*76
2023-09-19T23:02:38.923Z,1695164558.923 [NAL9602](INFO): GPS fix at 20230919T230238: (36.802479, -121.787026)
2023-09-19T23:02:38.936Z,1695164558.936 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T23:02:38.936Z,1695164558.936 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T23:02:47.917Z,1695164567.917 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0061.lzma
2023-09-19T23:02:50.195Z,1695164570.195 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0061.lzma.bak
2023-09-19T23:02:50.195Z,1695164570.195 [DataOverHttps](INFO): SBD MOMSN=18800141
2023-09-19T23:02:52.253Z,1695164572.253 [NAL9602](INFO): SBD MO Status=0, MOMSN=13570, MT Status=0, MTMSN=0
2023-09-19T23:02:52.253Z,1695164572.253 [NAL9602](INFO): No messages in MT queue
2023-09-19T23:03:07.110Z,1695164587.110 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20230919T204619/Express0062.lzma
2023-09-19T23:03:08.112Z,1695164588.112 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0062.lzma.bak
2023-09-19T23:03:08.112Z,1695164588.112 [DataOverHttps](INFO): SBD MOMSN=18800154
2023-09-19T23:03:11.699Z,1695164591.699 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T23:03:11.699Z,1695164591.699 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T23:03:11.699Z,1695164591.699 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T23:03:22.956Z,1695164602.956 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T23:08:12.423Z,1695164892.423 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T23:08:12.423Z,1695164892.423 [Default:CheckIn:C.Wait] Stopped
2023-09-19T23:08:12.423Z,1695164892.423 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T23:08:12.423Z,1695164892.423 [Default:CheckIn:D] Running Loop=1
2023-09-19T23:08:12.825Z,1695164892.825 [Default:CheckIn:D] Stopped
2023-09-19T23:08:12.825Z,1695164892.825 [Default:CheckIn:E] Running Loop=1
2023-09-19T23:08:13.382Z,1695164893.382 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.799268 min 
2023-09-19T23:08:13.382Z,1695164893.382 [Default:CheckIn:E] Stopped
2023-09-19T23:08:13.382Z,1695164893.382 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T23:08:13.382Z,1695164893.382 [Default:CheckIn] Stopped
2023-09-19T23:08:13.382Z,1695164893.382 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T23:08:13.383Z,1695164893.383 [Default:CheckIn](INFO): Running loop #19
2023-09-19T23:08:13.383Z,1695164893.383 [Default:CheckIn] Running Loop=19
2023-09-19T23:08:13.383Z,1695164893.383 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T23:08:13.383Z,1695164893.383 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T23:08:15.262Z,1695164895.262 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230814.00,A,3648.23221,N,12147.14849,W,4.587,24.52,190923,,,D*42
2023-09-19T23:08:15.264Z,1695164895.264 [NAL9602](INFO): GPS fix at 20230919T230814: (36.803870, -121.785808)
2023-09-19T23:08:15.306Z,1695164895.306 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T23:08:15.306Z,1695164895.306 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T23:08:23.609Z,1695164903.609 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0064.lzma
2023-09-19T23:08:24.612Z,1695164904.612 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0064.lzma.bak
2023-09-19T23:08:24.612Z,1695164904.612 [DataOverHttps](INFO): SBD MOMSN=18800229
2023-09-19T23:08:33.033Z,1695164913.033 [NAL9602](INFO): SBD MO Status=0, MOMSN=13571, MT Status=0, MTMSN=0
2023-09-19T23:08:33.033Z,1695164913.033 [NAL9602](INFO): No messages in MT queue
2023-09-19T23:08:42.730Z,1695164922.730 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230919T204619/Express0065.lzma
2023-09-19T23:08:43.732Z,1695164923.732 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0065.lzma.bak
2023-09-19T23:08:43.732Z,1695164923.732 [DataOverHttps](INFO): SBD MOMSN=18800233
2023-09-19T23:08:47.181Z,1695164927.181 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T23:08:47.181Z,1695164927.181 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T23:08:47.181Z,1695164927.181 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T23:08:52.427Z,1695164932.427 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-19T23:08:52.430Z,1695164932.430 [BPC1](INFO): Received data from all battery sticks.
2023-09-19T23:09:03.719Z,1695164943.719 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T23:13:47.835Z,1695165227.835 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T23:13:47.835Z,1695165227.835 [Default:CheckIn:C.Wait] Stopped
2023-09-19T23:13:47.835Z,1695165227.835 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T23:13:47.835Z,1695165227.835 [Default:CheckIn:D] Running Loop=1
2023-09-19T23:13:48.209Z,1695165228.209 [Default:CheckIn:D] Stopped
2023-09-19T23:13:48.209Z,1695165228.209 [Default:CheckIn:E] Running Loop=1
2023-09-19T23:13:48.615Z,1695165228.615 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.389014 min 
2023-09-19T23:13:48.615Z,1695165228.615 [Default:CheckIn:E] Stopped
2023-09-19T23:13:48.616Z,1695165228.616 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T23:13:48.616Z,1695165228.616 [Default:CheckIn] Stopped
2023-09-19T23:13:48.616Z,1695165228.616 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T23:13:48.616Z,1695165228.616 [Default:CheckIn](INFO): Running loop #20
2023-09-19T23:13:48.616Z,1695165228.616 [Default:CheckIn] Running Loop=20
2023-09-19T23:13:48.616Z,1695165228.616 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T23:13:48.616Z,1695165228.616 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T23:13:50.622Z,1695165230.622 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231349.00,A,3648.37394,N,12147.49142,W,10.069,234.38,190923,,,D*4F
2023-09-19T23:13:50.624Z,1695165230.624 [NAL9602](INFO): GPS fix at 20230919T231349: (36.806232, -121.791524)
2023-09-19T23:13:50.644Z,1695165230.644 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T23:13:50.644Z,1695165230.644 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T23:14:03.929Z,1695165243.929 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230919T204619/Courier0067.lzma
2023-09-19T23:14:04.932Z,1695165244.932 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0067.lzma.bak
2023-09-19T23:14:04.932Z,1695165244.932 [DataOverHttps](INFO): SBD MOMSN=18800349
2023-09-19T23:14:05.229Z,1695165245.229 [NAL9602](INFO): SBD MO Status=0, MOMSN=13572, MT Status=0, MTMSN=0
2023-09-19T23:14:05.229Z,1695165245.229 [NAL9602](INFO): No messages in MT queue
2023-09-19T23:14:22.890Z,1695165262.890 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230919T204619/Express0068.lzma
2023-09-19T23:14:23.892Z,1695165263.892 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0068.lzma.bak
2023-09-19T23:14:23.892Z,1695165263.892 [DataOverHttps](INFO): SBD MOMSN=18800352
2023-09-19T23:14:27.427Z,1695165267.427 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T23:14:27.427Z,1695165267.427 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T23:14:27.427Z,1695165267.427 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T23:14:35.881Z,1695165275.881 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T23:19:28.021Z,1695165568.021 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T23:19:28.021Z,1695165568.021 [Default:CheckIn:C.Wait] Stopped
2023-09-19T23:19:28.021Z,1695165568.021 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T23:19:28.021Z,1695165568.021 [Default:CheckIn:D] Running Loop=1
2023-09-19T23:19:28.426Z,1695165568.426 [Default:CheckIn:D] Stopped
2023-09-19T23:19:28.426Z,1695165568.426 [Default:CheckIn:E] Running Loop=1
2023-09-19T23:19:28.836Z,1695165568.836 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.059294 min 
2023-09-19T23:19:28.836Z,1695165568.836 [Default:CheckIn:E] Stopped
2023-09-19T23:19:28.836Z,1695165568.836 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T23:19:28.836Z,1695165568.836 [Default:CheckIn] Stopped
2023-09-19T23:19:28.836Z,1695165568.836 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T23:19:28.837Z,1695165568.837 [Default:CheckIn](INFO): Running loop #21
2023-09-19T23:19:28.837Z,1695165568.837 [Default:CheckIn] Running Loop=21
2023-09-19T23:19:28.837Z,1695165568.837 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T23:19:28.837Z,1695165568.837 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T23:19:30.845Z,1695165570.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231930.00,A,3648.34579,N,12149.22137,W,14.268,264.25,190923,,,D*42
2023-09-19T23:19:30.856Z,1695165570.856 [NAL9602](INFO): GPS fix at 20230919T231930: (36.805763, -121.820356)
2023-09-19T23:19:30.868Z,1695165570.868 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T23:19:30.868Z,1695165570.868 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T23:19:39.350Z,1695165579.350 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0070.lzma
2023-09-19T23:19:40.352Z,1695165580.352 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0070.lzma.bak
2023-09-19T23:19:40.352Z,1695165580.352 [DataOverHttps](INFO): SBD MOMSN=18800356
2023-09-19T23:19:47.022Z,1695165587.022 [NAL9602](INFO): SBD MO Status=0, MOMSN=13573, MT Status=0, MTMSN=0
2023-09-19T23:19:47.022Z,1695165587.022 [NAL9602](INFO): No messages in MT queue
2023-09-19T23:20:02.702Z,1695165602.702 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230919T204619/Express0071.lzma
2023-09-19T23:20:03.704Z,1695165603.704 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0071.lzma.bak
2023-09-19T23:20:03.704Z,1695165603.704 [DataOverHttps](INFO): SBD MOMSN=18800359
2023-09-19T23:20:06.893Z,1695165606.893 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T23:20:06.893Z,1695165606.893 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T23:20:06.893Z,1695165606.893 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T23:20:17.746Z,1695165617.746 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T23:25:07.464Z,1695165907.464 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T23:25:07.464Z,1695165907.464 [Default:CheckIn:C.Wait] Stopped
2023-09-19T23:25:07.465Z,1695165907.465 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T23:25:07.465Z,1695165907.465 [Default:CheckIn:D] Running Loop=1
2023-09-19T23:25:07.865Z,1695165907.865 [Default:CheckIn:D] Stopped
2023-09-19T23:25:07.865Z,1695165907.865 [Default:CheckIn:E] Running Loop=1
2023-09-19T23:25:08.245Z,1695165908.245 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.716602 min 
2023-09-19T23:25:08.245Z,1695165908.245 [Default:CheckIn:E] Stopped
2023-09-19T23:25:08.245Z,1695165908.245 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T23:25:08.245Z,1695165908.245 [Default:CheckIn] Stopped
2023-09-19T23:25:08.245Z,1695165908.245 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T23:25:08.245Z,1695165908.245 [Default:CheckIn](INFO): Running loop #22
2023-09-19T23:25:08.245Z,1695165908.245 [Default:CheckIn] Running Loop=22
2023-09-19T23:25:08.245Z,1695165908.245 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T23:25:08.245Z,1695165908.245 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T23:25:10.259Z,1695165910.259 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232509.00,A,3648.35854,N,12149.26961,W,0.739,340.34,190923,,,D*78
2023-09-19T23:25:10.261Z,1695165910.261 [NAL9602](INFO): GPS fix at 20230919T232509: (36.805976, -121.821160)
2023-09-19T23:25:10.273Z,1695165910.273 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T23:25:10.273Z,1695165910.273 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T23:25:19.625Z,1695165919.625 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0073.lzma
2023-09-19T23:25:20.628Z,1695165920.628 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0073.lzma.bak
2023-09-19T23:25:20.628Z,1695165920.628 [DataOverHttps](INFO): SBD MOMSN=18800464
2023-09-19T23:25:25.601Z,1695165925.601 [NAL9602](INFO): SBD MO Status=0, MOMSN=13574, MT Status=0, MTMSN=0
2023-09-19T23:25:25.601Z,1695165925.601 [NAL9602](INFO): No messages in MT queue
2023-09-19T23:25:40.562Z,1695165940.562 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230919T204619/Express0074.lzma
2023-09-19T23:25:41.564Z,1695165941.564 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0074.lzma.bak
2023-09-19T23:25:41.564Z,1695165941.564 [DataOverHttps](INFO): SBD MOMSN=18800467
2023-09-19T23:25:46.677Z,1695165946.677 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T23:25:46.677Z,1695165946.677 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T23:25:46.677Z,1695165946.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T23:25:56.420Z,1695165956.420 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T23:28:17.610Z,1695166097.610 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2023-09-19T23:28:18.614Z,1695166098.614 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.099293
2023-09-19T23:28:40.982Z,1695166120.982 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003154
2023-09-19T23:30:47.355Z,1695166247.355 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T23:30:47.355Z,1695166247.355 [Default:CheckIn:C.Wait] Stopped
2023-09-19T23:30:47.355Z,1695166247.355 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T23:30:47.355Z,1695166247.355 [Default:CheckIn:D] Running Loop=1
2023-09-19T23:30:47.785Z,1695166247.785 [Default:CheckIn:D] Stopped
2023-09-19T23:30:47.785Z,1695166247.785 [Default:CheckIn:E] Running Loop=1
2023-09-19T23:30:48.187Z,1695166248.187 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.381934 min 
2023-09-19T23:30:48.187Z,1695166248.187 [Default:CheckIn:E] Stopped
2023-09-19T23:30:48.188Z,1695166248.188 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T23:30:48.188Z,1695166248.188 [Default:CheckIn] Stopped
2023-09-19T23:30:48.188Z,1695166248.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T23:30:48.188Z,1695166248.188 [Default:CheckIn](INFO): Running loop #23
2023-09-19T23:30:48.188Z,1695166248.188 [Default:CheckIn] Running Loop=23
2023-09-19T23:30:48.188Z,1695166248.188 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T23:30:48.188Z,1695166248.188 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T23:30:50.154Z,1695166250.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233049.00,A,3648.39783,N,12149.27075,W,1.613,346.88,190923,,,D*75
2023-09-19T23:30:50.156Z,1695166250.156 [NAL9602](INFO): GPS fix at 20230919T233049: (36.806630, -121.821179)
2023-09-19T23:30:50.168Z,1695166250.168 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T23:30:50.169Z,1695166250.169 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T23:30:58.266Z,1695166258.266 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230919T204619/Courier0076.lzma
2023-09-19T23:30:59.268Z,1695166259.268 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Courier0076.lzma.bak
2023-09-19T23:30:59.268Z,1695166259.268 [DataOverHttps](INFO): SBD MOMSN=18800471
2023-09-19T23:31:01.077Z,1695166261.077 [NAL9602](INFO): SBD MO Status=0, MOMSN=13575, MT Status=0, MTMSN=0
2023-09-19T23:31:01.077Z,1695166261.077 [NAL9602](INFO): No messages in MT queue
2023-09-19T23:31:19.218Z,1695166279.218 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230919T204619/Express0077.lzma
2023-09-19T23:31:20.220Z,1695166280.220 [DataOverHttps](INFO): Moved sent file to Logs/20230919T204619/Express0077.lzma.bak
2023-09-19T23:31:20.220Z,1695166280.220 [DataOverHttps](INFO): SBD MOMSN=18800474
2023-09-19T23:31:23.733Z,1695166283.733 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T23:31:23.733Z,1695166283.733 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T23:31:23.733Z,1695166283.733 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T23:31:31.783Z,1695166291.783 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-19T23:32:51.199Z,1695166371.199 [DataOverHttps](IMPORTANT): SBD MTMSN=20230919T233250
2023-09-19T23:32:58.538Z,1695166378.538 [DataOverHttps](INFO): Received command: restart logs