2022-06-06T16:25:59.305Z,1654532759.305 [Supervisor](DEBUG): Initializing supervisor.
2022-06-06T16:25:59.309Z,1654532759.309 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-06-06T16:25:59.310Z,1654532759.310 [SyncHandler](INFO): Protected caller Thread ID is 834
2022-06-06T16:25:59.310Z,1654532759.310 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-06-06T16:25:59.311Z,1654532759.311 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-06-06T16:25:59.312Z,1654532759.312 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835
2022-06-06T16:25:59.316Z,1654532759.316 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-06-06T16:25:59.334Z,1654532759.334 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-06-06T16:25:59.335Z,1654532759.335 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-06-06T16:25:59.335Z,1654532759.335 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836
2022-06-06T16:25:59.339Z,1654532759.339 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-06-06T16:25:59.340Z,1654532759.340 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-06-06T16:25:59.341Z,1654532759.341 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
2022-06-06T16:25:59.343Z,1654532759.343 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-06-06T16:25:59.344Z,1654532759.344 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-06-06T16:25:59.344Z,1654532759.344 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
2022-06-06T16:25:59.348Z,1654532759.348 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-06-06T16:25:59.348Z,1654532759.348 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-06-06T16:25:59.352Z,1654532759.352 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-06-06T16:25:59.607Z,1654532759.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-06-06T16:25:59.609Z,1654532759.609 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-06-06T16:25:59.691Z,1654532759.691 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-06-06T16:26:00.260Z,1654532760.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-06-06T16:26:00.262Z,1654532760.262 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-06-06T16:26:00.587Z,1654532760.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-06-06T16:26:00.588Z,1654532760.588 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-06-06T16:26:00.685Z,1654532760.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-06-06T16:26:00.687Z,1654532760.687 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-06-06T16:26:01.053Z,1654532761.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-06-06T16:26:01.055Z,1654532761.055 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-06-06T16:26:01.267Z,1654532761.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-06-06T16:26:01.267Z,1654532761.267 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-06-06T16:26:01.722Z,1654532761.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-06-06T16:26:01.723Z,1654532761.723 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-06-06T16:26:01.852Z,1654532761.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-06-06T16:26:01.854Z,1654532761.854 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-06-06T16:26:01.934Z,1654532761.934 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-06-06T16:26:03.090Z,1654532763.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-06-06T16:26:03.092Z,1654532763.092 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-06-06T16:26:03.458Z,1654532763.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-06-06T16:26:03.459Z,1654532763.459 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-06-06T16:26:03.649Z,1654532763.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-06-06T16:26:03.651Z,1654532763.651 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-06-06T16:26:03.762Z,1654532763.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-06-06T16:26:03.762Z,1654532763.762 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-06-06T16:26:03.984Z,1654532763.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-06-06T16:26:03.985Z,1654532763.985 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-06-06T16:26:04.213Z,1654532764.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-06-06T16:26:04.214Z,1654532764.214 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2022-06-06T16:26:04.218Z,1654532764.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2022-06-06T16:26:04.310Z,1654532764.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2022-06-06T16:26:04.396Z,1654532764.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2022-06-06T16:26:04.500Z,1654532764.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2022-06-06T16:26:04.584Z,1654532764.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2022-06-06T16:26:04.682Z,1654532764.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2022-06-06T16:26:04.785Z,1654532764.785 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2022-06-06T16:26:04.974Z,1654532764.974 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2022-06-06T16:26:05.050Z,1654532765.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2022-06-06T16:26:05.198Z,1654532765.198 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2022-06-06T16:26:05.335Z,1654532765.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2022-06-06T16:26:05.451Z,1654532765.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2022-06-06T16:26:05.713Z,1654532765.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-06-06T16:26:05.713Z,1654532765.713 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-06-06T16:26:05.728Z,1654532765.728 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-06-06T16:26:05.798Z,1654532765.798 [VerticalControl](DEBUG): Construct VerticalControl.
2022-06-06T16:26:05.859Z,1654532765.859 [VerticalControl] Loaded
2022-06-06T16:26:05.859Z,1654532765.859 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-06-06T16:26:05.862Z,1654532765.862 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-06-06T16:26:05.901Z,1654532765.901 [HorizontalControl] Loaded
2022-06-06T16:26:05.901Z,1654532765.901 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-06-06T16:26:05.903Z,1654532765.903 [SpeedControl](DEBUG): Construct SpeedControl.
2022-06-06T16:26:05.906Z,1654532765.906 [SpeedControl] Loaded
2022-06-06T16:26:05.906Z,1654532765.906 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-06-06T16:26:05.909Z,1654532765.909 [LoopControl](DEBUG): Construct LoopControl.
2022-06-06T16:26:05.909Z,1654532765.909 [LoopControl] Loaded
2022-06-06T16:26:05.910Z,1654532765.910 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-06-06T16:26:05.910Z,1654532765.910 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-06-06T16:26:05.912Z,1654532765.912 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-06-06T16:26:05.928Z,1654532765.928 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-06-06T16:26:05.928Z,1654532765.928 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-06-06T16:26:06.069Z,1654532766.069 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-06-06T16:26:06.070Z,1654532766.070 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-06-06T16:26:06.243Z,1654532766.243 [BuoyancyServo] Loaded
2022-06-06T16:26:06.243Z,1654532766.243 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-06-06T16:26:06.262Z,1654532766.262 [ElevatorServo] Loaded
2022-06-06T16:26:06.263Z,1654532766.263 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-06-06T16:26:06.281Z,1654532766.281 [MassServo] Loaded
2022-06-06T16:26:06.281Z,1654532766.281 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-06-06T16:26:06.299Z,1654532766.299 [RudderServo] Loaded
2022-06-06T16:26:06.299Z,1654532766.299 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-06-06T16:26:06.316Z,1654532766.316 [ThrusterServo] Loaded
2022-06-06T16:26:06.317Z,1654532766.317 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-06-06T16:26:06.317Z,1654532766.317 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-06-06T16:26:06.318Z,1654532766.318 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-06-06T16:26:06.455Z,1654532766.455 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-06-06T16:26:06.455Z,1654532766.455 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-06-06T16:26:06.468Z,1654532766.468 [NavChart] Loaded
2022-06-06T16:26:06.469Z,1654532766.469 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-06-06T16:26:06.474Z,1654532766.474 [UniversalFixResidualReporter] Loaded
2022-06-06T16:26:06.474Z,1654532766.474 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-06-06T16:26:06.475Z,1654532766.475 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-06-06T16:26:06.476Z,1654532766.476 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-06-06T16:26:06.559Z,1654532766.559 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-06-06T16:26:06.560Z,1654532766.560 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-06-06T16:26:07.485Z,1654532767.485 [AHRS_M2] Loaded
2022-06-06T16:26:07.486Z,1654532767.486 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-06-06T16:26:08.300Z,1654532768.300 [BPC1] Loaded
2022-06-06T16:26:08.301Z,1654532768.301 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-06-06T16:26:08.597Z,1654532768.597 [DataOverHttps] Loaded
2022-06-06T16:26:08.597Z,1654532768.597 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-06-06T16:26:08.631Z,1654532768.631 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408F14E0
2022-06-06T16:26:08.632Z,1654532768.632 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2022-06-06T16:26:08.670Z,1654532768.670 [Depth_Keller] Loaded
2022-06-06T16:26:08.671Z,1654532768.671 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-06-06T16:26:08.848Z,1654532768.848 [NAL9602] Loaded
2022-06-06T16:26:08.848Z,1654532768.848 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-06-06T16:26:08.929Z,1654532768.929 [Onboard] Loaded
2022-06-06T16:26:08.929Z,1654532768.929 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-06-06T16:26:08.930Z,1654532768.930 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409214E0
2022-06-06T16:26:08.930Z,1654532768.930 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2022-06-06T16:26:08.954Z,1654532768.954 [Power24vConverter] Loaded
2022-06-06T16:26:08.955Z,1654532768.955 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-06-06T16:26:09.019Z,1654532769.019 [Radio_Surface] Loaded
2022-06-06T16:26:09.019Z,1654532769.019 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-06-06T16:26:09.035Z,1654532769.035 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409514E0
2022-06-06T16:26:09.036Z,1654532769.036 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2022-06-06T16:26:09.037Z,1654532769.037 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-06-06T16:26:09.037Z,1654532769.037 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-06-06T16:26:09.459Z,1654532769.459 [DepthRateCalculator] Loaded
2022-06-06T16:26:09.459Z,1654532769.459 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-06-06T16:26:09.464Z,1654532769.464 [PitchRateCalculator] Loaded
2022-06-06T16:26:09.464Z,1654532769.464 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-06-06T16:26:09.473Z,1654532769.473 [SpeedCalculator] Loaded
2022-06-06T16:26:09.473Z,1654532769.473 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-06-06T16:26:09.478Z,1654532769.478 [YawRateCalculator] Loaded
2022-06-06T16:26:09.478Z,1654532769.478 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-06-06T16:26:09.496Z,1654532769.496 [ElevatorOffsetCalculator] Loaded
2022-06-06T16:26:09.496Z,1654532769.496 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-06-06T16:26:09.497Z,1654532769.497 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-06-06T16:26:09.497Z,1654532769.497 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-06-06T16:26:09.673Z,1654532769.673 [CANONSampler] Loaded
2022-06-06T16:26:09.673Z,1654532769.673 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2022-06-06T16:26:09.733Z,1654532769.733 [CTD_Seabird] Loaded
2022-06-06T16:26:09.733Z,1654532769.733 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-06-06T16:26:09.734Z,1654532769.734 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A094E0
2022-06-06T16:26:09.734Z,1654532769.734 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2022-06-06T16:26:09.754Z,1654532769.754 [PAR_Licor] Loaded
2022-06-06T16:26:09.754Z,1654532769.754 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-06-06T16:26:09.785Z,1654532769.785 [WetLabsBB2FL] Loaded
2022-06-06T16:26:09.786Z,1654532769.786 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-06-06T16:26:09.787Z,1654532769.787 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A394E0
2022-06-06T16:26:09.787Z,1654532769.787 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2022-06-06T16:26:09.788Z,1654532769.788 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-06-06T16:26:09.788Z,1654532769.788 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-06-06T16:26:09.831Z,1654532769.831 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-06-06T16:26:09.832Z,1654532769.832 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-06-06T16:26:10.208Z,1654532770.208 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-06-06T16:26:10.210Z,1654532770.210 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-06-06T16:26:10.378Z,1654532770.378 [SBIT](DEBUG): Construct Startup Built In Test.
2022-06-06T16:26:10.387Z,1654532770.387 [SBIT] Loaded
2022-06-06T16:26:10.387Z,1654532770.387 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-06-06T16:26:10.390Z,1654532770.390 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-06-06T16:26:10.403Z,1654532770.403 [IBIT] Loaded
2022-06-06T16:26:10.403Z,1654532770.403 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-06-06T16:26:10.409Z,1654532770.409 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-06-06T16:26:10.513Z,1654532770.513 [CBIT] Loaded
2022-06-06T16:26:10.513Z,1654532770.513 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-06-06T16:26:10.514Z,1654532770.514 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-06-06T16:26:10.514Z,1654532770.514 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-06-06T16:26:10.632Z,1654532770.632 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-06-06T16:26:10.638Z,1654532770.638 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-06-06T16:26:10.641Z,1654532770.641 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-06-06T16:26:10.652Z,1654532770.652 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-06-06T16:26:10.653Z,1654532770.653 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B754E0
2022-06-06T16:26:10.653Z,1654532770.653 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2022-06-06T16:26:10.658Z,1654532770.658 [Supervisor](INFO): Main Thread ID is 829
2022-06-06T16:26:10.658Z,1654532770.658 [Supervisor](DEBUG): Running supervisor.
2022-06-06T16:26:10.659Z,1654532770.659 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2022-06-06T16:26:10.659Z,1654532770.659 [CommandExec](INFO): Initializing the command executive.
2022-06-06T16:26:10.661Z,1654532770.661 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2022-06-06T16:26:10.663Z,1654532770.663 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2022-06-06T16:26:10.663Z,1654532770.663 [controlThread](DEBUG): Initializing ControlThread
2022-06-06T16:26:10.664Z,1654532770.664 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-06-06T16:26:10.666Z,1654532770.666 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-06-06T16:26:10.666Z,1654532770.666 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-06-06T16:26:10.667Z,1654532770.667 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-06-06T16:26:10.669Z,1654532770.669 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-06-06T16:26:10.669Z,1654532770.669 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-06-06T16:26:10.672Z,1654532770.672 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-06-06T16:26:10.672Z,1654532770.672 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-06-06T16:26:10.673Z,1654532770.673 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-06-06T16:26:10.673Z,1654532770.673 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-06-06T16:26:10.673Z,1654532770.673 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-06-06T16:26:10.676Z,1654532770.676 [SBIT](INFO): Initialize SBIT Component.
2022-06-06T16:26:10.676Z,1654532770.676 [SBIT](IMPORTANT): git: 2022-04-11
2022-06-06T16:26:10.676Z,1654532770.676 [SBIT](INFO): git hash: 059cb0c4bb1d2c42f72e645cf8ce57fde1aeb3d9
2022-06-06T16:26:10.677Z,1654532770.677 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-06-06T16:26:10.678Z,1654532770.678 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-06-06T16:26:10.679Z,1654532770.679 [SBIT](INFO): Beginning SBIT in 72.000000 seconds.
2022-06-06T16:26:10.679Z,1654532770.679 [IBIT](INFO): Initialize IBIT Component.
2022-06-06T16:26:10.680Z,1654532770.680 [CBIT](DEBUG): Initialize CBIT Component.
2022-06-06T16:26:10.681Z,1654532770.681 [logger ThreadHandler](INFO): Handler Thread ID is 933
2022-06-06T16:26:10.691Z,1654532770.691 [CBIT](DEBUG): Initialized mux pins.
2022-06-06T16:26:10.692Z,1654532770.692 [CBIT](DEBUG): Initializing the watchdog timer.
2022-06-06T16:26:10.700Z,1654532770.700 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2022-06-06T16:26:10.701Z,1654532770.701 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-06-06T16:26:10.712Z,1654532770.712 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2022-06-06T16:26:10.716Z,1654532770.716 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-06-06T16:26:10.716Z,1654532770.716 [CBIT](DEBUG): Initializing heartbeat.
2022-06-06T16:26:10.733Z,1654532770.733 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2022-06-06T16:26:10.748Z,1654532770.748 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 937
2022-06-06T16:26:10.748Z,1654532770.748 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-06-06T16:26:10.753Z,1654532770.753 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 939
2022-06-06T16:26:10.756Z,1654532770.756 [WetLabsBB2FL](INFO): Powering up
2022-06-06T16:26:10.757Z,1654532770.757 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941
2022-06-06T16:26:10.766Z,1654532770.766 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-06-06T16:26:10.766Z,1654532770.766 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-06-06T16:26:10.766Z,1654532770.766 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-06-06T16:26:10.766Z,1654532770.766 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-06-06T16:26:10.766Z,1654532770.766 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-06-06T16:26:10.767Z,1654532770.767 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-06-06T16:26:10.767Z,1654532770.767 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-06-06T16:26:10.767Z,1654532770.767 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-06-06T16:26:10.767Z,1654532770.767 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-06-06T16:26:10.768Z,1654532770.768 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-06-06T16:26:10.768Z,1654532770.768 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-06-06T16:26:10.768Z,1654532770.768 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-06-06T16:26:10.768Z,1654532770.768 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-06-06T16:26:10.768Z,1654532770.768 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-06-06T16:26:10.768Z,1654532770.768 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-06-06T16:26:10.768Z,1654532770.768 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-06-06T16:26:10.788Z,1654532770.788 [CBIT](DEBUG): Deactivating GF circuits.
2022-06-06T16:26:10.788Z,1654532770.788 [CBIT](DEBUG): Deactivating emergency mode.
2022-06-06T16:26:10.823Z,1654532770.823 [CBIT](DEBUG): Backplane powered.
2022-06-06T16:26:10.824Z,1654532770.824 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-06-06T16:26:10.849Z,1654532770.849 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-06-06T16:26:10.881Z,1654532770.881 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2022-06-06T16:26:10.882Z,1654532770.882 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-06-06T16:26:10.957Z,1654532770.957 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-06-06T16:26:10.959Z,1654532770.959 [Default:A.Wait](DEBUG): Construct Wait.
2022-06-06T16:26:10.965Z,1654532770.965 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-06-06T16:26:10.997Z,1654532770.997 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-06-06T16:26:11.016Z,1654532771.016 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-06-06T16:26:11.021Z,1654532771.021 [Default:E.Execute](DEBUG): Construct Execute.
2022-06-06T16:26:11.041Z,1654532771.041 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2022-06-06T16:26:11.045Z,1654532771.045 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-06-06T16:26:11.060Z,1654532771.060 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-06-06T16:26:11.135Z,1654532771.135 [Radio_Surface](INFO): Powering up
2022-06-06T16:26:11.226Z,1654532771.226 [Power24vConverter](INFO): Powering up.
2022-06-06T16:26:11.227Z,1654532771.227 [CANONSampler](INFO): Powering down
2022-06-06T16:26:11.265Z,1654532771.265 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-06-06T16:26:11.272Z,1654532771.272 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-06-06T16:26:11.273Z,1654532771.273 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-06-06T16:26:11.280Z,1654532771.280 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-06-06T16:26:11.281Z,1654532771.281 [MassServo](DEBUG): Initializing EZServoServo.
2022-06-06T16:26:11.288Z,1654532771.288 [MassServo](DEBUG): Initializing MassServo.
2022-06-06T16:26:11.288Z,1654532771.288 [RudderServo](DEBUG): Initializing EZServoServo.
2022-06-06T16:26:11.296Z,1654532771.296 [RudderServo](DEBUG): Initializing RudderServo.
2022-06-06T16:26:11.296Z,1654532771.296 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-06-06T16:26:11.304Z,1654532771.304 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-06-06T16:26:12.195Z,1654532772.195 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-06-06T16:26:12.196Z,1654532772.196 [RudderServo](FAULT): Rudder failed to initialize
2022-06-06T16:26:12.196Z,1654532772.196 [RudderServo] Communications Fault, FailCount= 1
2022-06-06T16:26:12.196Z,1654532772.196 [RudderServo](ERROR): Communications Fault
2022-06-06T16:26:12.297Z,1654532772.297 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-06-06T16:26:12.528Z,1654532772.528 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-06-06T16:26:12.529Z,1654532772.529 [RudderServo](INFO): Powering down
2022-06-06T16:26:12.864Z,1654532772.864 [WetLabsBB2FL](INFO): Powering down
2022-06-06T16:26:13.132Z,1654532773.132 [RudderServo](DEBUG): Initializing EZServoServo.
2022-06-06T16:26:13.252Z,1654532773.252 [RudderServo](DEBUG): Initializing RudderServo.
2022-06-06T16:26:13.256Z,1654532773.256 [CBIT](INFO): Clearing failed state for component RudderServo
2022-06-06T16:26:13.256Z,1654532773.256 [RudderServo] No Fault, FailCount= 1
2022-06-06T16:26:38.969Z,1654532798.969 [NAL9602](INFO): Powering up NAL9602
2022-06-06T16:26:49.877Z,1654532809.877 [NAL9602](INFO): NAL9602 initialized
2022-06-06T16:27:23.434Z,1654532843.434 [SBIT](IMPORTANT): Beginning Startup BIT
2022-06-06T16:27:23.439Z,1654532843.439 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T16:27:34.778Z,1654532854.778 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T16:27:35.159Z,1654532855.159 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T16:27:46.176Z,1654532866.176 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T16:27:46.573Z,1654532866.573 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T16:27:57.437Z,1654532877.437 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T16:27:57.437Z,1654532877.437 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2022-06-06T16:28:17.592Z,1654532897.592 [SBIT](IMPORTANT): SBIT PASSED
2022-06-06T16:28:17.592Z,1654532897.592 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-06-06T16:28:17.593Z,1654532897.593 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-06-06T16:28:17.593Z,1654532897.593 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=3 count;

2022-06-06T16:28:17.593Z,1654532897.593 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-06-06T16:28:17.593Z,1654532897.593 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-06-06T16:28:17.593Z,1654532897.593 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2022-06-06T16:28:17.593Z,1654532897.593 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-06-06T16:28:17.594Z,1654532897.594 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=347.211455 cubic_centimeter;

2022-06-06T16:28:17.594Z,1654532897.594 [SBIT](IMPORTANT): VerticalControl.massDefault=-13.148004 millimeter;

2022-06-06T16:28:18.002Z,1654532898.002 [MissionManager](IMPORTANT): Started mission Startup
2022-06-06T16:28:18.002Z,1654532898.002 [Startup] Running Loop=1
2022-06-06T16:28:18.002Z,1654532898.002 [Startup](DEBUG): Aggregate::initialize Startup
2022-06-06T16:28:18.002Z,1654532898.002 [Startup:A.GoToSurface] Running Loop=1
2022-06-06T16:28:18.002Z,1654532898.002 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-06-06T16:28:18.003Z,1654532898.003 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-06-06T16:28:18.003Z,1654532898.003 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-06-06T16:28:18.004Z,1654532898.004 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-06-06T16:28:18.004Z,1654532898.004 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-06-06T16:28:18.004Z,1654532898.004 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-06-06T16:28:18.006Z,1654532898.006 [Startup:StartupSatComms] Running Loop=1
2022-06-06T16:28:18.006Z,1654532898.006 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-06-06T16:28:18.006Z,1654532898.006 [Startup:StartupSatComms:A] Running Loop=1
2022-06-06T16:28:18.386Z,1654532898.386 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-06-06T16:28:53.611Z,1654532933.611 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005154
2022-06-06T16:29:10.762Z,1654532950.762 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-06-06T16:29:10.762Z,1654532950.762 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:29:10.798Z,1654532950.798 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:29:11.198Z,1654532951.198 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:29:11.198Z,1654532951.198 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-06-06T16:29:18.026Z,1654532958.026 [Startup:StartupSatComms:A](INFO): Timed out from 2022-06-06T16:28:18.0Z
2022-06-06T16:29:18.026Z,1654532958.026 [Startup:StartupSatComms:A] Stopped
2022-06-06T16:29:18.026Z,1654532958.026 [Startup:StartupSatComms:B] Running Loop=1
2022-06-06T16:29:18.434Z,1654532958.434 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-06-06T16:29:23.240Z,1654532963.240 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220606T162559/Courier0000.lzma
2022-06-06T16:29:24.241Z,1654532964.241 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0000.lzma.bak
2022-06-06T16:29:24.242Z,1654532964.242 [DataOverHttps](INFO): SBD MOMSN=16860802
2022-06-06T16:29:39.917Z,1654532979.917 [DataOverHttps](INFO): Sending 747 bytes from file Logs/20220606T162559/Express0001.lzma
2022-06-06T16:29:40.917Z,1654532980.917 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0001.lzma.bak
2022-06-06T16:29:40.918Z,1654532980.918 [DataOverHttps](INFO): SBD MOMSN=16860805
2022-06-06T16:29:42.276Z,1654532982.276 [Startup:StartupSatComms:B] Stopped
2022-06-06T16:29:42.276Z,1654532982.276 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-06-06T16:29:42.276Z,1654532982.276 [Startup:StartupSatComms] Stopped
2022-06-06T16:29:42.276Z,1654532982.276 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-06-06T16:29:42.277Z,1654532982.277 [Startup](INFO): Completed Startup
2022-06-06T16:29:42.277Z,1654532982.277 [MissionManager](INFO): Startup is completed.
2022-06-06T16:29:42.277Z,1654532982.277 [MissionManager](INFO): Uninitializing Mission Startup
2022-06-06T16:29:42.277Z,1654532982.277 [Startup] Stopped
2022-06-06T16:29:42.277Z,1654532982.277 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-06-06T16:29:42.277Z,1654532982.277 [Startup:A.GoToSurface] Stopped
2022-06-06T16:29:42.277Z,1654532982.277 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-06-06T16:29:42.670Z,1654532982.670 [MissionManager](IMPORTANT): Started mission Default
2022-06-06T16:29:42.670Z,1654532982.670 [Default] Running Loop=1
2022-06-06T16:29:42.670Z,1654532982.670 [Default](DEBUG): Aggregate::initialize Default
2022-06-06T16:29:42.670Z,1654532982.670 [Default:B.GoToSurface] Running Loop=1
2022-06-06T16:29:42.670Z,1654532982.670 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-06-06T16:29:42.670Z,1654532982.670 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-06-06T16:29:42.671Z,1654532982.671 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-06-06T16:29:42.671Z,1654532982.671 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-06-06T16:29:42.672Z,1654532982.672 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-06-06T16:29:42.672Z,1654532982.672 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-06-06T16:29:42.672Z,1654532982.672 [Default:A.Wait] Running Loop=1
2022-06-06T16:29:42.672Z,1654532982.672 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-06-06T16:29:48.308Z,1654532988.308 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T16:29:56.039Z,1654532996.039 [Default:A.Wait](INFO): Done Waiting.
2022-06-06T16:29:56.039Z,1654532996.039 [Default:A.Wait] Stopped
2022-06-06T16:29:56.039Z,1654532996.039 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T16:29:56.430Z,1654532996.430 [Default:CheckIn] Running Loop=1
2022-06-06T16:29:56.430Z,1654532996.430 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T16:29:56.430Z,1654532996.430 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T16:29:56.819Z,1654532996.819 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-06-06T16:30:03.659Z,1654533003.659 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T16:30:18.607Z,1654533018.607 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T16:30:33.959Z,1654533033.959 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T16:30:48.908Z,1654533048.908 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T16:31:03.856Z,1654533063.856 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T16:31:19.207Z,1654533079.207 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T16:31:34.566Z,1654533094.566 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T16:31:49.103Z,1654533109.103 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T16:31:53.147Z,1654533113.147 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-06T16:32:03.647Z,1654533123.647 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2022-06-06T16:32:11.743Z,1654533131.743 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-06-06T16:32:11.744Z,1654533131.744 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:32:11.754Z,1654533131.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:32:12.176Z,1654533132.176 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:32:12.176Z,1654533132.176 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-06-06T16:34:41.619Z,1654533281.619 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-06-06T16:34:41.622Z,1654533281.622 [BPC1](INFO): Received data from all battery sticks.
2022-06-06T16:34:56.582Z,1654533296.582 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T16:29:56.4Z
2022-06-06T16:34:56.582Z,1654533296.582 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T16:34:56.582Z,1654533296.582 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T16:34:56.978Z,1654533296.978 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-06-06T16:35:03.191Z,1654533303.191 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20220606T162559/Courier0004.lzma
2022-06-06T16:35:04.193Z,1654533304.193 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0004.lzma.bak
2022-06-06T16:35:04.194Z,1654533304.194 [DataOverHttps](INFO): SBD MOMSN=16860896
2022-06-06T16:35:12.746Z,1654533312.746 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-06-06T16:35:12.746Z,1654533312.746 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:35:12.756Z,1654533312.756 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:35:13.256Z,1654533313.256 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:35:13.256Z,1654533313.256 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-06-06T16:35:19.724Z,1654533319.724 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20220606T162559/Express0005.lzma
2022-06-06T16:35:20.725Z,1654533320.725 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0005.lzma.bak
2022-06-06T16:35:20.725Z,1654533320.725 [DataOverHttps](INFO): SBD MOMSN=16860898
2022-06-06T16:35:22.053Z,1654533322.053 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T16:35:22.053Z,1654533322.053 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T16:35:22.053Z,1654533322.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T16:36:12.109Z,1654533372.109 [Power24vConverter](INFO): Powering down.
2022-06-06T16:36:52.109Z,1654533412.109 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T16:36:52.109Z,1654533412.109 [NAL9602] Data Fault, FailCount= 1
2022-06-06T16:36:52.109Z,1654533412.109 [NAL9602](ERROR): Data Fault
2022-06-06T16:36:52.126Z,1654533412.126 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T16:36:52.509Z,1654533412.509 [NAL9602](INFO): Powering down
2022-06-06T16:36:53.350Z,1654533413.350 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T16:36:53.350Z,1654533413.350 [NAL9602] No Fault, FailCount= 1
2022-06-06T16:37:22.809Z,1654533442.809 [NAL9602](INFO): Powering up NAL9602
2022-06-06T16:37:33.725Z,1654533453.725 [NAL9602](INFO): NAL9602 initialized
2022-06-06T16:38:13.724Z,1654533493.724 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-06-06T16:38:13.724Z,1654533493.724 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:38:13.735Z,1654533493.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:38:14.145Z,1654533494.145 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:38:14.145Z,1654533494.145 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-06-06T16:40:22.602Z,1654533622.602 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T16:40:22.602Z,1654533622.602 [Default:CheckIn:C.Wait] Stopped
2022-06-06T16:40:22.602Z,1654533622.602 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T16:40:22.602Z,1654533622.602 [Default:CheckIn:D] Running Loop=1
2022-06-06T16:40:23.010Z,1654533623.010 [Default:CheckIn:D] Stopped
2022-06-06T16:40:23.010Z,1654533623.010 [Default:CheckIn:E] Running Loop=1
2022-06-06T16:40:23.437Z,1654533623.437 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672336 min 
2022-06-06T16:40:23.437Z,1654533623.437 [Default:CheckIn:E] Stopped
2022-06-06T16:40:23.437Z,1654533623.437 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T16:40:23.437Z,1654533623.437 [Default:CheckIn] Stopped
2022-06-06T16:40:23.437Z,1654533623.437 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T16:40:23.437Z,1654533623.437 [Default:CheckIn](INFO): Running loop #2
2022-06-06T16:40:23.437Z,1654533623.437 [Default:CheckIn] Running Loop=2
2022-06-06T16:40:23.437Z,1654533623.437 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T16:40:23.438Z,1654533623.438 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T16:41:14.717Z,1654533674.717 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-06-06T16:41:14.717Z,1654533674.717 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:41:14.748Z,1654533674.748 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:41:15.129Z,1654533675.129 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:41:15.130Z,1654533675.130 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-06-06T16:43:09.393Z,1654533789.393 [CommandExec](IMPORTANT): got command burn on
2022-06-06T16:43:09.393Z,1654533789.393 [CommandExec](IMPORTANT): Activating dropweight wire
2022-06-06T16:43:13.137Z,1654533793.137 [CommandExec](IMPORTANT): got command burn off
2022-06-06T16:43:13.137Z,1654533793.137 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-06-06T16:44:15.709Z,1654533855.709 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-06-06T16:44:15.709Z,1654533855.709 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:44:15.719Z,1654533855.719 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:44:16.133Z,1654533856.133 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:44:16.133Z,1654533856.133 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-06-06T16:45:23.591Z,1654533923.591 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T16:40:23.4Z
2022-06-06T16:45:23.591Z,1654533923.591 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T16:45:23.592Z,1654533923.592 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T16:45:30.331Z,1654533930.331 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220606T162559/Courier0007.lzma
2022-06-06T16:45:31.333Z,1654533931.333 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0007.lzma.bak
2022-06-06T16:45:31.333Z,1654533931.333 [DataOverHttps](INFO): SBD MOMSN=16860904
2022-06-06T16:46:17.968Z,1654533977.968 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20220606T162559/Express0008.lzma
2022-06-06T16:46:18.969Z,1654533978.969 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0008.lzma.bak
2022-06-06T16:46:18.970Z,1654533978.970 [DataOverHttps](INFO): SBD MOMSN=16860906
2022-06-06T16:46:20.140Z,1654533980.140 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T16:46:20.140Z,1654533980.140 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T16:46:20.141Z,1654533980.141 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T16:47:16.777Z,1654534036.777 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-06-06T16:47:16.777Z,1654534036.777 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:47:16.787Z,1654534036.787 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:47:17.185Z,1654534037.185 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:47:17.185Z,1654534037.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-06-06T16:47:34.929Z,1654534054.929 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T16:47:34.929Z,1654534054.929 [NAL9602] Data Fault, FailCount= 2
2022-06-06T16:47:34.929Z,1654534054.929 [NAL9602](ERROR): Data Fault
2022-06-06T16:47:34.946Z,1654534054.946 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T16:47:35.325Z,1654534055.325 [NAL9602](INFO): Powering down
2022-06-06T16:47:36.148Z,1654534056.148 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T16:47:36.148Z,1654534056.148 [NAL9602] No Fault, FailCount= 2
2022-06-06T16:48:05.625Z,1654534085.625 [NAL9602](INFO): Powering up NAL9602
2022-06-06T16:48:16.533Z,1654534096.533 [NAL9602](INFO): NAL9602 initialized
2022-06-06T16:50:17.788Z,1654534217.788 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-06-06T16:50:17.788Z,1654534217.788 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:50:17.848Z,1654534217.848 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:50:18.199Z,1654534218.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:50:18.199Z,1654534218.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-06-06T16:51:20.826Z,1654534280.826 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T16:51:20.826Z,1654534280.826 [Default:CheckIn:C.Wait] Stopped
2022-06-06T16:51:20.826Z,1654534280.826 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T16:51:20.826Z,1654534280.826 [Default:CheckIn:D] Running Loop=1
2022-06-06T16:51:21.215Z,1654534281.215 [Default:CheckIn:D] Stopped
2022-06-06T16:51:21.215Z,1654534281.215 [Default:CheckIn:E] Running Loop=1
2022-06-06T16:51:21.605Z,1654534281.605 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.642413 min 
2022-06-06T16:51:21.605Z,1654534281.605 [Default:CheckIn:E] Stopped
2022-06-06T16:51:21.605Z,1654534281.605 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T16:51:21.605Z,1654534281.605 [Default:CheckIn] Stopped
2022-06-06T16:51:21.606Z,1654534281.606 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T16:51:21.606Z,1654534281.606 [Default:CheckIn](INFO): Running loop #3
2022-06-06T16:51:21.606Z,1654534281.606 [Default:CheckIn] Running Loop=3
2022-06-06T16:51:21.606Z,1654534281.606 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T16:51:21.606Z,1654534281.606 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T16:53:18.786Z,1654534398.786 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-06-06T16:53:18.786Z,1654534398.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:53:18.796Z,1654534398.796 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:53:19.222Z,1654534399.222 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:53:19.222Z,1654534399.222 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-06-06T16:56:19.767Z,1654534579.767 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-06-06T16:56:19.767Z,1654534579.767 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:56:19.786Z,1654534579.786 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:56:20.202Z,1654534580.202 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:56:20.202Z,1654534580.202 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-06-06T16:56:21.807Z,1654534581.807 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T16:51:21.6Z
2022-06-06T16:56:21.807Z,1654534581.807 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T16:56:21.807Z,1654534581.807 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T16:56:30.271Z,1654534590.271 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0010.lzma
2022-06-06T16:56:31.273Z,1654534591.273 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0010.lzma.bak
2022-06-06T16:56:31.273Z,1654534591.273 [DataOverHttps](INFO): SBD MOMSN=16860948
2022-06-06T16:56:46.827Z,1654534606.827 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220606T162559/Express0011.lzma
2022-06-06T16:56:47.830Z,1654534607.830 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0011.lzma.bak
2022-06-06T16:56:47.830Z,1654534607.830 [DataOverHttps](INFO): SBD MOMSN=16860950
2022-06-06T16:56:48.869Z,1654534608.869 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T16:56:48.869Z,1654534608.869 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T16:56:48.869Z,1654534608.869 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T16:58:17.397Z,1654534697.397 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T16:58:17.397Z,1654534697.397 [NAL9602] Data Fault, FailCount= 3
2022-06-06T16:58:17.397Z,1654534697.397 [NAL9602](ERROR): Data Fault
2022-06-06T16:58:17.483Z,1654534697.483 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T16:58:17.801Z,1654534697.801 [NAL9602](INFO): Powering down
2022-06-06T16:58:18.652Z,1654534698.652 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T16:58:18.652Z,1654534698.652 [NAL9602] No Fault, FailCount= 3
2022-06-06T16:58:48.129Z,1654534728.129 [NAL9602](INFO): Powering up NAL9602
2022-06-06T16:58:59.053Z,1654534739.053 [NAL9602](INFO): NAL9602 initialized
2022-06-06T16:59:20.872Z,1654534760.872 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-06-06T16:59:20.872Z,1654534760.872 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T16:59:20.882Z,1654534760.882 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T16:59:21.292Z,1654534761.292 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T16:59:21.293Z,1654534761.293 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-06-06T17:01:49.575Z,1654534909.575 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T17:01:49.575Z,1654534909.575 [Default:CheckIn:C.Wait] Stopped
2022-06-06T17:01:49.575Z,1654534909.575 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T17:01:49.576Z,1654534909.576 [Default:CheckIn:D] Running Loop=1
2022-06-06T17:01:49.988Z,1654534909.988 [Default:CheckIn:D] Stopped
2022-06-06T17:01:49.988Z,1654534909.988 [Default:CheckIn:E] Running Loop=1
2022-06-06T17:01:50.395Z,1654534910.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.121973 min 
2022-06-06T17:01:50.396Z,1654534910.396 [Default:CheckIn:E] Stopped
2022-06-06T17:01:50.396Z,1654534910.396 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T17:01:50.396Z,1654534910.396 [Default:CheckIn] Stopped
2022-06-06T17:01:50.396Z,1654534910.396 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T17:01:50.396Z,1654534910.396 [Default:CheckIn](INFO): Running loop #4
2022-06-06T17:01:50.396Z,1654534910.396 [Default:CheckIn] Running Loop=4
2022-06-06T17:01:50.397Z,1654534910.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T17:01:50.397Z,1654534910.397 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T17:02:21.883Z,1654534941.883 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-06-06T17:02:21.883Z,1654534941.883 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:02:21.926Z,1654534941.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:02:22.293Z,1654534942.293 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:02:22.293Z,1654534942.293 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-06-06T17:05:22.887Z,1654535122.887 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-06-06T17:05:22.887Z,1654535122.887 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:05:22.920Z,1654535122.920 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:05:23.297Z,1654535123.297 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:05:23.297Z,1654535123.297 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-06-06T17:06:50.567Z,1654535210.567 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T17:01:50.4Z
2022-06-06T17:06:50.567Z,1654535210.567 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T17:06:50.567Z,1654535210.567 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T17:06:58.775Z,1654535218.775 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0013.lzma
2022-06-06T17:06:59.777Z,1654535219.777 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0013.lzma.bak
2022-06-06T17:06:59.777Z,1654535219.777 [DataOverHttps](INFO): SBD MOMSN=16860957
2022-06-06T17:07:15.171Z,1654535235.171 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220606T162559/Express0014.lzma
2022-06-06T17:07:16.173Z,1654535236.173 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0014.lzma.bak
2022-06-06T17:07:16.174Z,1654535236.174 [DataOverHttps](INFO): SBD MOMSN=16860959
2022-06-06T17:07:17.222Z,1654535237.222 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T17:07:17.223Z,1654535237.223 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T17:07:17.223Z,1654535237.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T17:08:23.989Z,1654535303.989 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-06-06T17:08:23.989Z,1654535303.989 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:08:24.057Z,1654535304.057 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:08:24.418Z,1654535304.418 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:08:24.418Z,1654535304.418 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-06-06T17:08:59.909Z,1654535339.909 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T17:08:59.909Z,1654535339.909 [NAL9602] Data Fault, FailCount= 4
2022-06-06T17:08:59.909Z,1654535339.909 [NAL9602](ERROR): Data Fault
2022-06-06T17:08:59.968Z,1654535339.968 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T17:09:00.305Z,1654535340.305 [NAL9602](INFO): Powering down
2022-06-06T17:09:01.133Z,1654535341.133 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T17:09:01.133Z,1654535341.133 [NAL9602] No Fault, FailCount= 4
2022-06-06T17:09:30.605Z,1654535370.605 [NAL9602](INFO): Powering up NAL9602
2022-06-06T17:09:41.517Z,1654535381.517 [NAL9602](INFO): NAL9602 initialized
2022-06-06T17:11:25.110Z,1654535485.110 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-06-06T17:11:25.110Z,1654535485.110 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:11:25.133Z,1654535485.133 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:11:25.505Z,1654535485.505 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:11:25.505Z,1654535485.505 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-06-06T17:12:17.988Z,1654535537.988 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T17:12:17.988Z,1654535537.988 [Default:CheckIn:C.Wait] Stopped
2022-06-06T17:12:17.988Z,1654535537.988 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T17:12:17.989Z,1654535537.989 [Default:CheckIn:D] Running Loop=1
2022-06-06T17:12:18.405Z,1654535538.405 [Default:CheckIn:D] Stopped
2022-06-06T17:12:18.405Z,1654535538.405 [Default:CheckIn:E] Running Loop=1
2022-06-06T17:12:18.799Z,1654535538.799 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.595577 min 
2022-06-06T17:12:18.800Z,1654535538.800 [Default:CheckIn:E] Stopped
2022-06-06T17:12:18.800Z,1654535538.800 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T17:12:18.800Z,1654535538.800 [Default:CheckIn] Stopped
2022-06-06T17:12:18.800Z,1654535538.800 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T17:12:18.800Z,1654535538.800 [Default:CheckIn](INFO): Running loop #5
2022-06-06T17:12:18.800Z,1654535538.800 [Default:CheckIn] Running Loop=5
2022-06-06T17:12:18.800Z,1654535538.800 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T17:12:18.800Z,1654535538.800 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T17:14:26.111Z,1654535666.111 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-06-06T17:14:26.111Z,1654535666.111 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:14:26.133Z,1654535666.133 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:14:26.498Z,1654535666.498 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:14:26.498Z,1654535666.498 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-06-06T17:17:19.017Z,1654535839.017 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T17:12:18.8Z
2022-06-06T17:17:19.017Z,1654535839.017 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T17:17:19.017Z,1654535839.017 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T17:17:26.503Z,1654535846.503 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0016.lzma
2022-06-06T17:17:27.101Z,1654535847.101 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-06-06T17:17:27.101Z,1654535847.101 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:17:27.111Z,1654535847.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:17:27.505Z,1654535847.505 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0016.lzma.bak
2022-06-06T17:17:27.506Z,1654535847.506 [DataOverHttps](INFO): SBD MOMSN=16860968
2022-06-06T17:17:27.511Z,1654535847.511 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:17:27.511Z,1654535847.511 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2022-06-06T17:17:42.900Z,1654535862.900 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220606T162559/Express0017.lzma
2022-06-06T17:17:43.901Z,1654535863.901 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0017.lzma.bak
2022-06-06T17:17:43.902Z,1654535863.902 [DataOverHttps](INFO): SBD MOMSN=16860970
2022-06-06T17:17:45.260Z,1654535865.260 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T17:17:45.261Z,1654535865.261 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T17:17:45.261Z,1654535865.261 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T17:19:42.553Z,1654535982.553 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T17:19:42.553Z,1654535982.553 [NAL9602] Data Fault, FailCount= 5
2022-06-06T17:19:42.553Z,1654535982.553 [NAL9602](ERROR): Data Fault
2022-06-06T17:19:42.569Z,1654535982.569 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T17:19:42.569Z,1654535982.569 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-06-06T17:19:42.945Z,1654535982.945 [NAL9602](INFO): Powering down
2022-06-06T17:19:42.985Z,1654535982.985 [CommandExec](FAULT): Scheduling is paused
2022-06-06T17:19:42.985Z,1654535982.985 [CBIT](INFO): Critical error at 20220606T171942
2022-06-06T17:19:42.985Z,1654535982.985 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-06-06T17:20:28.283Z,1654536028.283 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2022-06-06T17:20:28.283Z,1654536028.283 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:20:28.293Z,1654536028.293 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:20:28.686Z,1654536028.686 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:20:28.686Z,1654536028.686 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2022-06-06T17:21:42.664Z,1654536102.664 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T17:21:42.664Z,1654536102.664 [NAL9602] No Fault, FailCount= 5
2022-06-06T17:21:43.049Z,1654536103.049 [NAL9602](INFO): Powering up NAL9602
2022-06-06T17:21:53.965Z,1654536113.965 [NAL9602](INFO): NAL9602 initialized
2022-06-06T17:22:45.734Z,1654536165.734 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T17:22:45.734Z,1654536165.734 [Default:CheckIn:C.Wait] Stopped
2022-06-06T17:22:45.734Z,1654536165.734 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T17:22:45.734Z,1654536165.734 [Default:CheckIn:D] Running Loop=1
2022-06-06T17:22:46.130Z,1654536166.130 [Default:CheckIn:D] Stopped
2022-06-06T17:22:46.130Z,1654536166.130 [Default:CheckIn:E] Running Loop=1
2022-06-06T17:22:46.550Z,1654536166.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.057666 min 
2022-06-06T17:22:46.550Z,1654536166.550 [Default:CheckIn:E] Stopped
2022-06-06T17:22:46.550Z,1654536166.550 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T17:22:46.550Z,1654536166.550 [Default:CheckIn] Stopped
2022-06-06T17:22:46.551Z,1654536166.551 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T17:22:46.551Z,1654536166.551 [Default:CheckIn](INFO): Running loop #6
2022-06-06T17:22:46.551Z,1654536166.551 [Default:CheckIn] Running Loop=6
2022-06-06T17:22:46.551Z,1654536166.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T17:22:46.551Z,1654536166.551 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T17:23:29.411Z,1654536209.411 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2022-06-06T17:23:29.411Z,1654536209.411 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:23:29.421Z,1654536209.421 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:23:29.842Z,1654536209.842 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:23:29.842Z,1654536209.842 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2022-06-06T17:26:30.543Z,1654536390.543 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2022-06-06T17:26:30.543Z,1654536390.543 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:26:30.553Z,1654536390.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:26:30.554Z,1654536390.554 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:27:46.880Z,1654536466.880 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T17:22:46.6Z
2022-06-06T17:27:46.880Z,1654536466.880 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T17:27:46.881Z,1654536466.881 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T17:27:53.671Z,1654536473.671 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20220606T162559/Courier0019.lzma
2022-06-06T17:27:54.675Z,1654536474.675 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0019.lzma.bak
2022-06-06T17:27:54.675Z,1654536474.675 [DataOverHttps](INFO): SBD MOMSN=16860978
2022-06-06T17:28:10.253Z,1654536490.253 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20220606T162559/Express0020.lzma
2022-06-06T17:28:11.253Z,1654536491.253 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0020.lzma.bak
2022-06-06T17:28:11.254Z,1654536491.254 [DataOverHttps](INFO): SBD MOMSN=16860981
2022-06-06T17:28:12.352Z,1654536492.352 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T17:28:12.353Z,1654536492.353 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T17:28:12.353Z,1654536492.353 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T17:31:54.985Z,1654536714.985 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T17:31:54.985Z,1654536714.985 [NAL9602] Data Fault, FailCount= 1
2022-06-06T17:31:54.985Z,1654536714.985 [NAL9602](ERROR): Data Fault
2022-06-06T17:31:55.006Z,1654536715.006 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T17:31:55.385Z,1654536715.385 [NAL9602](INFO): Powering down
2022-06-06T17:31:56.208Z,1654536716.208 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T17:31:56.208Z,1654536716.208 [NAL9602] No Fault, FailCount= 1
2022-06-06T17:32:25.739Z,1654536745.739 [NAL9602](INFO): Powering up NAL9602
2022-06-06T17:32:36.647Z,1654536756.647 [NAL9602](INFO): NAL9602 initialized
2022-06-06T17:33:13.069Z,1654536793.069 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T17:33:13.069Z,1654536793.069 [Default:CheckIn:C.Wait] Stopped
2022-06-06T17:33:13.069Z,1654536793.069 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T17:33:13.069Z,1654536793.069 [Default:CheckIn:D] Running Loop=1
2022-06-06T17:33:13.441Z,1654536793.441 [Default:CheckIn:D] Stopped
2022-06-06T17:33:13.441Z,1654536793.441 [Default:CheckIn:E] Running Loop=1
2022-06-06T17:33:13.850Z,1654536793.850 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.512846 min 
2022-06-06T17:33:13.850Z,1654536793.850 [Default:CheckIn:E] Stopped
2022-06-06T17:33:13.850Z,1654536793.850 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T17:33:13.850Z,1654536793.850 [Default:CheckIn] Stopped
2022-06-06T17:33:13.851Z,1654536793.851 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T17:33:13.851Z,1654536793.851 [Default:CheckIn](INFO): Running loop #7
2022-06-06T17:33:13.851Z,1654536793.851 [Default:CheckIn] Running Loop=7
2022-06-06T17:33:13.851Z,1654536793.851 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T17:33:13.851Z,1654536793.851 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T17:38:14.218Z,1654537094.218 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T17:33:13.9Z
2022-06-06T17:38:14.218Z,1654537094.218 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T17:38:14.218Z,1654537094.218 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T17:38:23.907Z,1654537103.907 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0022.lzma
2022-06-06T17:38:24.906Z,1654537104.906 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0022.lzma.bak
2022-06-06T17:38:24.906Z,1654537104.906 [DataOverHttps](INFO): SBD MOMSN=16860994
2022-06-06T17:38:43.035Z,1654537123.035 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220606T162559/Express0023.lzma
2022-06-06T17:38:44.037Z,1654537124.037 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0023.lzma.bak
2022-06-06T17:38:44.038Z,1654537124.038 [DataOverHttps](INFO): SBD MOMSN=16860996
2022-06-06T17:38:45.049Z,1654537125.049 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T17:38:45.049Z,1654537125.049 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T17:38:45.049Z,1654537125.049 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T17:39:38.719Z,1654537178.719 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:39:54.071Z,1654537194.071 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:40:09.423Z,1654537209.423 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:40:24.416Z,1654537224.416 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:40:39.363Z,1654537239.363 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:40:54.315Z,1654537254.315 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:41:09.259Z,1654537269.259 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:41:24.611Z,1654537284.611 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:41:30.713Z,1654537290.713 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:41:30.713Z,1654537290.713 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2022-06-06T17:41:39.560Z,1654537299.560 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:41:54.103Z,1654537314.103 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T17:42:05.826Z,1654537325.826 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-06-06T17:42:05.840Z,1654537325.840 [BPC1](INFO): Received data from all battery sticks.
2022-06-06T17:42:37.809Z,1654537357.809 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T17:42:37.809Z,1654537357.809 [NAL9602] Data Fault, FailCount= 2
2022-06-06T17:42:37.809Z,1654537357.809 [NAL9602](ERROR): Data Fault
2022-06-06T17:42:37.859Z,1654537357.859 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T17:42:38.205Z,1654537358.205 [NAL9602](INFO): Powering down
2022-06-06T17:42:39.018Z,1654537359.018 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T17:42:39.018Z,1654537359.018 [NAL9602] No Fault, FailCount= 2
2022-06-06T17:43:08.493Z,1654537388.493 [NAL9602](INFO): Powering up NAL9602
2022-06-06T17:43:19.405Z,1654537399.405 [NAL9602](INFO): NAL9602 initialized
2022-06-06T17:43:45.682Z,1654537425.682 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T17:43:45.683Z,1654537425.683 [Default:CheckIn:C.Wait] Stopped
2022-06-06T17:43:45.683Z,1654537425.683 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T17:43:45.683Z,1654537425.683 [Default:CheckIn:D] Running Loop=1
2022-06-06T17:43:46.097Z,1654537426.097 [Default:CheckIn:D] Stopped
2022-06-06T17:43:46.097Z,1654537426.097 [Default:CheckIn:E] Running Loop=1
2022-06-06T17:43:46.485Z,1654537426.485 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.057121 min 
2022-06-06T17:43:46.485Z,1654537426.485 [Default:CheckIn:E] Stopped
2022-06-06T17:43:46.485Z,1654537426.485 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T17:43:46.485Z,1654537426.485 [Default:CheckIn] Stopped
2022-06-06T17:43:46.485Z,1654537426.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T17:43:46.486Z,1654537426.486 [Default:CheckIn](INFO): Running loop #8
2022-06-06T17:43:46.486Z,1654537426.486 [Default:CheckIn] Running Loop=8
2022-06-06T17:43:46.486Z,1654537426.486 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T17:43:46.486Z,1654537426.486 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T17:44:31.388Z,1654537471.388 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-06-06T17:44:31.388Z,1654537471.388 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:44:31.398Z,1654537471.398 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:44:31.814Z,1654537471.814 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:44:31.814Z,1654537471.814 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-06-06T17:47:32.416Z,1654537652.416 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-06-06T17:47:32.416Z,1654537652.416 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:47:32.426Z,1654537652.426 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:47:32.838Z,1654537652.838 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:47:32.838Z,1654537652.838 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-06-06T17:48:46.760Z,1654537726.760 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T17:43:46.5Z
2022-06-06T17:48:46.760Z,1654537726.760 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T17:48:46.760Z,1654537726.760 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T17:48:55.987Z,1654537735.987 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20220606T162559/Courier0025.lzma
2022-06-06T17:48:56.990Z,1654537736.990 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0025.lzma.bak
2022-06-06T17:48:56.990Z,1654537736.990 [DataOverHttps](INFO): SBD MOMSN=16861097
2022-06-06T17:49:12.460Z,1654537752.460 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220606T162559/Express0026.lzma
2022-06-06T17:49:13.470Z,1654537753.470 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0026.lzma.bak
2022-06-06T17:49:13.470Z,1654537753.470 [DataOverHttps](INFO): SBD MOMSN=16861099
2022-06-06T17:49:14.654Z,1654537754.654 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T17:49:14.654Z,1654537754.654 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T17:49:14.654Z,1654537754.654 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T17:50:33.487Z,1654537833.487 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-06-06T17:50:33.487Z,1654537833.487 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:50:33.498Z,1654537833.498 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:50:33.910Z,1654537833.910 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:50:33.910Z,1654537833.910 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-06-06T17:53:22.085Z,1654538002.085 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T17:53:22.085Z,1654538002.085 [NAL9602] Data Fault, FailCount= 3
2022-06-06T17:53:22.085Z,1654538002.085 [NAL9602](ERROR): Data Fault
2022-06-06T17:53:22.158Z,1654538002.158 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T17:53:22.501Z,1654538002.501 [NAL9602](INFO): Powering down
2022-06-06T17:53:23.377Z,1654538003.377 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T17:53:23.377Z,1654538003.377 [NAL9602] No Fault, FailCount= 3
2022-06-06T17:53:34.615Z,1654538014.615 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-06-06T17:53:34.615Z,1654538014.615 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:53:34.653Z,1654538014.653 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:53:35.024Z,1654538015.024 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:53:35.024Z,1654538015.024 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-06-06T17:53:52.791Z,1654538032.791 [NAL9602](INFO): Powering up NAL9602
2022-06-06T17:54:03.697Z,1654538043.697 [NAL9602](INFO): NAL9602 initialized
2022-06-06T17:54:15.421Z,1654538055.421 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T17:54:15.421Z,1654538055.421 [Default:CheckIn:C.Wait] Stopped
2022-06-06T17:54:15.421Z,1654538055.421 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T17:54:15.421Z,1654538055.421 [Default:CheckIn:D] Running Loop=1
2022-06-06T17:54:15.830Z,1654538055.830 [Default:CheckIn:D] Stopped
2022-06-06T17:54:15.830Z,1654538055.830 [Default:CheckIn:E] Running Loop=1
2022-06-06T17:54:16.247Z,1654538056.247 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.552669 min 
2022-06-06T17:54:16.247Z,1654538056.247 [Default:CheckIn:E] Stopped
2022-06-06T17:54:16.247Z,1654538056.247 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T17:54:16.248Z,1654538056.248 [Default:CheckIn] Stopped
2022-06-06T17:54:16.248Z,1654538056.248 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T17:54:16.248Z,1654538056.248 [Default:CheckIn](INFO): Running loop #9
2022-06-06T17:54:16.248Z,1654538056.248 [Default:CheckIn] Running Loop=9
2022-06-06T17:54:16.248Z,1654538056.248 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T17:54:16.248Z,1654538056.248 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T17:56:35.727Z,1654538195.727 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-06-06T17:56:35.727Z,1654538195.727 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:56:35.738Z,1654538195.738 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:56:36.141Z,1654538196.141 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:56:36.141Z,1654538196.141 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-06-06T17:59:16.566Z,1654538356.566 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T17:54:16.2Z
2022-06-06T17:59:16.567Z,1654538356.567 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T17:59:16.567Z,1654538356.567 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T17:59:23.383Z,1654538363.383 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0028.lzma
2022-06-06T17:59:24.385Z,1654538364.385 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0028.lzma.bak
2022-06-06T17:59:24.386Z,1654538364.386 [DataOverHttps](INFO): SBD MOMSN=16861106
2022-06-06T17:59:36.776Z,1654538376.776 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-06-06T17:59:36.776Z,1654538376.776 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T17:59:36.795Z,1654538376.795 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T17:59:37.199Z,1654538377.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T17:59:37.200Z,1654538377.200 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-06-06T17:59:39.711Z,1654538379.711 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220606T162559/Express0029.lzma
2022-06-06T17:59:40.713Z,1654538380.713 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0029.lzma.bak
2022-06-06T17:59:40.714Z,1654538380.714 [DataOverHttps](INFO): SBD MOMSN=16861108
2022-06-06T17:59:41.993Z,1654538381.993 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T17:59:41.993Z,1654538381.993 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T17:59:41.994Z,1654538381.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T18:02:37.775Z,1654538557.775 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-06-06T18:02:37.775Z,1654538557.775 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:02:37.785Z,1654538557.785 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:02:38.195Z,1654538558.195 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:02:38.195Z,1654538558.195 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-06-06T18:04:04.641Z,1654538644.641 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T18:04:04.641Z,1654538644.641 [NAL9602] Data Fault, FailCount= 4
2022-06-06T18:04:04.642Z,1654538644.642 [NAL9602](ERROR): Data Fault
2022-06-06T18:04:04.688Z,1654538644.688 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T18:04:05.045Z,1654538645.045 [NAL9602](INFO): Powering down
2022-06-06T18:04:05.872Z,1654538645.872 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T18:04:05.872Z,1654538645.872 [NAL9602] No Fault, FailCount= 4
2022-06-06T18:04:35.373Z,1654538675.373 [NAL9602](INFO): Powering up NAL9602
2022-06-06T18:04:42.655Z,1654538682.655 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T18:04:42.656Z,1654538682.656 [Default:CheckIn:C.Wait] Stopped
2022-06-06T18:04:42.656Z,1654538682.656 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T18:04:42.656Z,1654538682.656 [Default:CheckIn:D] Running Loop=1
2022-06-06T18:04:43.064Z,1654538683.064 [Default:CheckIn:D] Stopped
2022-06-06T18:04:43.064Z,1654538683.064 [Default:CheckIn:E] Running Loop=1
2022-06-06T18:04:43.465Z,1654538683.465 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.006559 min 
2022-06-06T18:04:43.465Z,1654538683.465 [Default:CheckIn:E] Stopped
2022-06-06T18:04:43.465Z,1654538683.465 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T18:04:43.465Z,1654538683.465 [Default:CheckIn] Stopped
2022-06-06T18:04:43.465Z,1654538683.465 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T18:04:43.466Z,1654538683.466 [Default:CheckIn](INFO): Running loop #10
2022-06-06T18:04:43.466Z,1654538683.466 [Default:CheckIn] Running Loop=10
2022-06-06T18:04:43.466Z,1654538683.466 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T18:04:43.466Z,1654538683.466 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T18:04:46.281Z,1654538686.281 [NAL9602](INFO): NAL9602 initialized
2022-06-06T18:05:38.886Z,1654538738.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-06-06T18:05:38.886Z,1654538738.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:05:38.897Z,1654538738.897 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:05:39.313Z,1654538739.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:05:39.313Z,1654538739.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-06-06T18:08:40.008Z,1654538920.008 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-06-06T18:08:40.008Z,1654538920.008 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:08:40.018Z,1654538920.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:08:40.426Z,1654538920.426 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:08:40.426Z,1654538920.426 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-06-06T18:09:43.482Z,1654538983.482 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T18:04:43.5Z
2022-06-06T18:09:43.482Z,1654538983.482 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T18:09:43.482Z,1654538983.482 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T18:09:51.235Z,1654538991.235 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0031.lzma
2022-06-06T18:09:52.237Z,1654538992.237 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0031.lzma.bak
2022-06-06T18:09:52.238Z,1654538992.238 [DataOverHttps](INFO): SBD MOMSN=16861125
2022-06-06T18:10:07.655Z,1654539007.655 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220606T162559/Express0032.lzma
2022-06-06T18:10:08.658Z,1654539008.658 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0032.lzma.bak
2022-06-06T18:10:08.658Z,1654539008.658 [DataOverHttps](INFO): SBD MOMSN=16861127
2022-06-06T18:10:09.759Z,1654539009.759 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T18:10:09.759Z,1654539009.759 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T18:10:09.759Z,1654539009.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T18:11:41.056Z,1654539101.056 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-06-06T18:11:41.056Z,1654539101.056 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:11:41.066Z,1654539101.066 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:11:41.476Z,1654539101.476 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:11:41.476Z,1654539101.476 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-06-06T18:14:42.052Z,1654539282.052 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-06-06T18:14:42.052Z,1654539282.052 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:14:42.062Z,1654539282.062 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:14:42.467Z,1654539282.467 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:14:42.467Z,1654539282.467 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-06-06T18:14:48.921Z,1654539288.921 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T18:14:48.921Z,1654539288.921 [NAL9602] Data Fault, FailCount= 5
2022-06-06T18:14:48.921Z,1654539288.921 [NAL9602](ERROR): Data Fault
2022-06-06T18:14:49.004Z,1654539289.004 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T18:14:49.005Z,1654539289.005 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-06-06T18:14:49.325Z,1654539289.325 [NAL9602](INFO): Powering down
2022-06-06T18:14:49.367Z,1654539289.367 [CBIT](INFO): Critical error at 20220606T181449
2022-06-06T18:15:10.340Z,1654539310.340 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T18:15:10.340Z,1654539310.340 [Default:CheckIn:C.Wait] Stopped
2022-06-06T18:15:10.340Z,1654539310.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T18:15:10.340Z,1654539310.340 [Default:CheckIn:D] Running Loop=1
2022-06-06T18:15:10.754Z,1654539310.754 [Default:CheckIn:D] Stopped
2022-06-06T18:15:10.754Z,1654539310.754 [Default:CheckIn:E] Running Loop=1
2022-06-06T18:15:11.144Z,1654539311.144 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.468058 min 
2022-06-06T18:15:11.144Z,1654539311.144 [Default:CheckIn:E] Stopped
2022-06-06T18:15:11.144Z,1654539311.144 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T18:15:11.144Z,1654539311.144 [Default:CheckIn] Stopped
2022-06-06T18:15:11.144Z,1654539311.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T18:15:11.144Z,1654539311.144 [Default:CheckIn](INFO): Running loop #11
2022-06-06T18:15:11.144Z,1654539311.144 [Default:CheckIn] Running Loop=11
2022-06-06T18:15:11.144Z,1654539311.144 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T18:15:11.145Z,1654539311.145 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T18:16:49.062Z,1654539409.062 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T18:16:49.062Z,1654539409.062 [NAL9602] No Fault, FailCount= 5
2022-06-06T18:16:49.372Z,1654539409.372 [NAL9602](INFO): Powering up NAL9602
2022-06-06T18:17:00.281Z,1654539420.281 [NAL9602](INFO): NAL9602 initialized
2022-06-06T18:17:43.122Z,1654539463.122 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-06-06T18:17:43.122Z,1654539463.122 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:17:43.132Z,1654539463.132 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:17:43.547Z,1654539463.547 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:17:43.547Z,1654539463.547 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-06-06T18:20:11.472Z,1654539611.472 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T18:15:11.1Z
2022-06-06T18:20:11.472Z,1654539611.472 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T18:20:11.472Z,1654539611.472 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T18:20:19.987Z,1654539619.987 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20220606T162559/Courier0034.lzma
2022-06-06T18:20:20.990Z,1654539620.990 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0034.lzma.bak
2022-06-06T18:20:20.990Z,1654539620.990 [DataOverHttps](INFO): SBD MOMSN=16861133
2022-06-06T18:20:37.876Z,1654539637.876 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220606T162559/Express0035.lzma
2022-06-06T18:20:38.878Z,1654539638.878 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0035.lzma.bak
2022-06-06T18:20:38.878Z,1654539638.878 [DataOverHttps](INFO): SBD MOMSN=16861136
2022-06-06T18:20:40.166Z,1654539640.166 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T18:20:40.166Z,1654539640.166 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T18:20:40.166Z,1654539640.166 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T18:20:44.229Z,1654539644.229 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-06-06T18:20:44.229Z,1654539644.229 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:20:44.241Z,1654539644.241 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:20:44.653Z,1654539644.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:20:44.653Z,1654539644.653 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-06-06T18:23:45.244Z,1654539825.244 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-06-06T18:23:45.244Z,1654539825.244 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:23:45.265Z,1654539825.265 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:23:45.676Z,1654539825.676 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:23:45.677Z,1654539825.677 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-06-06T18:25:40.750Z,1654539940.750 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T18:25:40.750Z,1654539940.750 [Default:CheckIn:C.Wait] Stopped
2022-06-06T18:25:40.750Z,1654539940.750 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T18:25:40.750Z,1654539940.750 [Default:CheckIn:D] Running Loop=1
2022-06-06T18:25:41.165Z,1654539941.165 [Default:CheckIn:D] Stopped
2022-06-06T18:25:41.165Z,1654539941.165 [Default:CheckIn:E] Running Loop=1
2022-06-06T18:25:41.582Z,1654539941.582 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.974919 min 
2022-06-06T18:25:41.582Z,1654539941.582 [Default:CheckIn:E] Stopped
2022-06-06T18:25:41.582Z,1654539941.582 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T18:25:41.582Z,1654539941.582 [Default:CheckIn] Stopped
2022-06-06T18:25:41.582Z,1654539941.582 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T18:25:41.583Z,1654539941.583 [Default:CheckIn](INFO): Running loop #12
2022-06-06T18:25:41.583Z,1654539941.583 [Default:CheckIn] Running Loop=12
2022-06-06T18:25:41.583Z,1654539941.583 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T18:25:41.583Z,1654539941.583 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T18:26:46.240Z,1654540006.240 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-06-06T18:26:46.240Z,1654540006.240 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:26:46.263Z,1654540006.263 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:26:46.661Z,1654540006.661 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:26:46.661Z,1654540006.661 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-06-06T18:27:03.185Z,1654540023.185 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T18:27:03.185Z,1654540023.185 [NAL9602] Data Fault, FailCount= 1
2022-06-06T18:27:03.185Z,1654540023.185 [NAL9602](ERROR): Data Fault
2022-06-06T18:27:03.208Z,1654540023.208 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T18:27:03.597Z,1654540023.597 [NAL9602](INFO): Powering down
2022-06-06T18:27:04.415Z,1654540024.415 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T18:27:04.415Z,1654540024.415 [NAL9602] No Fault, FailCount= 1
2022-06-06T18:27:33.889Z,1654540053.889 [NAL9602](INFO): Powering up NAL9602
2022-06-06T18:27:44.797Z,1654540064.797 [NAL9602](INFO): NAL9602 initialized
2022-06-06T18:29:47.361Z,1654540187.361 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-06-06T18:29:47.361Z,1654540187.361 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:29:47.400Z,1654540187.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:29:47.778Z,1654540187.778 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:29:47.778Z,1654540187.778 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-06-06T18:30:41.960Z,1654540241.960 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T18:25:41.6Z
2022-06-06T18:30:41.960Z,1654540241.960 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T18:30:41.961Z,1654540241.961 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T18:30:50.123Z,1654540250.123 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0037.lzma
2022-06-06T18:30:51.126Z,1654540251.126 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0037.lzma.bak
2022-06-06T18:30:51.126Z,1654540251.126 [DataOverHttps](INFO): SBD MOMSN=16861140
2022-06-06T18:31:07.659Z,1654540267.659 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220606T162559/Express0038.lzma
2022-06-06T18:31:08.682Z,1654540268.682 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0038.lzma.bak
2022-06-06T18:31:08.682Z,1654540268.682 [DataOverHttps](INFO): SBD MOMSN=16861142
2022-06-06T18:31:09.871Z,1654540269.871 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T18:31:09.872Z,1654540269.872 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T18:31:09.872Z,1654540269.872 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T18:32:48.445Z,1654540368.445 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-06-06T18:32:48.445Z,1654540368.445 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:32:48.456Z,1654540368.456 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:32:48.866Z,1654540368.866 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:32:48.866Z,1654540368.866 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2022-06-06T18:35:49.448Z,1654540549.448 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2022-06-06T18:35:49.448Z,1654540549.448 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:35:49.459Z,1654540549.459 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:35:49.833Z,1654540549.833 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:35:49.833Z,1654540549.833 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2022-06-06T18:36:10.447Z,1654540570.447 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T18:36:10.447Z,1654540570.447 [Default:CheckIn:C.Wait] Stopped
2022-06-06T18:36:10.447Z,1654540570.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T18:36:10.447Z,1654540570.447 [Default:CheckIn:D] Running Loop=1
2022-06-06T18:36:10.862Z,1654540570.862 [Default:CheckIn:D] Stopped
2022-06-06T18:36:10.862Z,1654540570.862 [Default:CheckIn:E] Running Loop=1
2022-06-06T18:36:11.268Z,1654540571.268 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.469873 min 
2022-06-06T18:36:11.269Z,1654540571.269 [Default:CheckIn:E] Stopped
2022-06-06T18:36:11.269Z,1654540571.269 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T18:36:11.269Z,1654540571.269 [Default:CheckIn] Stopped
2022-06-06T18:36:11.269Z,1654540571.269 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T18:36:11.269Z,1654540571.269 [Default:CheckIn](INFO): Running loop #13
2022-06-06T18:36:11.269Z,1654540571.269 [Default:CheckIn] Running Loop=13
2022-06-06T18:36:11.269Z,1654540571.269 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T18:36:11.269Z,1654540571.269 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T18:37:46.585Z,1654540666.585 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T18:37:46.585Z,1654540666.585 [NAL9602] Data Fault, FailCount= 2
2022-06-06T18:37:46.585Z,1654540666.585 [NAL9602](ERROR): Data Fault
2022-06-06T18:37:46.676Z,1654540666.676 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T18:37:46.993Z,1654540666.993 [NAL9602](INFO): Powering down
2022-06-06T18:37:47.874Z,1654540667.874 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T18:37:47.874Z,1654540667.874 [NAL9602] No Fault, FailCount= 2
2022-06-06T18:38:17.289Z,1654540697.289 [NAL9602](INFO): Powering up NAL9602
2022-06-06T18:38:28.225Z,1654540708.225 [NAL9602](INFO): NAL9602 initialized
2022-06-06T18:38:50.538Z,1654540730.538 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2022-06-06T18:38:50.538Z,1654540730.538 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:38:50.612Z,1654540730.612 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:38:50.928Z,1654540730.928 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:38:50.928Z,1654540730.928 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2022-06-06T18:41:11.600Z,1654540871.600 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T18:36:11.3Z
2022-06-06T18:41:11.601Z,1654540871.601 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T18:41:11.601Z,1654540871.601 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T18:41:18.635Z,1654540878.635 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0040.lzma
2022-06-06T18:41:19.637Z,1654540879.637 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0040.lzma.bak
2022-06-06T18:41:19.638Z,1654540879.638 [DataOverHttps](INFO): SBD MOMSN=16861147
2022-06-06T18:41:35.103Z,1654540895.103 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20220606T162559/Express0041.lzma
2022-06-06T18:41:36.105Z,1654540896.105 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0041.lzma.bak
2022-06-06T18:41:36.106Z,1654540896.106 [DataOverHttps](INFO): SBD MOMSN=16861149
2022-06-06T18:41:37.478Z,1654540897.478 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T18:41:37.478Z,1654540897.478 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T18:41:37.478Z,1654540897.478 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T18:41:51.597Z,1654540911.597 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2022-06-06T18:41:51.597Z,1654540911.597 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:41:51.607Z,1654540911.607 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:41:51.607Z,1654540911.607 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:46:38.042Z,1654541198.042 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T18:46:38.042Z,1654541198.042 [Default:CheckIn:C.Wait] Stopped
2022-06-06T18:46:38.042Z,1654541198.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T18:46:38.042Z,1654541198.042 [Default:CheckIn:D] Running Loop=1
2022-06-06T18:46:38.456Z,1654541198.456 [Default:CheckIn:D] Stopped
2022-06-06T18:46:38.456Z,1654541198.456 [Default:CheckIn:E] Running Loop=1
2022-06-06T18:46:38.862Z,1654541198.862 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.929769 min 
2022-06-06T18:46:38.862Z,1654541198.862 [Default:CheckIn:E] Stopped
2022-06-06T18:46:38.862Z,1654541198.862 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T18:46:38.863Z,1654541198.863 [Default:CheckIn] Stopped
2022-06-06T18:46:38.863Z,1654541198.863 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T18:46:38.863Z,1654541198.863 [Default:CheckIn](INFO): Running loop #14
2022-06-06T18:46:38.863Z,1654541198.863 [Default:CheckIn] Running Loop=14
2022-06-06T18:46:38.863Z,1654541198.863 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T18:46:38.863Z,1654541198.863 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T18:47:05.091Z,1654541225.091 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T18:47:15.728Z,1654541235.728 [CommandExec](IMPORTANT): got command ibit
2022-06-06T18:47:16.079Z,1654541236.079 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-06-06T18:47:16.079Z,1654541236.079 [IBIT](IMPORTANT): Beginning control surface checks.
2022-06-06T18:47:16.082Z,1654541236.082 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T18:47:18.913Z,1654541238.913 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-06-06T18:47:20.448Z,1654541240.448 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T18:47:26.997Z,1654541246.997 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T18:47:27.389Z,1654541247.389 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T18:47:30.236Z,1654541250.236 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-06-06T18:47:35.795Z,1654541255.795 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T18:47:38.372Z,1654541258.372 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T18:47:38.775Z,1654541258.775 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T18:47:41.548Z,1654541261.548 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-06-06T18:47:49.652Z,1654541269.652 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T18:47:49.653Z,1654541269.653 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2022-06-06T18:47:51.152Z,1654541271.152 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T18:48:06.499Z,1654541286.499 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T18:48:22.256Z,1654541302.256 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T18:48:29.533Z,1654541309.533 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T18:48:29.533Z,1654541309.533 [NAL9602] Data Fault, FailCount= 3
2022-06-06T18:48:29.533Z,1654541309.533 [NAL9602](ERROR): Data Fault
2022-06-06T18:48:29.589Z,1654541309.589 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T18:48:29.933Z,1654541309.933 [NAL9602](INFO): Powering down
2022-06-06T18:48:30.768Z,1654541310.768 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T18:48:30.768Z,1654541310.768 [NAL9602] No Fault, FailCount= 3
2022-06-06T18:48:37.203Z,1654541317.203 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T18:48:52.556Z,1654541332.556 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T18:49:00.237Z,1654541340.237 [NAL9602](INFO): Powering up NAL9602
2022-06-06T18:49:07.126Z,1654541347.126 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-06-06T18:49:07.564Z,1654541347.564 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 346.578979
Voltage: 16.611000
2022-06-06T18:49:07.564Z,1654541347.564 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2022-06-06T18:49:07.564Z,1654541347.564 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2022-06-06T18:49:07.907Z,1654541347.907 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T18:49:07.926Z,1654541347.926 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-06-06T18:49:07.926Z,1654541347.926 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-06-06T18:49:07.927Z,1654541347.927 [IBIT](IMPORTANT): Pressure:10.127182 PSI
2022-06-06T18:49:07.927Z,1654541347.927 [IBIT](IMPORTANT): Humidity:9.602002 %
2022-06-06T18:49:08.344Z,1654541348.344 [IBIT](IMPORTANT): Vehicle Pitch:-0.451153 degrees
2022-06-06T18:49:08.344Z,1654541348.344 [IBIT](IMPORTANT): Vehicle  Roll:0.466636 degrees
2022-06-06T18:49:08.345Z,1654541348.345 [IBIT](IMPORTANT): Vehicle  Heading:253.763733 degrees
2022-06-06T18:49:08.754Z,1654541348.754 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-06-06T18:49:08.755Z,1654541348.755 [IBIT](IMPORTANT): buoyancyNeutral: 347.211456 cc
2022-06-06T18:49:08.755Z,1654541348.755 [IBIT](IMPORTANT): massDefault: -1.314800 cm
2022-06-06T18:49:08.755Z,1654541348.755 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2022-06-06T18:49:08.755Z,1654541348.755 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2022-06-06T18:49:08.756Z,1654541348.756 [IBIT](IMPORTANT): IBIT FAILED
2022-06-06T18:49:11.149Z,1654541351.149 [NAL9602](INFO): NAL9602 initialized
2022-06-06T18:49:22.451Z,1654541362.451 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2022-06-06T18:49:30.139Z,1654541370.139 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-06-06T18:49:30.153Z,1654541370.153 [BPC1](INFO): Received data from all battery sticks.
2022-06-06T18:49:46.496Z,1654541386.496 [CommandExec](IMPORTANT): got command failComponent
2022-06-06T18:49:46.496Z,1654541386.496 [CommandExec](IMPORTANT): Failed components:
2022-06-06T18:49:46.497Z,1654541386.497 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault
2022-06-06T18:49:53.256Z,1654541393.256 [CommandExec](IMPORTANT): got command failComponent
2022-06-06T18:49:53.256Z,1654541393.256 [CommandExec](IMPORTANT): Failed components:
2022-06-06T18:49:53.257Z,1654541393.257 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault
2022-06-06T18:49:54.608Z,1654541394.608 [CommandExec](IMPORTANT): got command ibit
2022-06-06T18:49:54.811Z,1654541394.811 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-06-06T18:49:54.811Z,1654541394.811 [IBIT](IMPORTANT): Beginning control surface checks.
2022-06-06T18:49:54.814Z,1654541394.814 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T18:49:57.680Z,1654541397.680 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-06-06T18:50:05.710Z,1654541405.710 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T18:50:06.126Z,1654541406.126 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T18:50:08.947Z,1654541408.947 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-06-06T18:50:17.133Z,1654541417.133 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T18:50:17.618Z,1654541417.618 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T18:50:20.425Z,1654541420.425 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-06-06T18:50:28.468Z,1654541428.468 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002834
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.001909
CHAN A3 (5V): -0.001130
CHAN B0 (3.3V): 0.000005
CHAN B1 (3.15aV): 0.000127
CHAN B2 (3.15bV): -0.000084
CHAN B3 (GND): 0.000031
OPEN: -0.000298
Full Scale: +/- 1 mA
2022-06-06T18:51:45.556Z,1654541505.556 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-06-06T18:51:45.925Z,1654541505.925 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2022-06-06T18:51:45.926Z,1654541505.926 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2022-06-06T18:51:45.926Z,1654541505.926 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2022-06-06T18:51:46.346Z,1654541506.346 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-06-06T18:51:46.346Z,1654541506.346 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-06-06T18:51:46.347Z,1654541506.347 [IBIT](IMPORTANT): Pressure:10.169780 PSI
2022-06-06T18:51:46.347Z,1654541506.347 [IBIT](IMPORTANT): Humidity:9.602002 %
2022-06-06T18:51:46.768Z,1654541506.768 [IBIT](IMPORTANT): Vehicle Pitch:-0.455651 degrees
2022-06-06T18:51:46.768Z,1654541506.768 [IBIT](IMPORTANT): Vehicle  Roll:0.454308 degrees
2022-06-06T18:51:46.769Z,1654541506.769 [IBIT](IMPORTANT): Vehicle  Heading:253.773361 degrees
2022-06-06T18:51:47.153Z,1654541507.153 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-06-06T18:51:47.154Z,1654541507.154 [IBIT](IMPORTANT): buoyancyNeutral: 347.211456 cc
2022-06-06T18:51:47.154Z,1654541507.154 [IBIT](IMPORTANT): massDefault: -1.314800 cm
2022-06-06T18:51:47.154Z,1654541507.154 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2022-06-06T18:51:47.155Z,1654541507.155 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2022-06-06T18:51:47.155Z,1654541507.155 [IBIT](IMPORTANT): IBIT FAILED
2022-06-06T18:51:47.576Z,1654541507.576 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T18:46:38.9Z
2022-06-06T18:51:47.576Z,1654541507.576 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T18:51:47.576Z,1654541507.576 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T18:51:51.135Z,1654541511.135 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20220606T162559/Courier0043.lzma
2022-06-06T18:51:52.137Z,1654541512.137 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0043.lzma.bak
2022-06-06T18:51:52.138Z,1654541512.138 [DataOverHttps](INFO): SBD MOMSN=16861257
2022-06-06T18:52:07.734Z,1654541527.734 [DataOverHttps](INFO): Sending 1066 bytes from file Logs/20220606T162559/Express0044.lzma
2022-06-06T18:52:08.734Z,1654541528.734 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0044.lzma.bak
2022-06-06T18:52:08.734Z,1654541528.734 [DataOverHttps](INFO): SBD MOMSN=16861259
2022-06-06T18:52:09.900Z,1654541529.900 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T18:52:09.900Z,1654541529.900 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T18:52:09.900Z,1654541529.900 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T18:56:51.906Z,1654541811.906 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:56:51.906Z,1654541811.906 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2022-06-06T18:57:10.462Z,1654541830.462 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T18:57:10.463Z,1654541830.463 [Default:CheckIn:C.Wait] Stopped
2022-06-06T18:57:10.463Z,1654541830.463 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T18:57:10.463Z,1654541830.463 [Default:CheckIn:D] Running Loop=1
2022-06-06T18:57:10.878Z,1654541830.878 [Default:CheckIn:D] Stopped
2022-06-06T18:57:10.878Z,1654541830.878 [Default:CheckIn:E] Running Loop=1
2022-06-06T18:57:11.281Z,1654541831.281 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.470133 min 
2022-06-06T18:57:11.281Z,1654541831.281 [Default:CheckIn:E] Stopped
2022-06-06T18:57:11.281Z,1654541831.281 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T18:57:11.282Z,1654541831.282 [Default:CheckIn] Stopped
2022-06-06T18:57:11.282Z,1654541831.282 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T18:57:11.282Z,1654541831.282 [Default:CheckIn](INFO): Running loop #15
2022-06-06T18:57:11.282Z,1654541831.282 [Default:CheckIn] Running Loop=15
2022-06-06T18:57:11.282Z,1654541831.282 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T18:57:11.282Z,1654541831.282 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T18:59:13.297Z,1654541953.297 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T18:59:13.297Z,1654541953.297 [NAL9602] Data Fault, FailCount= 4
2022-06-06T18:59:13.297Z,1654541953.297 [NAL9602](ERROR): Data Fault
2022-06-06T18:59:13.314Z,1654541953.314 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T18:59:13.693Z,1654541953.693 [NAL9602](INFO): Powering down
2022-06-06T18:59:14.525Z,1654541954.525 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T18:59:14.525Z,1654541954.525 [NAL9602] No Fault, FailCount= 4
2022-06-06T18:59:43.997Z,1654541983.997 [NAL9602](INFO): Powering up NAL9602
2022-06-06T18:59:52.492Z,1654541992.492 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-06-06T18:59:52.492Z,1654541992.492 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T18:59:52.502Z,1654541992.502 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T18:59:52.902Z,1654541992.902 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T18:59:52.902Z,1654541992.902 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-06-06T18:59:54.901Z,1654541994.901 [NAL9602](INFO): NAL9602 initialized
2022-06-06T19:02:11.580Z,1654542131.580 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T18:57:11.3Z
2022-06-06T19:02:11.580Z,1654542131.580 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T19:02:11.580Z,1654542131.580 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T19:02:20.436Z,1654542140.436 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0046.lzma
2022-06-06T19:02:21.438Z,1654542141.438 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0046.lzma.bak
2022-06-06T19:02:21.438Z,1654542141.438 [DataOverHttps](INFO): SBD MOMSN=16861483
2022-06-06T19:02:40.735Z,1654542160.735 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20220606T162559/Express0047.lzma
2022-06-06T19:02:41.738Z,1654542161.738 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0047.lzma.bak
2022-06-06T19:02:41.738Z,1654542161.738 [DataOverHttps](INFO): SBD MOMSN=16861509
2022-06-06T19:02:42.786Z,1654542162.786 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T19:02:42.786Z,1654542162.786 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T19:02:42.786Z,1654542162.786 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T19:02:53.694Z,1654542173.694 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-06-06T19:02:53.694Z,1654542173.694 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:02:53.704Z,1654542173.704 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:02:54.118Z,1654542174.118 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:02:54.118Z,1654542174.118 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-06-06T19:04:56.829Z,1654542296.829 [CommandExec](IMPORTANT): got command strobe off
2022-06-06T19:04:56.829Z,1654542296.829 [CommandExec](IMPORTANT): Deactivating strobe
2022-06-06T19:05:54.773Z,1654542354.773 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-06-06T19:05:54.773Z,1654542354.773 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:05:54.817Z,1654542354.817 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:05:55.186Z,1654542355.186 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:05:55.186Z,1654542355.186 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-06-06T19:07:43.595Z,1654542463.595 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T19:07:43.596Z,1654542463.596 [Default:CheckIn:C.Wait] Stopped
2022-06-06T19:07:43.596Z,1654542463.596 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T19:07:43.596Z,1654542463.596 [Default:CheckIn:D] Running Loop=1
2022-06-06T19:07:43.971Z,1654542463.971 [Default:CheckIn:D] Stopped
2022-06-06T19:07:43.971Z,1654542463.971 [Default:CheckIn:E] Running Loop=1
2022-06-06T19:07:44.378Z,1654542464.378 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.021680 min 
2022-06-06T19:07:44.378Z,1654542464.378 [Default:CheckIn:E] Stopped
2022-06-06T19:07:44.378Z,1654542464.378 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T19:07:44.379Z,1654542464.379 [Default:CheckIn] Stopped
2022-06-06T19:07:44.379Z,1654542464.379 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T19:07:44.379Z,1654542464.379 [Default:CheckIn](INFO): Running loop #16
2022-06-06T19:07:44.379Z,1654542464.379 [Default:CheckIn] Running Loop=16
2022-06-06T19:07:44.379Z,1654542464.379 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T19:07:44.379Z,1654542464.379 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T19:08:55.601Z,1654542535.601 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-06-06T19:08:55.601Z,1654542535.601 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:08:55.676Z,1654542535.676 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:08:55.978Z,1654542535.978 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:08:55.978Z,1654542535.978 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-06-06T19:09:56.549Z,1654542596.549 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T19:09:56.549Z,1654542596.549 [NAL9602] Data Fault, FailCount= 5
2022-06-06T19:09:56.549Z,1654542596.549 [NAL9602](ERROR): Data Fault
2022-06-06T19:09:56.599Z,1654542596.599 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T19:09:56.599Z,1654542596.599 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-06-06T19:09:56.949Z,1654542596.949 [NAL9602](INFO): Powering down
2022-06-06T19:09:56.998Z,1654542596.998 [CBIT](INFO): Critical error at 20220606T190956
2022-06-06T19:11:56.615Z,1654542716.615 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-06-06T19:11:56.615Z,1654542716.615 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:11:56.661Z,1654542716.661 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:11:56.661Z,1654542716.661 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T19:11:56.661Z,1654542716.661 [NAL9602] No Fault, FailCount= 5
2022-06-06T19:11:57.009Z,1654542717.009 [NAL9602](INFO): Powering up NAL9602
2022-06-06T19:11:57.025Z,1654542717.025 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:11:57.025Z,1654542717.025 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-06-06T19:12:07.928Z,1654542727.928 [NAL9602](INFO): NAL9602 initialized
2022-06-06T19:12:44.713Z,1654542764.713 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T19:07:44.4Z
2022-06-06T19:12:44.713Z,1654542764.713 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T19:12:44.713Z,1654542764.713 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T19:12:54.767Z,1654542774.767 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20220606T162559/Courier0049.lzma
2022-06-06T19:12:55.770Z,1654542775.770 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0049.lzma.bak
2022-06-06T19:12:55.770Z,1654542775.770 [DataOverHttps](INFO): SBD MOMSN=16861514
2022-06-06T19:13:11.151Z,1654542791.151 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20220606T162559/Express0050.lzma
2022-06-06T19:13:12.153Z,1654542792.153 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0050.lzma.bak
2022-06-06T19:13:12.154Z,1654542792.154 [DataOverHttps](INFO): SBD MOMSN=16861517
2022-06-06T19:13:13.463Z,1654542793.463 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T19:13:13.463Z,1654542793.463 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T19:13:13.463Z,1654542793.463 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T19:14:57.760Z,1654542897.760 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-06-06T19:14:57.760Z,1654542897.760 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:14:57.770Z,1654542897.770 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:14:58.169Z,1654542898.169 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:14:58.169Z,1654542898.169 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-06-06T19:17:58.869Z,1654543078.869 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-06-06T19:17:58.869Z,1654543078.869 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:17:58.908Z,1654543078.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:17:59.275Z,1654543079.275 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:17:59.275Z,1654543079.275 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-06-06T19:18:14.197Z,1654543094.197 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T19:18:14.197Z,1654543094.197 [Default:CheckIn:C.Wait] Stopped
2022-06-06T19:18:14.197Z,1654543094.197 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T19:18:14.197Z,1654543094.197 [Default:CheckIn:D] Running Loop=1
2022-06-06T19:18:14.618Z,1654543094.618 [Default:CheckIn:D] Stopped
2022-06-06T19:18:14.618Z,1654543094.618 [Default:CheckIn:E] Running Loop=1
2022-06-06T19:18:15.030Z,1654543095.030 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 168.532471 min 
2022-06-06T19:18:15.030Z,1654543095.030 [Default:CheckIn:E] Stopped
2022-06-06T19:18:15.030Z,1654543095.030 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T19:18:15.030Z,1654543095.030 [Default:CheckIn] Stopped
2022-06-06T19:18:15.030Z,1654543095.030 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T19:18:15.030Z,1654543095.030 [Default:CheckIn](INFO): Running loop #17
2022-06-06T19:18:15.030Z,1654543095.030 [Default:CheckIn] Running Loop=17
2022-06-06T19:18:15.031Z,1654543095.031 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T19:18:15.031Z,1654543095.031 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T19:21:00.058Z,1654543260.058 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-06-06T19:21:00.058Z,1654543260.058 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:21:00.068Z,1654543260.068 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:21:00.479Z,1654543260.479 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:21:00.479Z,1654543260.479 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-06-06T19:22:09.121Z,1654543329.121 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T19:22:09.121Z,1654543329.121 [NAL9602] Data Fault, FailCount= 1
2022-06-06T19:22:09.121Z,1654543329.121 [NAL9602](ERROR): Data Fault
2022-06-06T19:22:09.173Z,1654543329.173 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T19:22:09.521Z,1654543329.521 [NAL9602](INFO): Powering down
2022-06-06T19:22:10.357Z,1654543330.357 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T19:22:10.357Z,1654543330.357 [NAL9602] No Fault, FailCount= 1
2022-06-06T19:22:39.821Z,1654543359.821 [NAL9602](INFO): Powering up NAL9602
2022-06-06T19:22:50.733Z,1654543370.733 [NAL9602](INFO): NAL9602 initialized
2022-06-06T19:23:15.394Z,1654543395.394 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T19:18:15.0Z
2022-06-06T19:23:15.394Z,1654543395.394 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T19:23:15.394Z,1654543395.394 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T19:23:22.327Z,1654543402.327 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0052.lzma
2022-06-06T19:23:23.558Z,1654543403.558 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0052.lzma.bak
2022-06-06T19:23:23.558Z,1654543403.558 [DataOverHttps](INFO): SBD MOMSN=16861523
2022-06-06T19:23:38.748Z,1654543418.748 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220606T162559/Express0053.lzma
2022-06-06T19:23:39.750Z,1654543419.750 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0053.lzma.bak
2022-06-06T19:23:39.750Z,1654543419.750 [DataOverHttps](INFO): SBD MOMSN=16861525
2022-06-06T19:23:40.859Z,1654543420.859 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T19:23:40.859Z,1654543420.859 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T19:23:40.859Z,1654543420.859 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T19:24:01.036Z,1654543441.036 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-06-06T19:24:01.036Z,1654543441.036 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:24:01.046Z,1654543441.046 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:24:01.458Z,1654543441.458 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:24:01.458Z,1654543441.458 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-06-06T19:27:02.064Z,1654543622.064 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-06-06T19:27:02.064Z,1654543622.064 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:27:02.074Z,1654543622.074 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:27:02.490Z,1654543622.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:27:02.490Z,1654543622.490 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-06-06T19:28:41.501Z,1654543721.501 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T19:28:41.501Z,1654543721.501 [Default:CheckIn:C.Wait] Stopped
2022-06-06T19:28:41.501Z,1654543721.501 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T19:28:41.501Z,1654543721.501 [Default:CheckIn:D] Running Loop=1
2022-06-06T19:28:41.910Z,1654543721.910 [Default:CheckIn:D] Stopped
2022-06-06T19:28:41.910Z,1654543721.910 [Default:CheckIn:E] Running Loop=1
2022-06-06T19:28:42.309Z,1654543722.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 178.987321 min 
2022-06-06T19:28:42.309Z,1654543722.309 [Default:CheckIn:E] Stopped
2022-06-06T19:28:42.309Z,1654543722.309 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T19:28:42.310Z,1654543722.310 [Default:CheckIn] Stopped
2022-06-06T19:28:42.310Z,1654543722.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T19:28:42.310Z,1654543722.310 [Default:CheckIn](INFO): Running loop #18
2022-06-06T19:28:42.310Z,1654543722.310 [Default:CheckIn] Running Loop=18
2022-06-06T19:28:42.310Z,1654543722.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T19:28:42.310Z,1654543722.310 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T19:30:03.129Z,1654543803.129 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-06-06T19:30:03.129Z,1654543803.129 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:30:03.139Z,1654543803.139 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:30:03.540Z,1654543803.540 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:30:03.540Z,1654543803.540 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-06-06T19:32:51.753Z,1654543971.753 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T19:32:51.753Z,1654543971.753 [NAL9602] Data Fault, FailCount= 2
2022-06-06T19:32:51.753Z,1654543971.753 [NAL9602](ERROR): Data Fault
2022-06-06T19:32:51.859Z,1654543971.859 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T19:32:52.125Z,1654543972.125 [NAL9602](INFO): Powering down
2022-06-06T19:32:53.030Z,1654543973.030 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T19:32:53.030Z,1654543973.030 [NAL9602] No Fault, FailCount= 2
2022-06-06T19:33:04.275Z,1654543984.275 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-06-06T19:33:04.275Z,1654543984.275 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:33:04.297Z,1654543984.297 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:33:04.713Z,1654543984.713 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:33:04.713Z,1654543984.713 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-06-06T19:33:22.453Z,1654544002.453 [NAL9602](INFO): Powering up NAL9602
2022-06-06T19:33:33.369Z,1654544013.369 [NAL9602](INFO): NAL9602 initialized
2022-06-06T19:33:42.691Z,1654544022.691 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T19:28:42.3Z
2022-06-06T19:33:42.691Z,1654544022.691 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T19:33:42.691Z,1654544022.691 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T19:33:49.775Z,1654544029.775 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0055.lzma
2022-06-06T19:33:50.778Z,1654544030.778 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0055.lzma.bak
2022-06-06T19:33:50.778Z,1654544030.778 [DataOverHttps](INFO): SBD MOMSN=16861529
2022-06-06T19:34:06.512Z,1654544046.512 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220606T162559/Express0056.lzma
2022-06-06T19:34:07.514Z,1654544047.514 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0056.lzma.bak
2022-06-06T19:34:07.514Z,1654544047.514 [DataOverHttps](INFO): SBD MOMSN=16861531
2022-06-06T19:34:08.535Z,1654544048.535 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T19:34:08.535Z,1654544048.535 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T19:34:08.535Z,1654544048.535 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T19:36:05.272Z,1654544165.272 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-06-06T19:36:05.272Z,1654544165.272 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:36:05.282Z,1654544165.282 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:36:05.690Z,1654544165.690 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:36:05.690Z,1654544165.690 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-06-06T19:39:06.273Z,1654544346.273 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-06-06T19:39:06.273Z,1654544346.273 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:39:06.283Z,1654544346.283 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:39:06.711Z,1654544346.711 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:39:06.712Z,1654544346.712 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-06-06T19:39:09.097Z,1654544349.097 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T19:39:09.097Z,1654544349.097 [Default:CheckIn:C.Wait] Stopped
2022-06-06T19:39:09.097Z,1654544349.097 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T19:39:09.097Z,1654544349.097 [Default:CheckIn:D] Running Loop=1
2022-06-06T19:39:09.501Z,1654544349.501 [Default:CheckIn:D] Stopped
2022-06-06T19:39:09.501Z,1654544349.501 [Default:CheckIn:E] Running Loop=1
2022-06-06T19:39:09.926Z,1654544349.926 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 189.447184 min 
2022-06-06T19:39:09.926Z,1654544349.926 [Default:CheckIn:E] Stopped
2022-06-06T19:39:09.926Z,1654544349.926 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T19:39:09.926Z,1654544349.926 [Default:CheckIn] Stopped
2022-06-06T19:39:09.926Z,1654544349.926 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T19:39:09.927Z,1654544349.927 [Default:CheckIn](INFO): Running loop #19
2022-06-06T19:39:09.927Z,1654544349.927 [Default:CheckIn] Running Loop=19
2022-06-06T19:39:09.927Z,1654544349.927 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T19:39:09.927Z,1654544349.927 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T19:42:07.289Z,1654544527.289 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-06-06T19:42:07.289Z,1654544527.289 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:42:07.315Z,1654544527.315 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:42:07.691Z,1654544527.691 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:42:07.691Z,1654544527.691 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-06-06T19:43:34.197Z,1654544614.197 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T19:43:34.197Z,1654544614.197 [NAL9602] Data Fault, FailCount= 3
2022-06-06T19:43:34.197Z,1654544614.197 [NAL9602](ERROR): Data Fault
2022-06-06T19:43:34.257Z,1654544614.257 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T19:43:34.601Z,1654544614.601 [NAL9602](INFO): Powering down
2022-06-06T19:43:35.429Z,1654544615.429 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T19:43:35.429Z,1654544615.429 [NAL9602] No Fault, FailCount= 3
2022-06-06T19:44:04.907Z,1654544644.907 [NAL9602](INFO): Powering up NAL9602
2022-06-06T19:44:10.162Z,1654544650.162 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T19:39:09.9Z
2022-06-06T19:44:10.162Z,1654544650.162 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T19:44:10.162Z,1654544650.162 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T19:44:15.816Z,1654544655.816 [NAL9602](INFO): NAL9602 initialized
2022-06-06T19:44:17.075Z,1654544657.075 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0058.lzma
2022-06-06T19:44:18.078Z,1654544658.078 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0058.lzma.bak
2022-06-06T19:44:18.078Z,1654544658.078 [DataOverHttps](INFO): SBD MOMSN=16861535
2022-06-06T19:44:35.768Z,1654544675.768 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220606T162559/Express0059.lzma
2022-06-06T19:44:36.769Z,1654544676.769 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0059.lzma.bak
2022-06-06T19:44:36.770Z,1654544676.770 [DataOverHttps](INFO): SBD MOMSN=16861537
2022-06-06T19:44:38.078Z,1654544678.078 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T19:44:38.078Z,1654544678.078 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T19:44:38.078Z,1654544678.078 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T19:45:08.403Z,1654544708.403 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-06-06T19:45:08.403Z,1654544708.403 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:45:08.426Z,1654544708.426 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:45:08.817Z,1654544708.817 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:45:08.817Z,1654544708.817 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-06-06T19:48:09.386Z,1654544889.386 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-06-06T19:48:09.386Z,1654544889.386 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:48:09.396Z,1654544889.396 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:48:09.792Z,1654544889.792 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:48:09.792Z,1654544889.792 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2022-06-06T19:49:38.686Z,1654544978.686 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T19:49:38.686Z,1654544978.686 [Default:CheckIn:C.Wait] Stopped
2022-06-06T19:49:38.686Z,1654544978.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T19:49:38.686Z,1654544978.686 [Default:CheckIn:D] Running Loop=1
2022-06-06T19:49:39.094Z,1654544979.094 [Default:CheckIn:D] Stopped
2022-06-06T19:49:39.094Z,1654544979.094 [Default:CheckIn:E] Running Loop=1
2022-06-06T19:49:39.494Z,1654544979.494 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 199.940397 min 
2022-06-06T19:49:39.494Z,1654544979.494 [Default:CheckIn:E] Stopped
2022-06-06T19:49:39.494Z,1654544979.494 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T19:49:39.494Z,1654544979.494 [Default:CheckIn] Stopped
2022-06-06T19:49:39.494Z,1654544979.494 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T19:49:39.495Z,1654544979.495 [Default:CheckIn](INFO): Running loop #20
2022-06-06T19:49:39.495Z,1654544979.495 [Default:CheckIn] Running Loop=20
2022-06-06T19:49:39.495Z,1654544979.495 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T19:49:39.495Z,1654544979.495 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T19:51:10.406Z,1654545070.406 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2022-06-06T19:51:10.406Z,1654545070.406 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:51:10.416Z,1654545070.416 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:51:10.837Z,1654545070.837 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:51:10.837Z,1654545070.837 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2022-06-06T19:54:11.472Z,1654545251.472 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2022-06-06T19:54:11.472Z,1654545251.472 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:54:11.482Z,1654545251.482 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:54:11.846Z,1654545251.846 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T19:54:11.846Z,1654545251.846 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2022-06-06T19:54:17.445Z,1654545257.445 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T19:54:17.445Z,1654545257.445 [NAL9602] Data Fault, FailCount= 4
2022-06-06T19:54:17.445Z,1654545257.445 [NAL9602](ERROR): Data Fault
2022-06-06T19:54:17.599Z,1654545257.599 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T19:54:17.853Z,1654545257.853 [NAL9602](INFO): Powering down
2022-06-06T19:54:18.762Z,1654545258.762 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T19:54:18.762Z,1654545258.762 [NAL9602] No Fault, FailCount= 4
2022-06-06T19:54:26.361Z,1654545266.361 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:54:39.721Z,1654545279.721 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T19:49:39.5Z
2022-06-06T19:54:39.721Z,1654545279.721 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T19:54:39.721Z,1654545279.721 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T19:54:41.307Z,1654545281.307 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:54:46.875Z,1654545286.875 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220606T162559/Courier0061.lzma
2022-06-06T19:54:47.878Z,1654545287.878 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0061.lzma.bak
2022-06-06T19:54:47.878Z,1654545287.878 [DataOverHttps](INFO): SBD MOMSN=16861542
2022-06-06T19:54:48.181Z,1654545288.181 [NAL9602](INFO): Powering up NAL9602
2022-06-06T19:54:56.707Z,1654545296.707 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:54:59.137Z,1654545299.137 [NAL9602](INFO): NAL9602 initialized
2022-06-06T19:55:05.732Z,1654545305.732 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220606T162559/Express0062.lzma
2022-06-06T19:55:06.733Z,1654545306.733 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0062.lzma.bak
2022-06-06T19:55:06.734Z,1654545306.734 [DataOverHttps](INFO): SBD MOMSN=16861545
2022-06-06T19:55:08.050Z,1654545308.050 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T19:55:08.050Z,1654545308.050 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T19:55:08.050Z,1654545308.050 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T19:55:11.658Z,1654545311.658 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:55:27.008Z,1654545327.008 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:55:42.359Z,1654545342.359 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:55:57.711Z,1654545357.711 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:56:13.063Z,1654545373.063 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:56:28.011Z,1654545388.011 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:56:42.960Z,1654545402.960 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T19:56:59.126Z,1654545419.126 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-06-06T19:56:59.128Z,1654545419.128 [BPC1](INFO): Received data from all battery sticks.
2022-06-06T19:57:12.484Z,1654545432.484 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2022-06-06T19:57:12.484Z,1654545432.484 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-06T19:57:12.499Z,1654545432.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T19:57:12.499Z,1654545432.499 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-06T20:00:08.621Z,1654545608.621 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:00:08.621Z,1654545608.621 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:00:08.621Z,1654545608.621 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:00:08.621Z,1654545608.621 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:00:09.044Z,1654545609.044 [Default:CheckIn:D] Stopped
2022-06-06T20:00:09.044Z,1654545609.044 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:00:09.453Z,1654545609.453 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 210.439567 min 
2022-06-06T20:00:09.453Z,1654545609.453 [Default:CheckIn:E] Stopped
2022-06-06T20:00:09.453Z,1654545609.453 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:00:09.453Z,1654545609.453 [Default:CheckIn] Stopped
2022-06-06T20:00:09.454Z,1654545609.454 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:00:09.454Z,1654545609.454 [Default:CheckIn](INFO): Running loop #21
2022-06-06T20:00:09.454Z,1654545609.454 [Default:CheckIn] Running Loop=21
2022-06-06T20:00:09.454Z,1654545609.454 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:00:09.454Z,1654545609.454 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:03:49.896Z,1654545829.896 [CommandExec](IMPORTANT): got command failComponent
2022-06-06T20:03:49.896Z,1654545829.896 [CommandExec](IMPORTANT): Failed components:
2022-06-06T20:03:49.897Z,1654545829.897 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault
2022-06-06T20:03:51.872Z,1654545831.872 [CommandExec](IMPORTANT): got command ibit
2022-06-06T20:03:52.108Z,1654545832.108 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-06-06T20:03:52.108Z,1654545832.108 [IBIT](IMPORTANT): Beginning control surface checks.
2022-06-06T20:03:52.112Z,1654545832.112 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T20:03:54.985Z,1654545834.985 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-06-06T20:04:03.000Z,1654545843.000 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T20:04:03.413Z,1654545843.413 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T20:04:06.229Z,1654545846.229 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-06-06T20:04:14.421Z,1654545854.421 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-06T20:04:14.849Z,1654545854.849 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-06T20:04:17.665Z,1654545857.665 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-06-06T20:04:25.740Z,1654545865.740 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002406
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002298
CHAN A3 (5V): -0.001739
CHAN B0 (3.3V): 0.000273
CHAN B1 (3.15aV): -0.000116
CHAN B2 (3.15bV): -0.000214
CHAN B3 (GND): 0.000115
OPEN: -0.000365
Full Scale: +/- 1 mA
2022-06-06T20:04:32.944Z,1654545872.944 [CommandExec](IMPORTANT): got command failComponent
2022-06-06T20:04:32.944Z,1654545872.944 [CommandExec](IMPORTANT): Failed components:
2022-06-06T20:04:32.945Z,1654545872.945 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault
2022-06-06T20:05:00.821Z,1654545900.821 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-06T20:05:00.821Z,1654545900.821 [NAL9602] Data Fault, FailCount= 5
2022-06-06T20:05:00.821Z,1654545900.821 [NAL9602](ERROR): Data Fault
2022-06-06T20:05:00.836Z,1654545900.836 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-06T20:05:00.836Z,1654545900.836 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-06-06T20:05:01.225Z,1654545901.225 [NAL9602](INFO): Powering down
2022-06-06T20:05:01.238Z,1654545901.238 [CBIT](INFO): Critical error at 20220606T200500
2022-06-06T20:05:42.344Z,1654545942.344 [CommandExec](IMPORTANT): got command failComponent
2022-06-06T20:05:42.344Z,1654545942.344 [CommandExec](IMPORTANT): Failed components:
2022-06-06T20:05:42.345Z,1654545942.345 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault
2022-06-06T20:05:42.345Z,1654545942.345 [CommandExec](IMPORTANT): NAL9602: Data Fault
2022-06-06T20:05:42.849Z,1654545942.849 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-06-06T20:05:43.250Z,1654545943.250 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2022-06-06T20:05:43.250Z,1654545943.250 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2022-06-06T20:05:43.250Z,1654545943.250 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2022-06-06T20:05:43.662Z,1654545943.662 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-06-06T20:05:43.662Z,1654545943.662 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-06-06T20:05:43.663Z,1654545943.663 [IBIT](IMPORTANT): Pressure:10.158778 PSI
2022-06-06T20:05:43.663Z,1654545943.663 [IBIT](IMPORTANT): Humidity:9.547064 %
2022-06-06T20:05:44.073Z,1654545944.073 [IBIT](IMPORTANT): Vehicle Pitch:-0.448872 degrees
2022-06-06T20:05:44.073Z,1654545944.073 [IBIT](IMPORTANT): Vehicle  Roll:0.470169 degrees
2022-06-06T20:05:44.073Z,1654545944.073 [IBIT](IMPORTANT): Vehicle  Heading:253.708984 degrees
2022-06-06T20:05:44.459Z,1654545944.459 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-06-06T20:05:44.460Z,1654545944.460 [IBIT](IMPORTANT): buoyancyNeutral: 347.211456 cc
2022-06-06T20:05:44.460Z,1654545944.460 [IBIT](IMPORTANT): massDefault: -1.314800 cm
2022-06-06T20:05:44.460Z,1654545944.460 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2022-06-06T20:05:44.461Z,1654545944.461 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2022-06-06T20:05:44.461Z,1654545944.461 [IBIT](IMPORTANT): IBIT FAILED
2022-06-06T20:05:44.868Z,1654545944.868 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-06T20:00:09.5Z
2022-06-06T20:05:44.868Z,1654545944.868 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:05:44.869Z,1654545944.869 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T20:05:49.312Z,1654545949.312 [CommandExec](IMPORTANT): got command failComponent
2022-06-06T20:05:49.312Z,1654545949.312 [CommandExec](IMPORTANT): Failed components:
2022-06-06T20:05:49.313Z,1654545949.313 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault
2022-06-06T20:05:49.313Z,1654545949.313 [CommandExec](IMPORTANT): NAL9602: Data Fault
2022-06-06T20:05:51.063Z,1654545951.063 [DataOverHttps](INFO): Sending 92 bytes from file Logs/20220606T162559/Courier0064.lzma
2022-06-06T20:05:51.704Z,1654545951.704 [CommandExec](IMPORTANT): got command failComponent
2022-06-06T20:05:51.704Z,1654545951.704 [CommandExec](IMPORTANT): Failed components:
2022-06-06T20:05:51.705Z,1654545951.705 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault
2022-06-06T20:05:51.705Z,1654545951.705 [CommandExec](IMPORTANT): NAL9602: Data Fault
2022-06-06T20:05:52.065Z,1654545952.065 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0064.lzma.bak
2022-06-06T20:05:52.066Z,1654545952.066 [DataOverHttps](INFO): SBD MOMSN=16861559
2022-06-06T20:06:07.301Z,1654545967.301 [DataOverHttps](INFO): Sending 887 bytes from file Logs/20220606T162559/Express0065.lzma
2022-06-06T20:06:08.302Z,1654545968.302 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0065.lzma.bak
2022-06-06T20:06:08.302Z,1654545968.302 [DataOverHttps](INFO): SBD MOMSN=16861562
2022-06-06T20:06:09.509Z,1654545969.509 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T20:06:09.509Z,1654545969.509 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T20:06:09.509Z,1654545969.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T20:06:19.816Z,1654545979.816 [CommandExec](IMPORTANT): got command failComponent
2022-06-06T20:06:19.816Z,1654545979.816 [CommandExec](IMPORTANT): Failed components:
2022-06-06T20:06:19.817Z,1654545979.817 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault
2022-06-06T20:06:19.817Z,1654545979.817 [CommandExec](IMPORTANT): NAL9602: Data Fault
2022-06-06T20:07:00.824Z,1654546020.824 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-06T20:07:00.824Z,1654546020.824 [NAL9602] No Fault, FailCount= 5
2022-06-06T20:07:01.213Z,1654546021.213 [NAL9602](INFO): Powering up NAL9602
2022-06-06T20:07:12.121Z,1654546032.121 [NAL9602](INFO): NAL9602 initialized
2022-06-06T20:07:24.572Z,1654546044.572 [CommandExec](IMPORTANT): got command failComponent
2022-06-06T20:07:24.572Z,1654546044.572 [CommandExec](IMPORTANT): Failed components:
2022-06-06T20:07:24.572Z,1654546044.572 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault
2022-06-06T20:11:10.120Z,1654546270.120 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:11:10.120Z,1654546270.120 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:11:10.120Z,1654546270.120 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:11:10.120Z,1654546270.120 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:11:10.519Z,1654546270.519 [Default:CheckIn:D] Stopped
2022-06-06T20:11:10.519Z,1654546270.519 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:11:10.941Z,1654546270.941 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 221.464160 min 
2022-06-06T20:11:10.941Z,1654546270.941 [Default:CheckIn:E] Stopped
2022-06-06T20:11:10.941Z,1654546270.941 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:11:10.942Z,1654546270.942 [Default:CheckIn] Stopped
2022-06-06T20:11:10.942Z,1654546270.942 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:11:10.942Z,1654546270.942 [Default:CheckIn](INFO): Running loop #22
2022-06-06T20:11:10.942Z,1654546270.942 [Default:CheckIn] Running Loop=22
2022-06-06T20:11:10.942Z,1654546270.942 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:11:10.942Z,1654546270.942 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:12:12.750Z,1654546332.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-06T20:12:12.750Z,1654546332.750 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2022-06-06T20:15:07.727Z,1654546507.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201506.00,A,3648.17120,N,12147.26906,W,0.408,235.09,060622,,,A*7B
2022-06-06T20:15:07.730Z,1654546507.730 [NAL9602](INFO): GPS fix at 20220606T201506: (36.802853, -121.787818)
2022-06-06T20:15:07.756Z,1654546507.756 [DeadReckonUsingMultipleVelocitySources](INFO): Failure count cleared after critical for DeadReckonUsingMultipleVelocitySources
2022-06-06T20:15:07.758Z,1654546507.758 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:15:07.758Z,1654546507.758 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T20:15:16.536Z,1654546516.536 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20220606T162559/Courier0067.lzma
2022-06-06T20:15:17.538Z,1654546517.538 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0067.lzma.bak
2022-06-06T20:15:17.538Z,1654546517.538 [DataOverHttps](INFO): SBD MOMSN=16861704
2022-06-06T20:15:34.300Z,1654546534.300 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20220606T162559/Express0068.lzma
2022-06-06T20:15:35.298Z,1654546535.298 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0068.lzma.bak
2022-06-06T20:15:35.298Z,1654546535.298 [DataOverHttps](INFO): SBD MOMSN=16861707
2022-06-06T20:15:36.432Z,1654546536.432 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T20:15:36.432Z,1654546536.432 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T20:15:36.432Z,1654546536.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T20:15:40.456Z,1654546540.456 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T20:20:37.064Z,1654546837.064 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:20:37.064Z,1654546837.064 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:20:37.064Z,1654546837.064 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:20:37.064Z,1654546837.064 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:20:37.454Z,1654546837.454 [Default:CheckIn:D] Stopped
2022-06-06T20:20:37.454Z,1654546837.454 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:20:37.863Z,1654546837.863 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 230.913070 min 
2022-06-06T20:20:37.863Z,1654546837.863 [Default:CheckIn:E] Stopped
2022-06-06T20:20:37.863Z,1654546837.863 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:20:37.863Z,1654546837.863 [Default:CheckIn] Stopped
2022-06-06T20:20:37.863Z,1654546837.863 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:20:37.863Z,1654546837.863 [Default:CheckIn](INFO): Running loop #23
2022-06-06T20:20:37.863Z,1654546837.863 [Default:CheckIn] Running Loop=23
2022-06-06T20:20:37.863Z,1654546837.863 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:20:37.864Z,1654546837.864 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:20:39.878Z,1654546839.878 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202038.00,A,3648.17173,N,12147.27393,W,1.555,158.57,060622,,,A*7A
2022-06-06T20:20:39.880Z,1654546839.880 [NAL9602](INFO): GPS fix at 20220606T202038: (36.802862, -121.787899)
2022-06-06T20:20:39.890Z,1654546839.890 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:20:39.890Z,1654546839.890 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T20:20:48.060Z,1654546848.060 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220606T162559/Courier0070.lzma
2022-06-06T20:20:49.062Z,1654546849.062 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0070.lzma.bak
2022-06-06T20:20:49.062Z,1654546849.062 [DataOverHttps](INFO): SBD MOMSN=16861809
2022-06-06T20:21:07.720Z,1654546867.720 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220606T162559/Express0071.lzma
2022-06-06T20:21:08.722Z,1654546868.722 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0071.lzma.bak
2022-06-06T20:21:08.722Z,1654546868.722 [DataOverHttps](INFO): SBD MOMSN=16861813
2022-06-06T20:21:09.816Z,1654546869.816 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T20:21:09.816Z,1654546869.816 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T20:21:09.816Z,1654546869.816 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T20:21:57.076Z,1654546917.076 [NAL9602](INFO): SBD MO Status=2, MOMSN=33059, MT Status=2, MTMSN=0
2022-06-06T20:21:57.076Z,1654546917.076 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-06T20:22:14.454Z,1654546934.454 [NAL9602](INFO): SBD MO Status=2, MOMSN=33059, MT Status=2, MTMSN=0
2022-06-06T20:22:14.454Z,1654546934.454 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-06T20:22:46.784Z,1654546966.784 [NAL9602](INFO): SBD MO Status=2, MOMSN=33059, MT Status=2, MTMSN=0
2022-06-06T20:22:46.784Z,1654546966.784 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-06T20:23:11.022Z,1654546991.022 [NAL9602](INFO): SBD MO Status=2, MOMSN=33059, MT Status=2, MTMSN=0
2022-06-06T20:23:11.022Z,1654546991.022 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-06T20:23:35.670Z,1654547015.670 [NAL9602](INFO): SBD MO Status=0, MOMSN=33059, MT Status=0, MTMSN=0
2022-06-06T20:23:35.670Z,1654547015.670 [NAL9602](INFO): No messages in MT queue
2022-06-06T20:23:35.670Z,1654547015.670 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2022-06-06T20:24:06.366Z,1654547046.366 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T20:26:10.446Z,1654547170.446 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:26:10.446Z,1654547170.446 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:26:10.446Z,1654547170.446 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:26:10.447Z,1654547170.447 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:26:10.850Z,1654547170.850 [Default:CheckIn:D] Stopped
2022-06-06T20:26:10.850Z,1654547170.850 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:26:11.265Z,1654547171.265 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 236.469661 min 
2022-06-06T20:26:11.265Z,1654547171.265 [Default:CheckIn:E] Stopped
2022-06-06T20:26:11.265Z,1654547171.265 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:26:11.265Z,1654547171.265 [Default:CheckIn] Stopped
2022-06-06T20:26:11.265Z,1654547171.265 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:26:11.265Z,1654547171.265 [Default:CheckIn](INFO): Running loop #24
2022-06-06T20:26:11.265Z,1654547171.265 [Default:CheckIn] Running Loop=24
2022-06-06T20:26:11.265Z,1654547171.265 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:26:11.266Z,1654547171.266 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:26:13.266Z,1654547173.266 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202611.00,A,3648.16389,N,12147.21563,W,0.039,21.90,060622,,,A*44
2022-06-06T20:26:13.269Z,1654547173.269 [NAL9602](INFO): GPS fix at 20220606T202611: (36.802732, -121.786927)
2022-06-06T20:26:13.278Z,1654547173.278 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:26:13.279Z,1654547173.279 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T20:26:24.263Z,1654547184.263 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220606T162559/Courier0073.lzma
2022-06-06T20:26:25.266Z,1654547185.266 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0073.lzma.bak
2022-06-06T20:26:25.266Z,1654547185.266 [DataOverHttps](INFO): SBD MOMSN=16861875
2022-06-06T20:26:31.102Z,1654547191.102 [NAL9602](INFO): SBD MO Status=0, MOMSN=33060, MT Status=0, MTMSN=0
2022-06-06T20:26:31.102Z,1654547191.102 [NAL9602](INFO): No messages in MT queue
2022-06-06T20:26:41.896Z,1654547201.896 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20220606T162559/Express0074.lzma
2022-06-06T20:26:42.898Z,1654547202.898 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0074.lzma.bak
2022-06-06T20:26:42.898Z,1654547202.898 [DataOverHttps](INFO): SBD MOMSN=16861878
2022-06-06T20:26:44.036Z,1654547204.036 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T20:26:44.036Z,1654547204.036 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T20:26:44.036Z,1654547204.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T20:27:01.857Z,1654547221.857 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T20:31:44.451Z,1654547504.451 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:31:44.451Z,1654547504.451 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:31:44.451Z,1654547504.451 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:31:44.451Z,1654547504.451 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:31:44.848Z,1654547504.848 [Default:CheckIn:D] Stopped
2022-06-06T20:31:44.848Z,1654547504.848 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:31:45.252Z,1654547505.252 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 242.036296 min 
2022-06-06T20:31:45.252Z,1654547505.252 [Default:CheckIn:E] Stopped
2022-06-06T20:31:45.253Z,1654547505.253 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:31:45.253Z,1654547505.253 [Default:CheckIn] Stopped
2022-06-06T20:31:45.253Z,1654547505.253 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:31:45.253Z,1654547505.253 [Default:CheckIn](INFO): Running loop #25
2022-06-06T20:31:45.253Z,1654547505.253 [Default:CheckIn] Running Loop=25
2022-06-06T20:31:45.253Z,1654547505.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:31:45.253Z,1654547505.253 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:31:47.258Z,1654547507.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203146.00,A,3648.16524,N,12147.21387,W,0.019,0.05,060622,,,D*75
2022-06-06T20:31:47.260Z,1654547507.260 [NAL9602](INFO): GPS fix at 20220606T203146: (36.802754, -121.786898)
2022-06-06T20:31:47.276Z,1654547507.276 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:31:47.276Z,1654547507.276 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T20:31:55.007Z,1654547515.007 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220606T162559/Courier0076.lzma
2022-06-06T20:31:56.010Z,1654547516.010 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0076.lzma.bak
2022-06-06T20:31:56.010Z,1654547516.010 [DataOverHttps](INFO): SBD MOMSN=16861951
2022-06-06T20:32:00.590Z,1654547520.590 [NAL9602](INFO): SBD MO Status=0, MOMSN=33061, MT Status=0, MTMSN=0
2022-06-06T20:32:00.590Z,1654547520.590 [NAL9602](INFO): No messages in MT queue
2022-06-06T20:32:11.895Z,1654547531.895 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20220606T162559/Express0077.lzma
2022-06-06T20:32:12.898Z,1654547532.898 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0077.lzma.bak
2022-06-06T20:32:12.898Z,1654547532.898 [DataOverHttps](INFO): SBD MOMSN=16861955
2022-06-06T20:32:13.948Z,1654547533.948 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T20:32:13.948Z,1654547533.948 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T20:32:13.948Z,1654547533.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T20:32:31.330Z,1654547551.330 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T20:37:14.735Z,1654547834.735 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:37:14.735Z,1654547834.735 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:37:14.735Z,1654547834.735 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:37:14.735Z,1654547834.735 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:37:15.154Z,1654547835.154 [Default:CheckIn:D] Stopped
2022-06-06T20:37:15.154Z,1654547835.154 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:37:15.546Z,1654547835.546 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 247.541406 min 
2022-06-06T20:37:15.547Z,1654547835.547 [Default:CheckIn:E] Stopped
2022-06-06T20:37:15.547Z,1654547835.547 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:37:15.547Z,1654547835.547 [Default:CheckIn] Stopped
2022-06-06T20:37:15.547Z,1654547835.547 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:37:15.547Z,1654547835.547 [Default:CheckIn](INFO): Running loop #26
2022-06-06T20:37:15.547Z,1654547835.547 [Default:CheckIn] Running Loop=26
2022-06-06T20:37:15.547Z,1654547835.547 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:37:15.547Z,1654547835.547 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:37:17.560Z,1654547837.560 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203716.00,A,3648.16388,N,12147.21515,W,0.039,0.05,060622,,,D*79
2022-06-06T20:37:17.562Z,1654547837.562 [NAL9602](INFO): GPS fix at 20220606T203716: (36.802731, -121.786919)
2022-06-06T20:37:17.576Z,1654547837.576 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:37:17.577Z,1654547837.577 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T20:37:22.402Z,1654547842.402 [NAL9602](INFO): SBD MO Status=0, MOMSN=33062, MT Status=0, MTMSN=0
2022-06-06T20:37:22.402Z,1654547842.402 [NAL9602](INFO): No messages in MT queue
2022-06-06T20:37:25.251Z,1654547845.251 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20220606T162559/Courier0079.lzma
2022-06-06T20:37:26.254Z,1654547846.254 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0079.lzma.bak
2022-06-06T20:37:26.254Z,1654547846.254 [DataOverHttps](INFO): SBD MOMSN=16861965
2022-06-06T20:37:42.107Z,1654547862.107 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20220606T162559/Express0080.lzma
2022-06-06T20:37:43.110Z,1654547863.110 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0080.lzma.bak
2022-06-06T20:37:43.110Z,1654547863.110 [DataOverHttps](INFO): SBD MOMSN=16861968
2022-06-06T20:37:44.232Z,1654547864.232 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T20:37:44.232Z,1654547864.232 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T20:37:44.233Z,1654547864.233 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T20:37:53.108Z,1654547873.108 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T20:42:44.982Z,1654548164.982 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:42:44.982Z,1654548164.982 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:42:44.982Z,1654548164.982 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:42:44.982Z,1654548164.982 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:42:45.392Z,1654548165.392 [Default:CheckIn:D] Stopped
2022-06-06T20:42:45.392Z,1654548165.392 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:42:45.787Z,1654548165.787 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 253.045361 min 
2022-06-06T20:42:45.787Z,1654548165.787 [Default:CheckIn:E] Stopped
2022-06-06T20:42:45.787Z,1654548165.787 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:42:45.787Z,1654548165.787 [Default:CheckIn] Stopped
2022-06-06T20:42:45.787Z,1654548165.787 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:42:45.787Z,1654548165.787 [Default:CheckIn](INFO): Running loop #27
2022-06-06T20:42:45.787Z,1654548165.787 [Default:CheckIn] Running Loop=27
2022-06-06T20:42:45.811Z,1654548165.811 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:42:45.812Z,1654548165.812 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:42:47.798Z,1654548167.798 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204247.00,A,3648.49250,N,12147.11527,W,4.860,352.80,060622,,,D*7A
2022-06-06T20:42:47.801Z,1654548167.801 [NAL9602](INFO): GPS fix at 20220606T204247: (36.808208, -121.785254)
2022-06-06T20:42:47.814Z,1654548167.814 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:42:47.815Z,1654548167.815 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T20:42:48.619Z,1654548168.619 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T20:42:48.619Z,1654548168.619 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T20:42:48.619Z,1654548168.619 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T20:43:16.482Z,1654548196.482 [NAL9602](INFO): SBD MO Status=2, MOMSN=33063, MT Status=2, MTMSN=0
2022-06-06T20:43:16.482Z,1654548196.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-06T20:43:27.391Z,1654548207.391 [NAL9602](INFO): SBD MO Status=0, MOMSN=33063, MT Status=0, MTMSN=0
2022-06-06T20:43:27.391Z,1654548207.391 [NAL9602](INFO): No messages in MT queue
2022-06-06T20:43:58.092Z,1654548238.092 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T20:47:49.307Z,1654548469.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:47:49.307Z,1654548469.307 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:47:49.307Z,1654548469.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:47:49.308Z,1654548469.308 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:47:49.723Z,1654548469.723 [Default:CheckIn:D] Stopped
2022-06-06T20:47:49.723Z,1654548469.723 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:47:50.112Z,1654548470.112 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 258.117562 min 
2022-06-06T20:47:50.112Z,1654548470.112 [Default:CheckIn:E] Stopped
2022-06-06T20:47:50.112Z,1654548470.112 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:47:50.112Z,1654548470.112 [Default:CheckIn] Stopped
2022-06-06T20:47:50.112Z,1654548470.112 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:47:50.112Z,1654548470.112 [Default:CheckIn](INFO): Running loop #28
2022-06-06T20:47:50.112Z,1654548470.112 [Default:CheckIn] Running Loop=28
2022-06-06T20:47:50.112Z,1654548470.112 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:47:50.112Z,1654548470.112 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:47:52.126Z,1654548472.126 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204751.00,A,3648.32402,N,12147.89883,W,11.080,270.50,060622,,,A*4C
2022-06-06T20:47:52.128Z,1654548472.128 [NAL9602](INFO): GPS fix at 20220606T204751: (36.805400, -121.798314)
2022-06-06T20:47:52.138Z,1654548472.138 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:47:52.138Z,1654548472.138 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T20:47:59.111Z,1654548479.111 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220606T162559/Courier0082.lzma
2022-06-06T20:47:59.398Z,1654548479.398 [NAL9602](INFO): SBD MO Status=0, MOMSN=33064, MT Status=0, MTMSN=0
2022-06-06T20:47:59.398Z,1654548479.398 [NAL9602](INFO): No messages in MT queue
2022-06-06T20:48:00.114Z,1654548480.114 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0082.lzma.bak
2022-06-06T20:48:00.114Z,1654548480.114 [DataOverHttps](INFO): SBD MOMSN=16862040
2022-06-06T20:48:15.835Z,1654548495.835 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220606T162559/Courier0085.lzma
2022-06-06T20:48:16.838Z,1654548496.838 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0085.lzma.bak
2022-06-06T20:48:16.838Z,1654548496.838 [DataOverHttps](INFO): SBD MOMSN=16862043
2022-06-06T20:48:30.154Z,1654548510.154 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T20:48:35.359Z,1654548515.359 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220606T162559/Express0083.lzma
2022-06-06T20:48:36.362Z,1654548516.362 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0083.lzma.bak
2022-06-06T20:48:36.362Z,1654548516.362 [DataOverHttps](INFO): SBD MOMSN=16862046
2022-06-06T20:49:02.344Z,1654548542.344 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20220606T162559/Express0086.lzma
2022-06-06T20:49:03.346Z,1654548543.346 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0086.lzma.bak
2022-06-06T20:49:03.346Z,1654548543.346 [DataOverHttps](INFO): SBD MOMSN=16862049
2022-06-06T20:49:04.581Z,1654548544.581 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T20:49:04.581Z,1654548544.581 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T20:49:04.581Z,1654548544.581 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T20:54:05.214Z,1654548845.214 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:54:05.214Z,1654548845.214 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:54:05.214Z,1654548845.214 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:54:05.214Z,1654548845.214 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:54:05.624Z,1654548845.624 [Default:CheckIn:D] Stopped
2022-06-06T20:54:05.624Z,1654548845.624 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:54:06.019Z,1654548846.019 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 264.382568 min 
2022-06-06T20:54:06.019Z,1654548846.019 [Default:CheckIn:E] Stopped
2022-06-06T20:54:06.019Z,1654548846.019 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:54:06.019Z,1654548846.019 [Default:CheckIn] Stopped
2022-06-06T20:54:06.019Z,1654548846.019 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:54:06.051Z,1654548846.051 [Default:CheckIn](INFO): Running loop #29
2022-06-06T20:54:06.051Z,1654548846.051 [Default:CheckIn] Running Loop=29
2022-06-06T20:54:06.051Z,1654548846.051 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:54:06.051Z,1654548846.051 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:54:08.030Z,1654548848.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205407.00,A,3648.17376,N,12149.12801,W,7.911,250.45,060622,,,A*78
2022-06-06T20:54:08.033Z,1654548848.033 [NAL9602](INFO): GPS fix at 20220606T205407: (36.802896, -121.818800)
2022-06-06T20:54:08.042Z,1654548848.042 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:54:08.043Z,1654548848.043 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T20:54:15.455Z,1654548855.455 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220606T162559/Courier0088.lzma
2022-06-06T20:54:16.458Z,1654548856.458 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0088.lzma.bak
2022-06-06T20:54:16.458Z,1654548856.458 [DataOverHttps](INFO): SBD MOMSN=16862069
2022-06-06T20:54:24.598Z,1654548864.598 [NAL9602](INFO): SBD MO Status=0, MOMSN=33065, MT Status=0, MTMSN=0
2022-06-06T20:54:24.598Z,1654548864.598 [NAL9602](INFO): No messages in MT queue
2022-06-06T20:54:51.355Z,1654548891.355 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20220606T162559/Express0089.lzma
2022-06-06T20:54:52.358Z,1654548892.358 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0089.lzma.bak
2022-06-06T20:54:52.358Z,1654548892.358 [DataOverHttps](INFO): SBD MOMSN=16862072
2022-06-06T20:54:53.690Z,1654548893.690 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T20:54:53.691Z,1654548893.691 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T20:54:53.691Z,1654548893.691 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T20:54:55.302Z,1654548895.302 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T20:57:24.864Z,1654549044.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-06T20:59:54.418Z,1654549194.418 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T20:59:54.418Z,1654549194.418 [Default:CheckIn:C.Wait] Stopped
2022-06-06T20:59:54.418Z,1654549194.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T20:59:54.418Z,1654549194.418 [Default:CheckIn:D] Running Loop=1
2022-06-06T20:59:54.799Z,1654549194.799 [Default:CheckIn:D] Stopped
2022-06-06T20:59:54.799Z,1654549194.799 [Default:CheckIn:E] Running Loop=1
2022-06-06T20:59:55.197Z,1654549195.197 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 270.202148 min 
2022-06-06T20:59:55.197Z,1654549195.197 [Default:CheckIn:E] Stopped
2022-06-06T20:59:55.197Z,1654549195.197 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T20:59:55.197Z,1654549195.197 [Default:CheckIn] Stopped
2022-06-06T20:59:55.197Z,1654549195.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T20:59:55.197Z,1654549195.197 [Default:CheckIn](INFO): Running loop #30
2022-06-06T20:59:55.197Z,1654549195.197 [Default:CheckIn] Running Loop=30
2022-06-06T20:59:55.197Z,1654549195.197 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T20:59:55.198Z,1654549195.198 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T20:59:57.186Z,1654549197.186 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205956.00,A,3647.86908,N,12150.26829,W,10.361,260.53,060622,,,A*4F
2022-06-06T20:59:57.188Z,1654549197.188 [NAL9602](INFO): GPS fix at 20220606T205956: (36.797818, -121.837805)
2022-06-06T20:59:57.198Z,1654549197.198 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T20:59:57.198Z,1654549197.198 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T21:00:04.479Z,1654549204.479 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220606T162559/Courier0091.lzma
2022-06-06T21:00:05.482Z,1654549205.482 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0091.lzma.bak
2022-06-06T21:00:05.482Z,1654549205.482 [DataOverHttps](INFO): SBD MOMSN=16862160
2022-06-06T21:00:17.778Z,1654549217.778 [NAL9602](INFO): SBD MO Status=0, MOMSN=33066, MT Status=0, MTMSN=0
2022-06-06T21:00:17.778Z,1654549217.778 [NAL9602](INFO): No messages in MT queue
2022-06-06T21:00:24.244Z,1654549224.244 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20220606T162559/Express0092.lzma
2022-06-06T21:00:25.247Z,1654549225.247 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0092.lzma.bak
2022-06-06T21:00:25.247Z,1654549225.247 [DataOverHttps](INFO): SBD MOMSN=16862163
2022-06-06T21:00:26.309Z,1654549226.309 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T21:00:26.309Z,1654549226.309 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T21:00:26.310Z,1654549226.310 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T21:00:48.639Z,1654549248.639 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T21:01:57.593Z,1654549317.593 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T21:02:12.541Z,1654549332.541 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T21:02:27.893Z,1654549347.893 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T21:02:43.714Z,1654549363.714 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T21:02:59.065Z,1654549379.065 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T21:03:14.014Z,1654549394.014 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T21:03:28.961Z,1654549408.961 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T21:03:43.525Z,1654549423.525 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T21:03:58.858Z,1654549438.858 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-06T21:03:59.452Z,1654549439.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-06T21:04:14.212Z,1654549454.212 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2022-06-06T21:04:23.526Z,1654549463.526 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-06-06T21:04:23.529Z,1654549463.529 [BPC1](INFO): Received data from all battery sticks.
2022-06-06T21:04:30.576Z,1654549470.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-06T21:05:26.990Z,1654549526.990 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-06T21:05:26.990Z,1654549526.990 [Default:CheckIn:C.Wait] Stopped
2022-06-06T21:05:26.990Z,1654549526.990 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-06T21:05:26.990Z,1654549526.990 [Default:CheckIn:D] Running Loop=1
2022-06-06T21:05:27.391Z,1654549527.391 [Default:CheckIn:D] Stopped
2022-06-06T21:05:27.391Z,1654549527.391 [Default:CheckIn:E] Running Loop=1
2022-06-06T21:05:27.808Z,1654549527.808 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 275.745345 min 
2022-06-06T21:05:27.808Z,1654549527.808 [Default:CheckIn:E] Stopped
2022-06-06T21:05:27.808Z,1654549527.808 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-06T21:05:27.808Z,1654549527.808 [Default:CheckIn] Stopped
2022-06-06T21:05:27.808Z,1654549527.808 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-06T21:05:27.808Z,1654549527.808 [Default:CheckIn](INFO): Running loop #31
2022-06-06T21:05:27.808Z,1654549527.808 [Default:CheckIn] Running Loop=31
2022-06-06T21:05:27.809Z,1654549527.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-06T21:05:27.809Z,1654549527.809 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-06T21:05:29.810Z,1654549529.810 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210529.00,A,3647.76796,N,12150.80993,W,2.177,99.80,060622,,,D*4C
2022-06-06T21:05:29.812Z,1654549529.812 [NAL9602](INFO): GPS fix at 20220606T210529: (36.796133, -121.846832)
2022-06-06T21:05:29.822Z,1654549529.822 [Default:CheckIn:Read_GPS] Stopped
2022-06-06T21:05:29.822Z,1654549529.822 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-06T21:05:39.127Z,1654549539.127 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20220606T162559/Courier0094.lzma
2022-06-06T21:05:40.130Z,1654549540.130 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Courier0094.lzma.bak
2022-06-06T21:05:40.130Z,1654549540.130 [DataOverHttps](INFO): SBD MOMSN=16862196
2022-06-06T21:05:53.242Z,1654549553.242 [NAL9602](INFO): SBD MO Status=0, MOMSN=33067, MT Status=0, MTMSN=0
2022-06-06T21:05:53.242Z,1654549553.242 [NAL9602](INFO): No messages in MT queue
2022-06-06T21:05:56.011Z,1654549556.011 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220606T162559/Express0095.lzma
2022-06-06T21:05:57.014Z,1654549557.014 [DataOverHttps](INFO): Moved sent file to Logs/20220606T162559/Express0095.lzma.bak
2022-06-06T21:05:57.014Z,1654549557.014 [DataOverHttps](INFO): SBD MOMSN=16862199
2022-06-06T21:05:58.101Z,1654549558.101 [Default:CheckIn:Read_Iridium] Stopped
2022-06-06T21:05:58.101Z,1654549558.101 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-06T21:05:58.101Z,1654549558.101 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-06T21:06:23.940Z,1654549583.940 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-06T21:10:39.697Z,1654549839.697 [DataOverHttps](IMPORTANT): SBD MTMSN=20220606T211038
2022-06-06T21:10:47.120Z,1654549847.120 [DataOverHttps](INFO): Received command: restart logs