2023-05-12T20:02:32.073Z,1683921752.073 [Supervisor](DEBUG): Initializing supervisor.
2023-05-12T20:02:32.077Z,1683921752.077 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-12T20:02:32.078Z,1683921752.078 [SyncHandler](INFO): Protected caller Thread ID is 8529
2023-05-12T20:02:32.078Z,1683921752.078 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-12T20:02:32.079Z,1683921752.079 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-12T20:02:32.079Z,1683921752.079 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8530
2023-05-12T20:02:32.083Z,1683921752.083 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-12T20:02:32.100Z,1683921752.100 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-12T20:02:32.101Z,1683921752.101 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-12T20:02:32.101Z,1683921752.101 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8531
2023-05-12T20:02:32.105Z,1683921752.105 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-12T20:02:32.106Z,1683921752.106 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-12T20:02:32.107Z,1683921752.107 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8532
2023-05-12T20:02:32.109Z,1683921752.109 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-12T20:02:32.110Z,1683921752.110 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-12T20:02:32.110Z,1683921752.110 [logger ThreadHandler](INFO): Protected caller Thread ID is 8533
2023-05-12T20:02:32.113Z,1683921752.113 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-12T20:02:32.114Z,1683921752.114 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-12T20:02:32.115Z,1683921752.115 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-12T20:02:32.212Z,1683921752.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-12T20:02:32.212Z,1683921752.212 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-12T20:02:32.860Z,1683921752.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-12T20:02:32.860Z,1683921752.860 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-12T20:02:33.078Z,1683921753.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-12T20:02:33.079Z,1683921753.079 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-12T20:02:33.158Z,1683921753.158 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-12T20:02:33.288Z,1683921753.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-12T20:02:33.289Z,1683921753.289 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-12T20:02:33.370Z,1683921753.370 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-12T20:02:33.476Z,1683921753.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-12T20:02:33.477Z,1683921753.477 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-12T20:02:33.842Z,1683921753.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-12T20:02:33.842Z,1683921753.842 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-12T20:02:34.306Z,1683921754.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-12T20:02:34.306Z,1683921754.306 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-12T20:02:34.521Z,1683921754.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-12T20:02:34.521Z,1683921754.521 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-12T20:02:34.698Z,1683921754.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-12T20:02:34.699Z,1683921754.699 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-12T20:02:35.183Z,1683921755.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-12T20:02:35.184Z,1683921755.184 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-12T20:02:35.469Z,1683921755.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-12T20:02:35.470Z,1683921755.470 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-12T20:02:35.677Z,1683921755.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-12T20:02:35.677Z,1683921755.677 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-12T20:02:36.028Z,1683921756.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-12T20:02:36.028Z,1683921756.028 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-12T20:02:36.348Z,1683921756.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-12T20:02:36.350Z,1683921756.350 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-05-12T20:02:36.351Z,1683921756.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-05-12T20:02:36.434Z,1683921756.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-05-12T20:02:36.586Z,1683921756.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-05-12T20:02:36.686Z,1683921756.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-05-12T20:02:36.772Z,1683921756.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-05-12T20:02:36.887Z,1683921756.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-05-12T20:02:37.376Z,1683921757.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-05-12T20:02:37.910Z,1683921757.910 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-12T20:02:37.910Z,1683921757.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-05-12T20:02:38.004Z,1683921758.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-05-12T20:02:38.101Z,1683921758.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-05-12T20:02:38.236Z,1683921758.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-05-12T20:02:38.334Z,1683921758.334 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-12T20:02:38.342Z,1683921758.342 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-12T20:02:38.436Z,1683921758.436 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-12T20:02:38.437Z,1683921758.437 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-12T20:02:38.486Z,1683921758.486 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-12T20:02:38.543Z,1683921758.543 [VerticalControl] Loaded
2023-05-12T20:02:38.544Z,1683921758.544 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-12T20:02:38.546Z,1683921758.546 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-12T20:02:38.586Z,1683921758.586 [HorizontalControl] Loaded
2023-05-12T20:02:38.587Z,1683921758.587 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-12T20:02:38.589Z,1683921758.589 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-12T20:02:38.592Z,1683921758.592 [SpeedControl] Loaded
2023-05-12T20:02:38.592Z,1683921758.592 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-12T20:02:38.595Z,1683921758.595 [LoopControl](DEBUG): Construct LoopControl.
2023-05-12T20:02:38.595Z,1683921758.595 [LoopControl] Loaded
2023-05-12T20:02:38.596Z,1683921758.596 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-12T20:02:38.596Z,1683921758.596 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-12T20:02:38.596Z,1683921758.596 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-12T20:02:38.643Z,1683921758.643 [DepthRateCalculator] Loaded
2023-05-12T20:02:38.644Z,1683921758.644 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-12T20:02:38.648Z,1683921758.648 [PitchRateCalculator] Loaded
2023-05-12T20:02:38.648Z,1683921758.648 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-12T20:02:38.658Z,1683921758.658 [SpeedCalculator] Loaded
2023-05-12T20:02:38.658Z,1683921758.658 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-12T20:02:38.662Z,1683921758.662 [YawRateCalculator] Loaded
2023-05-12T20:02:38.663Z,1683921758.663 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-12T20:02:38.680Z,1683921758.680 [ElevatorOffsetCalculator] Loaded
2023-05-12T20:02:38.680Z,1683921758.680 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-12T20:02:38.681Z,1683921758.681 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-12T20:02:38.681Z,1683921758.681 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-12T20:02:38.707Z,1683921758.707 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-12T20:02:38.708Z,1683921758.708 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-12T20:02:38.774Z,1683921758.774 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-12T20:02:38.775Z,1683921758.775 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-12T20:02:39.067Z,1683921759.067 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-12T20:02:39.068Z,1683921759.068 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-12T20:02:39.207Z,1683921759.207 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-12T20:02:39.207Z,1683921759.207 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-12T20:02:39.629Z,1683921759.629 [AHRS_M2] Loaded
2023-05-12T20:02:39.629Z,1683921759.629 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-12T20:02:39.663Z,1683921759.663 [BackseatComponent] Loaded
2023-05-12T20:02:39.664Z,1683921759.664 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-12T20:02:39.665Z,1683921759.665 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2023-05-12T20:02:39.665Z,1683921759.665 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8615
2023-05-12T20:02:39.668Z,1683921759.668 [LcmUniversalReporter] Loaded
2023-05-12T20:02:39.668Z,1683921759.668 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-12T20:02:40.455Z,1683921760.455 [BPC1] Loaded
2023-05-12T20:02:40.455Z,1683921760.455 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-12T20:02:40.536Z,1683921760.536 [DATMMP] Loaded
2023-05-12T20:02:40.536Z,1683921760.536 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-12T20:02:40.537Z,1683921760.537 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409CA4E0
2023-05-12T20:02:40.537Z,1683921760.537 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 8616
2023-05-12T20:02:40.609Z,1683921760.609 [DataOverHttps] Loaded
2023-05-12T20:02:40.609Z,1683921760.609 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-12T20:02:40.610Z,1683921760.610 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409FA4E0
2023-05-12T20:02:40.611Z,1683921760.611 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8617
2023-05-12T20:02:40.631Z,1683921760.631 [Depth_Keller] Loaded
2023-05-12T20:02:40.631Z,1683921760.631 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-12T20:02:40.692Z,1683921760.692 [NAL9602] Loaded
2023-05-12T20:02:40.693Z,1683921760.693 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-12T20:02:40.721Z,1683921760.721 [Onboard] Loaded
2023-05-12T20:02:40.721Z,1683921760.721 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-12T20:02:40.722Z,1683921760.722 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0
2023-05-12T20:02:40.723Z,1683921760.723 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8618
2023-05-12T20:02:40.734Z,1683921760.734 [Power24vConverter] Loaded
2023-05-12T20:02:40.735Z,1683921760.735 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-12T20:02:40.747Z,1683921760.747 [Radio_Surface] Loaded
2023-05-12T20:02:40.747Z,1683921760.747 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-12T20:02:40.748Z,1683921760.748 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0
2023-05-12T20:02:40.749Z,1683921760.749 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8619
2023-05-12T20:02:40.792Z,1683921760.792 [RDI_Pathfinder] Loaded
2023-05-12T20:02:40.793Z,1683921760.793 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-05-12T20:02:40.794Z,1683921760.794 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-12T20:02:40.794Z,1683921760.794 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-12T20:02:40.900Z,1683921760.900 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-12T20:02:40.900Z,1683921760.900 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-12T20:02:40.913Z,1683921760.913 [NavChart] Loaded
2023-05-12T20:02:40.914Z,1683921760.914 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-12T20:02:40.919Z,1683921760.919 [UniversalFixResidualReporter] Loaded
2023-05-12T20:02:40.920Z,1683921760.920 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-12T20:02:40.920Z,1683921760.920 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-12T20:02:40.920Z,1683921760.920 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-12T20:02:41.054Z,1683921761.054 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-12T20:02:41.063Z,1683921761.063 [SBIT] Loaded
2023-05-12T20:02:41.063Z,1683921761.063 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-12T20:02:41.066Z,1683921761.066 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-12T20:02:41.078Z,1683921761.078 [IBIT] Loaded
2023-05-12T20:02:41.079Z,1683921761.079 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-12T20:02:41.084Z,1683921761.084 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-12T20:02:41.185Z,1683921761.185 [CBIT] Loaded
2023-05-12T20:02:41.185Z,1683921761.185 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-12T20:02:41.185Z,1683921761.185 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-12T20:02:41.186Z,1683921761.186 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-12T20:02:41.323Z,1683921761.323 [BuoyancyServo] Loaded
2023-05-12T20:02:41.323Z,1683921761.323 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-12T20:02:41.343Z,1683921761.343 [ElevatorServo] Loaded
2023-05-12T20:02:41.343Z,1683921761.343 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-12T20:02:41.362Z,1683921761.362 [MassServo] Loaded
2023-05-12T20:02:41.363Z,1683921761.363 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-12T20:02:41.380Z,1683921761.380 [RudderServo] Loaded
2023-05-12T20:02:41.381Z,1683921761.381 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-12T20:02:41.397Z,1683921761.397 [ThrusterServo] Loaded
2023-05-12T20:02:41.397Z,1683921761.397 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-05-12T20:02:41.398Z,1683921761.398 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-12T20:02:41.398Z,1683921761.398 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-12T20:02:41.408Z,1683921761.408 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-12T20:02:41.409Z,1683921761.409 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-12T20:02:41.576Z,1683921761.576 [CTD_Seabird] Loaded
2023-05-12T20:02:41.576Z,1683921761.576 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-12T20:02:41.577Z,1683921761.577 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BA24E0
2023-05-12T20:02:41.577Z,1683921761.577 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8620
2023-05-12T20:02:41.597Z,1683921761.597 [PAR_Licor] Loaded
2023-05-12T20:02:41.597Z,1683921761.597 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-12T20:02:41.627Z,1683921761.627 [WetLabsBB2FL] Loaded
2023-05-12T20:02:41.627Z,1683921761.627 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-12T20:02:41.628Z,1683921761.628 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD24E0
2023-05-12T20:02:41.629Z,1683921761.629 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8621
2023-05-12T20:02:41.648Z,1683921761.648 [WetLabsUBAT] Loaded
2023-05-12T20:02:41.648Z,1683921761.648 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-05-12T20:02:41.649Z,1683921761.649 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C024E0
2023-05-12T20:02:41.649Z,1683921761.649 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 8622
2023-05-12T20:02:41.650Z,1683921761.650 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-12T20:02:41.656Z,1683921761.656 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-12T20:02:41.659Z,1683921761.659 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-12T20:02:41.670Z,1683921761.670 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-12T20:02:41.671Z,1683921761.671 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C324E0
2023-05-12T20:02:41.671Z,1683921761.671 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8623
2023-05-12T20:02:41.675Z,1683921761.675 [Supervisor](INFO): Main Thread ID is 8528
2023-05-12T20:02:41.675Z,1683921761.675 [Supervisor](DEBUG): Running supervisor.
2023-05-12T20:02:41.676Z,1683921761.676 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8624
2023-05-12T20:02:41.677Z,1683921761.677 [CommandExec](INFO): Initializing the command executive.
2023-05-12T20:02:41.678Z,1683921761.678 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8625
2023-05-12T20:02:41.680Z,1683921761.680 [controlThread ThreadHandler](INFO): Handler Thread ID is 8626
2023-05-12T20:02:41.681Z,1683921761.681 [controlThread](DEBUG): Initializing ControlThread
2023-05-12T20:02:41.681Z,1683921761.681 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-12T20:02:41.683Z,1683921761.683 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-12T20:02:41.684Z,1683921761.684 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-12T20:02:41.684Z,1683921761.684 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-12T20:02:41.685Z,1683921761.685 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-12T20:02:41.685Z,1683921761.685 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-12T20:02:41.685Z,1683921761.685 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-12T20:02:41.686Z,1683921761.686 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-12T20:02:41.686Z,1683921761.686 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-12T20:02:41.692Z,1683921761.692 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-12T20:02:41.693Z,1683921761.693 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-12T20:02:41.693Z,1683921761.693 [SBIT](INFO): Initialize SBIT Component.
2023-05-12T20:02:41.693Z,1683921761.693 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973
2023-05-12T20:02:41.694Z,1683921761.694 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c
2023-05-12T20:02:41.694Z,1683921761.694 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-12T20:02:41.695Z,1683921761.695 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-05-12T20:02:41.696Z,1683921761.696 [SBIT](INFO): Beginning SBIT in 51.000000 seconds.
2023-05-12T20:02:41.697Z,1683921761.697 [IBIT](INFO): Initialize IBIT Component.
2023-05-12T20:02:41.698Z,1683921761.698 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-12T20:02:41.699Z,1683921761.699 [logger ThreadHandler](INFO): Handler Thread ID is 8627
2023-05-12T20:02:41.711Z,1683921761.711 [CBIT](DEBUG): Initialized mux pins.
2023-05-12T20:02:41.711Z,1683921761.711 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-12T20:02:41.720Z,1683921761.720 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8628
2023-05-12T20:02:41.732Z,1683921761.732 [DATMMP ThreadHandler](INFO): Handler Thread ID is 8629
2023-05-12T20:02:41.732Z,1683921761.732 [DATMMP](INFO): Start
2023-05-12T20:02:41.732Z,1683921761.732 [DATMMP](INFO): Powering up
2023-05-12T20:02:41.733Z,1683921761.733 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-12T20:02:41.735Z,1683921761.735 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-12T20:02:41.736Z,1683921761.736 [CBIT](DEBUG): Initializing heartbeat.
2023-05-12T20:02:41.737Z,1683921761.737 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8631
2023-05-12T20:02:41.738Z,1683921761.738 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-12T20:02:41.748Z,1683921761.748 [Onboard ThreadHandler](INFO): Handler Thread ID is 8632
2023-05-12T20:02:41.777Z,1683921761.777 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8633
2023-05-12T20:02:41.788Z,1683921761.788 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8634
2023-05-12T20:02:41.789Z,1683921761.789 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-12T20:02:41.792Z,1683921761.792 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8636
2023-05-12T20:02:41.796Z,1683921761.796 [WetLabsBB2FL](INFO): Powering up
2023-05-12T20:02:41.797Z,1683921761.797 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 8638
2023-05-12T20:02:41.800Z,1683921761.800 [WetLabsUBAT](INFO): Powering up
2023-05-12T20:02:41.801Z,1683921761.801 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8640
2023-05-12T20:02:41.804Z,1683921761.804 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-12T20:02:41.804Z,1683921761.804 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-12T20:02:41.805Z,1683921761.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-12T20:02:41.805Z,1683921761.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-12T20:02:41.805Z,1683921761.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-12T20:02:41.805Z,1683921761.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-12T20:02:41.805Z,1683921761.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-12T20:02:41.805Z,1683921761.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-12T20:02:41.807Z,1683921761.807 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-12T20:02:41.807Z,1683921761.807 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-12T20:02:41.847Z,1683921761.847 [CBIT](DEBUG): Backplane powered.
2023-05-12T20:02:41.852Z,1683921761.852 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-12T20:02:41.877Z,1683921761.877 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T20:02:41.921Z,1683921761.921 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-05-12T20:02:41.922Z,1683921761.922 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-12T20:02:41.983Z,1683921761.983 [DATMMP](INFO): Starting
2023-05-12T20:02:42.033Z,1683921762.033 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-12T20:02:42.034Z,1683921762.034 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-12T20:02:42.049Z,1683921762.049 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T20:02:42.101Z,1683921762.101 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-12T20:02:42.131Z,1683921762.131 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-12T20:02:42.172Z,1683921762.172 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-12T20:02:42.179Z,1683921762.179 [Radio_Surface](INFO): Powering up
2023-05-12T20:02:42.228Z,1683921762.228 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-05-12T20:02:42.233Z,1683921762.233 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-12T20:02:42.235Z,1683921762.235 [DATMMP](INFO): Starting
2023-05-12T20:02:42.251Z,1683921762.251 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-12T20:02:42.321Z,1683921762.321 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-05-12T20:02:42.322Z,1683921762.322 [Power24vConverter](INFO): Powering up.
2023-05-12T20:02:42.322Z,1683921762.322 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-05-12T20:02:42.330Z,1683921762.330 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-05-12T20:02:42.377Z,1683921762.377 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-12T20:02:42.383Z,1683921762.383 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-12T20:02:42.384Z,1683921762.384 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-12T20:02:42.391Z,1683921762.391 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-12T20:02:42.392Z,1683921762.392 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-12T20:02:42.399Z,1683921762.399 [MassServo](DEBUG): Initializing MassServo.
2023-05-12T20:02:42.400Z,1683921762.400 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-12T20:02:42.407Z,1683921762.407 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-12T20:02:42.408Z,1683921762.408 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-05-12T20:02:42.415Z,1683921762.415 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-05-12T20:02:42.487Z,1683921762.487 [DATMMP](INFO): Starting
2023-05-12T20:02:42.746Z,1683921762.746 [DATMMP](INFO): Starting
2023-05-12T20:02:42.999Z,1683921762.999 [DATMMP](INFO): Starting
2023-05-12T20:02:43.251Z,1683921763.251 [DATMMP](INFO): Starting
2023-05-12T20:02:43.525Z,1683921763.525 [DATMMP](INFO): Starting
2023-05-12T20:02:43.775Z,1683921763.775 [DATMMP](INFO): Starting
2023-05-12T20:02:44.028Z,1683921764.028 [DATMMP](INFO): Starting
2023-05-12T20:02:44.279Z,1683921764.279 [DATMMP](INFO): Starting
2023-05-12T20:02:44.407Z,1683921764.407 [WetLabsBB2FL](INFO): Powering down
2023-05-12T20:02:44.531Z,1683921764.531 [DATMMP](INFO): Starting
2023-05-12T20:02:44.783Z,1683921764.783 [DATMMP](INFO): Starting
2023-05-12T20:02:45.035Z,1683921765.035 [DATMMP](INFO): Starting
2023-05-12T20:02:45.287Z,1683921765.287 [DATMMP](INFO): Starting
2023-05-12T20:02:45.539Z,1683921765.539 [DATMMP](INFO): Starting
2023-05-12T20:02:45.791Z,1683921765.791 [DATMMP](INFO): Starting
2023-05-12T20:02:46.043Z,1683921766.043 [DATMMP](INFO): Starting
2023-05-12T20:02:46.296Z,1683921766.296 [DATMMP](INFO): Starting
2023-05-12T20:02:46.547Z,1683921766.547 [DATMMP](INFO): Starting
2023-05-12T20:02:46.799Z,1683921766.799 [DATMMP](INFO): Starting
2023-05-12T20:02:47.051Z,1683921767.051 [DATMMP](INFO): Starting
2023-05-12T20:02:47.303Z,1683921767.303 [DATMMP](INFO): Starting
2023-05-12T20:02:47.555Z,1683921767.555 [DATMMP](INFO): Starting
2023-05-12T20:02:47.807Z,1683921767.807 [DATMMP](INFO): Starting
2023-05-12T20:02:48.059Z,1683921768.059 [DATMMP](INFO): Starting
2023-05-12T20:02:48.320Z,1683921768.320 [DATMMP](INFO): Starting
2023-05-12T20:02:48.571Z,1683921768.571 [DATMMP](INFO): Starting
2023-05-12T20:02:48.821Z,1683921768.821 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-05-12T20:02:48.823Z,1683921768.823 [DATMMP](INFO): Starting
2023-05-12T20:02:49.075Z,1683921769.075 [DATMMP](INFO): Starting
2023-05-12T20:02:49.327Z,1683921769.327 [DATMMP](INFO): Starting
2023-05-12T20:02:49.579Z,1683921769.579 [DATMMP](INFO): Starting
2023-05-12T20:02:49.831Z,1683921769.831 [DATMMP](INFO): Starting
2023-05-12T20:02:50.083Z,1683921770.083 [DATMMP](INFO): Starting
2023-05-12T20:02:50.336Z,1683921770.336 [DATMMP](INFO): Starting
2023-05-12T20:02:50.587Z,1683921770.587 [DATMMP](INFO): Starting
2023-05-12T20:02:50.839Z,1683921770.839 [DATMMP](INFO): Starting
2023-05-12T20:02:51.071Z,1683921771.071 [WetLabsUBAT](INFO): Powering down
2023-05-12T20:02:51.091Z,1683921771.091 [DATMMP](INFO): Starting
2023-05-12T20:02:51.343Z,1683921771.343 [DATMMP](INFO): Starting
2023-05-12T20:02:51.595Z,1683921771.595 [DATMMP](INFO): Starting
2023-05-12T20:02:51.848Z,1683921771.848 [DATMMP](INFO): Starting
2023-05-12T20:02:52.099Z,1683921772.099 [DATMMP](INFO): Starting
2023-05-12T20:02:52.351Z,1683921772.351 [DATMMP](INFO): Starting
2023-05-12T20:02:52.603Z,1683921772.603 [DATMMP](INFO): Starting
2023-05-12T20:02:52.855Z,1683921772.855 [DATMMP](INFO): Starting
2023-05-12T20:02:53.107Z,1683921773.107 [DATMMP](INFO): Starting
2023-05-12T20:02:53.359Z,1683921773.359 [DATMMP](INFO): Starting
2023-05-12T20:02:53.611Z,1683921773.611 [DATMMP](INFO): Starting
2023-05-12T20:02:53.864Z,1683921773.864 [DATMMP](INFO): Starting
2023-05-12T20:02:53.866Z,1683921773.866 [DATMMP](INFO): DAT read: 

2023-05-12T20:02:53.869Z,1683921773.869 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-12T20:02:54.115Z,1683921774.115 [DATMMP](INFO): Starting
2023-05-12T20:02:54.367Z,1683921774.367 [DATMMP](INFO): Starting
2023-05-12T20:02:54.619Z,1683921774.619 [DATMMP](INFO): Starting
2023-05-12T20:02:54.871Z,1683921774.871 [DATMMP](INFO): Starting
2023-05-12T20:02:55.123Z,1683921775.123 [DATMMP](INFO): Starting
2023-05-12T20:02:55.375Z,1683921775.375 [DATMMP](INFO): Starting
2023-05-12T20:02:55.627Z,1683921775.627 [DATMMP](INFO): Starting
2023-05-12T20:02:55.628Z,1683921775.628 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-12T20:02:55.629Z,1683921775.629 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-12T20:02:55.630Z,1683921775.630 [DATMMP](INFO): DAT read: Apr 12 2000 20:02:49 

2023-05-12T20:02:55.761Z,1683921775.761 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004551
2023-05-12T20:02:55.880Z,1683921775.880 [DATMMP](INFO): Starting
2023-05-12T20:02:56.131Z,1683921776.131 [DATMMP](INFO): Starting
2023-05-12T20:02:56.383Z,1683921776.383 [DATMMP](INFO): Starting
2023-05-12T20:02:56.635Z,1683921776.635 [DATMMP](INFO): Starting
2023-05-12T20:02:56.636Z,1683921776.636 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-12T20:02:56.637Z,1683921776.637 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-12T20:02:56.638Z,1683921776.638 [DATMMP](INFO): commRate: 800
2023-05-12T20:02:56.887Z,1683921776.887 [DATMMP](INFO): Starting
2023-05-12T20:02:57.139Z,1683921777.139 [DATMMP](INFO): Starting
2023-05-12T20:02:57.391Z,1683921777.391 [DATMMP](INFO): Starting
2023-05-12T20:02:57.643Z,1683921777.643 [DATMMP](INFO): Starting
2023-05-12T20:02:57.895Z,1683921777.895 [DATMMP](INFO): Starting
2023-05-12T20:02:58.147Z,1683921778.147 [DATMMP](INFO): Starting
2023-05-12T20:02:58.399Z,1683921778.399 [DATMMP](INFO): Starting
2023-05-12T20:02:58.651Z,1683921778.651 [DATMMP](INFO): Starting
2023-05-12T20:02:58.703Z,1683921778.703 [DATMMP](INFO): entering command mode
2023-05-12T20:02:58.903Z,1683921778.903 [DATMMP](INFO): Starting
2023-05-12T20:02:58.904Z,1683921778.904 [DATMMP](INFO): DAT read: 

2023-05-12T20:02:58.904Z,1683921778.904 [DATMMP](INFO): DAT read: user:1>

2023-05-12T20:02:58.904Z,1683921778.904 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-12T20:02:58.905Z,1683921778.905 [DATMMP](INFO): entering MMP mode
2023-05-12T20:02:59.155Z,1683921779.155 [DATMMP](INFO): Starting
2023-05-12T20:02:59.156Z,1683921779.156 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T20:02:59.158Z,1683921779.158 [DATMMP](INFO): status rx: 	 x1
2023-05-12T20:02:59.158Z,1683921779.158 [DATMMP](INFO): MMP status: 1: started
2023-05-12T20:02:59.158Z,1683921779.158 [DATMMP](INFO): Received message type: status
2023-05-12T20:02:59.158Z,1683921779.158 [DATMMP](INFO): Handled
2023-05-12T20:02:59.407Z,1683921779.407 [DATMMP](INFO): Starting
2023-05-12T20:02:59.408Z,1683921779.408 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-12T20:02:59.409Z,1683921779.409 [DATMMP](INFO): Setting local address: 10
2023-05-12T20:02:59.411Z,1683921779.411 [DATMMP](INFO): Setting time to: 20:2:59 And date to:5/12/2023
2023-05-12T20:02:59.659Z,1683921779.659 [DATMMP](INFO): Starting
2023-05-12T20:02:59.660Z,1683921779.660 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T20:02:59.661Z,1683921779.661 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-12T20:02:59.661Z,1683921779.661 [DATMMP](INFO): Received message type: mdmpower
2023-05-12T20:02:59.661Z,1683921779.661 [DATMMP](INFO): Handled
2023-05-12T20:02:59.911Z,1683921779.911 [DATMMP](INFO): Starting
2023-05-12T20:02:59.912Z,1683921779.912 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-12T20:02:59.912Z,1683921779.912 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-12T20:02:59.913Z,1683921779.913 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-12T20:02:59.913Z,1683921779.913 [DATMMP](INFO): Match for : sreg_txpower
2023-05-12T20:02:59.913Z,1683921779.913 [DATMMP](INFO): ACK
2023-05-12T20:03:00.163Z,1683921780.163 [DATMMP](INFO): Starting
2023-05-12T20:03:00.164Z,1683921780.164 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T20:03:00.165Z,1683921780.165 [DATMMP](INFO): mdmlocal rx: 	 xA	 x0	 x0	 x0
2023-05-12T20:03:00.165Z,1683921780.165 [DATMMP](INFO): Received message type: mdmlocal
2023-05-12T20:03:00.165Z,1683921780.165 [DATMMP](INFO): Handled
2023-05-12T20:03:00.415Z,1683921780.415 [DATMMP](INFO): Starting
2023-05-12T20:03:00.416Z,1683921780.416 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-12T20:03:00.416Z,1683921780.416 [DATMMP](INFO): sreg_locaddr rx: 	 xA
2023-05-12T20:03:00.416Z,1683921780.416 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-12T20:03:00.417Z,1683921780.417 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-12T20:03:00.417Z,1683921780.417 [DATMMP](INFO): ACK
2023-05-12T20:03:00.667Z,1683921780.667 [DATMMP](INFO): Starting
2023-05-12T20:03:00.668Z,1683921780.668 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-12T20:03:00.669Z,1683921780.669 [DATMMP](INFO): timedate rx: 	 x0	 x14	 x2	 x3B	 x4	 xC	 x7B	 x0
2023-05-12T20:03:00.669Z,1683921780.669 [DATMMP](INFO): Received message type: timedate
2023-05-12T20:03:00.669Z,1683921780.669 [DATMMP](INFO): Match for : timedate
2023-05-12T20:03:00.670Z,1683921780.670 [DATMMP](INFO): ACK
2023-05-12T20:03:00.919Z,1683921780.919 [DATMMP](INFO): Starting
2023-05-12T20:03:00.920Z,1683921780.920 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T20:03:00.921Z,1683921780.921 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-12T20:03:00.921Z,1683921780.921 [DATMMP](INFO): Received message type: sys_verb
2023-05-12T20:03:00.921Z,1683921780.921 [DATMMP](INFO): Handled
2023-05-12T20:03:01.171Z,1683921781.171 [DATMMP](INFO): Starting
2023-05-12T20:03:01.172Z,1683921781.172 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-12T20:03:01.172Z,1683921781.172 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-12T20:03:01.173Z,1683921781.173 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-12T20:03:01.173Z,1683921781.173 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-12T20:03:01.173Z,1683921781.173 [DATMMP](INFO): ACK
2023-05-12T20:03:01.424Z,1683921781.424 [DATMMP](INFO): Starting
2023-05-12T20:03:01.424Z,1683921781.424 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-12T20:03:01.425Z,1683921781.425 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-12T20:03:01.425Z,1683921781.425 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-12T20:03:01.425Z,1683921781.425 [DATMMP](INFO): Received message type: version
2023-05-12T20:03:01.426Z,1683921781.426 [DATMMP](INFO): Match for : version
2023-05-12T20:03:01.426Z,1683921781.426 [DATMMP](INFO): ACK
2023-05-12T20:03:01.426Z,1683921781.426 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-12T20:03:10.941Z,1683921790.941 [NAL9602](INFO): Powering up NAL9602
2023-05-12T20:03:15.537Z,1683921795.537 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T20:03:15.538Z,1683921795.538 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-12T20:03:15.539Z,1683921795.539 [DATMMP](INFO): Received message type: timesync
2023-05-12T20:03:15.539Z,1683921795.539 [DATMMP](INFO): Handled
2023-05-12T20:03:21.861Z,1683921801.861 [NAL9602](INFO): NAL9602 initialized
2023-05-12T20:03:25.616Z,1683921805.616 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T20:03:25.617Z,1683921805.617 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-12T20:03:25.617Z,1683921805.617 [DATMMP](INFO): Received message type: timesync
2023-05-12T20:03:25.617Z,1683921805.617 [DATMMP](INFO): Handled
2023-05-12T20:03:33.200Z,1683921813.200 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-12T20:03:33.208Z,1683921813.208 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-12T20:03:44.143Z,1683921824.143 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000707
CHAN A1 (24V): -0.000114
CHAN A2 (12V): -0.007039
CHAN A3 (5V): -0.002178
CHAN B0 (3.3V): 0.000263
CHAN B1 (3.15aV): 0.000072
CHAN B2 (3.15bV): -0.000637
CHAN B3 (GND): 0.002280
OPEN: 0.005146
Full Scale: +/- 1 mA
2023-05-12T20:03:45.536Z,1683921825.536 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T20:03:45.537Z,1683921825.537 [DATMMP](INFO): timesync rx: 	 x0	 x0	 x0	 x0
2023-05-12T20:03:45.537Z,1683921825.537 [DATMMP](INFO): Received message type: timesync
2023-05-12T20:03:45.537Z,1683921825.537 [DATMMP](INFO): Handled
2023-05-12T20:03:56.633Z,1683921836.633 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T20:03:56.634Z,1683921836.634 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-12T20:03:56.634Z,1683921836.634 [DATMMP](INFO): Received message type: timesync
2023-05-12T20:03:56.634Z,1683921836.634 [DATMMP](INFO): Handled
2023-05-12T20:04:06.720Z,1683921846.720 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T20:04:06.721Z,1683921846.721 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-12T20:04:06.721Z,1683921846.721 [DATMMP](INFO): Received message type: timesync
2023-05-12T20:04:06.721Z,1683921846.721 [DATMMP](INFO): Handled
2023-05-12T20:04:27.302Z,1683921867.302 [SBIT](IMPORTANT): SBIT PASSED
2023-05-12T20:04:27.302Z,1683921867.302 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-12T20:04:27.303Z,1683921867.303 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-05-12T20:04:27.303Z,1683921867.303 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-05-12T20:04:27.303Z,1683921867.303 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-12T20:04:27.304Z,1683921867.304 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-05-12T20:04:27.304Z,1683921867.304 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.582187 cubic_centimeter;

2023-05-12T20:04:27.304Z,1683921867.304 [SBIT](IMPORTANT): VerticalControl.massDefault=7.892016 millimeter;

2023-05-12T20:04:27.304Z,1683921867.304 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-05-12T20:04:27.716Z,1683921867.716 [MissionManager](IMPORTANT): Started mission Startup
2023-05-12T20:04:27.717Z,1683921867.717 [Startup] Running Loop=1
2023-05-12T20:04:27.717Z,1683921867.717 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-12T20:04:27.717Z,1683921867.717 [Startup:A.GoToSurface] Running Loop=1
2023-05-12T20:04:27.717Z,1683921867.717 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T20:04:27.718Z,1683921867.718 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T20:04:27.718Z,1683921867.718 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T20:04:27.718Z,1683921867.718 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T20:04:27.719Z,1683921867.719 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T20:04:27.719Z,1683921867.719 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T20:04:27.721Z,1683921867.721 [Startup:StartupSatComms] Running Loop=1
2023-05-12T20:04:27.721Z,1683921867.721 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-12T20:04:27.721Z,1683921867.721 [Startup:StartupSatComms:A] Running Loop=1
2023-05-12T20:04:28.141Z,1683921868.141 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-12T20:05:27.947Z,1683921927.947 [Startup:StartupSatComms:A](INFO): Timed out from 2023-05-12T20:04:27.7Z
2023-05-12T20:05:27.947Z,1683921927.947 [Startup:StartupSatComms:A] Stopped
2023-05-12T20:05:27.947Z,1683921927.947 [Startup:StartupSatComms:B] Running Loop=1
2023-05-12T20:05:28.326Z,1683921928.326 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T20:05:35.075Z,1683921935.075 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T194314/Courier0003.lzma
2023-05-12T20:05:36.077Z,1683921936.077 [DataOverHttps](INFO): Moved sent file to Logs/20230512T194314/Courier0003.lzma.bak
2023-05-12T20:05:36.077Z,1683921936.077 [DataOverHttps](INFO): SBD MOMSN=18300850
2023-05-12T20:05:42.108Z,1683921942.108 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-05-12T20:05:42.108Z,1683921942.108 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:05:42.136Z,1683921942.136 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:05:42.580Z,1683921942.580 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:05:42.580Z,1683921942.580 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-05-12T20:05:52.103Z,1683921952.103 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T195842/Courier0000.lzma
2023-05-12T20:05:53.105Z,1683921953.105 [DataOverHttps](INFO): Moved sent file to Logs/20230512T195842/Courier0000.lzma.bak
2023-05-12T20:05:53.105Z,1683921953.105 [DataOverHttps](INFO): SBD MOMSN=18300852
2023-05-12T20:06:09.103Z,1683921969.103 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T200232/Courier0000.lzma
2023-05-12T20:06:09.976Z,1683921969.976 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-12T20:06:10.105Z,1683921970.105 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0000.lzma.bak
2023-05-12T20:06:10.105Z,1683921970.105 [DataOverHttps](INFO): SBD MOMSN=18300855
2023-05-12T20:06:26.035Z,1683921986.035 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20230512T194314/Express0004.lzma
2023-05-12T20:06:27.047Z,1683921987.047 [DataOverHttps](INFO): Moved sent file to Logs/20230512T194314/Express0004.lzma.bak
2023-05-12T20:06:27.048Z,1683921987.048 [DataOverHttps](INFO): SBD MOMSN=18300858
2023-05-12T20:06:28.163Z,1683921988.163 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-12T20:05:27.9Z
2023-05-12T20:06:28.164Z,1683921988.164 [Startup:StartupSatComms:B] Stopped
2023-05-12T20:06:28.164Z,1683921988.164 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-12T20:06:28.164Z,1683921988.164 [Startup:StartupSatComms] Stopped
2023-05-12T20:06:28.164Z,1683921988.164 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-12T20:06:28.165Z,1683921988.165 [Startup](INFO): Completed Startup
2023-05-12T20:06:28.165Z,1683921988.165 [MissionManager](INFO): Startup is completed.
2023-05-12T20:06:28.165Z,1683921988.165 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-12T20:06:28.165Z,1683921988.165 [Startup] Stopped
2023-05-12T20:06:28.165Z,1683921988.165 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-12T20:06:28.165Z,1683921988.165 [Startup:A.GoToSurface] Stopped
2023-05-12T20:06:28.165Z,1683921988.165 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T20:06:28.547Z,1683921988.547 [MissionManager](IMPORTANT): Started mission Default
2023-05-12T20:06:28.547Z,1683921988.547 [Default] Running Loop=1
2023-05-12T20:06:28.547Z,1683921988.547 [Default](DEBUG): Aggregate::initialize Default
2023-05-12T20:06:28.547Z,1683921988.547 [Default:B.GoToSurface] Running Loop=1
2023-05-12T20:06:28.547Z,1683921988.547 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T20:06:28.548Z,1683921988.548 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T20:06:28.548Z,1683921988.548 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T20:06:28.548Z,1683921988.548 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T20:06:28.548Z,1683921988.548 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T20:06:28.549Z,1683921988.549 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T20:06:28.549Z,1683921988.549 [Default:A.Wait] Running Loop=1
2023-05-12T20:06:28.549Z,1683921988.549 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-12T20:06:41.881Z,1683922001.881 [Default:A.Wait](INFO): Done Waiting.
2023-05-12T20:06:41.881Z,1683922001.881 [Default:A.Wait] Stopped
2023-05-12T20:06:41.882Z,1683922001.882 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T20:06:42.249Z,1683922002.249 [Default:CheckIn] Running Loop=1
2023-05-12T20:06:42.249Z,1683922002.249 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T20:06:42.249Z,1683922002.249 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T20:06:42.638Z,1683922002.638 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-12T20:07:10.179Z,1683922030.179 [DataOverHttps](INFO): Sending 554 bytes from file Logs/20230512T195842/Express0001.lzma
2023-05-12T20:07:11.181Z,1683922031.181 [DataOverHttps](INFO): Moved sent file to Logs/20230512T195842/Express0001.lzma.bak
2023-05-12T20:07:11.181Z,1683922031.181 [DataOverHttps](INFO): SBD MOMSN=18300862
2023-05-12T20:08:25.258Z,1683922105.258 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-12T20:08:43.067Z,1683922123.067 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-05-12T20:08:43.067Z,1683922123.067 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:08:43.078Z,1683922123.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:08:43.452Z,1683922123.452 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:08:43.453Z,1683922123.453 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-05-12T20:11:17.771Z,1683922277.771 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-12T20:11:17.774Z,1683922277.774 [BPC1](INFO): Received data from all battery sticks.
2023-05-12T20:11:42.448Z,1683922302.448 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T20:06:42.2Z
2023-05-12T20:11:42.448Z,1683922302.448 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T20:11:42.449Z,1683922302.449 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T20:11:42.846Z,1683922302.846 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T20:11:44.079Z,1683922304.079 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-05-12T20:11:44.079Z,1683922304.079 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:11:44.090Z,1683922304.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:11:44.455Z,1683922304.455 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:11:44.456Z,1683922304.456 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-05-12T20:11:49.763Z,1683922309.763 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20230512T200232/Courier0004.lzma
2023-05-12T20:11:50.765Z,1683922310.765 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0004.lzma.bak
2023-05-12T20:11:50.765Z,1683922310.765 [DataOverHttps](INFO): SBD MOMSN=18301122
2023-05-12T20:12:06.569Z,1683922326.569 [DataOverHttps](INFO): Sending 852 bytes from file Logs/20230512T200232/Express0001.lzma
2023-05-12T20:12:07.569Z,1683922327.569 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0001.lzma.bak
2023-05-12T20:12:07.569Z,1683922327.569 [DataOverHttps](INFO): SBD MOMSN=18301124
2023-05-12T20:12:23.544Z,1683922343.544 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230512T200232/Express0005.lzma
2023-05-12T20:12:24.546Z,1683922344.546 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0005.lzma.bak
2023-05-12T20:12:24.546Z,1683922344.546 [DataOverHttps](INFO): SBD MOMSN=18301143
2023-05-12T20:12:26.090Z,1683922346.090 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T20:12:26.090Z,1683922346.090 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T20:12:26.090Z,1683922346.090 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T20:13:24.227Z,1683922404.227 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T20:13:24.227Z,1683922404.227 [NAL9602] Data Fault, FailCount= 1
2023-05-12T20:13:24.227Z,1683922404.227 [NAL9602](ERROR): Data Fault
2023-05-12T20:13:24.264Z,1683922404.264 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T20:13:24.628Z,1683922404.628 [NAL9602](INFO): Powering down
2023-05-12T20:13:25.453Z,1683922405.453 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T20:13:25.453Z,1683922405.453 [NAL9602] No Fault, FailCount= 1
2023-05-12T20:13:54.925Z,1683922434.925 [NAL9602](INFO): Powering up NAL9602
2023-05-12T20:14:05.840Z,1683922445.840 [NAL9602](INFO): NAL9602 initialized
2023-05-12T20:14:45.035Z,1683922485.035 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-05-12T20:14:45.035Z,1683922485.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:14:45.046Z,1683922485.046 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:14:45.458Z,1683922485.458 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:14:45.458Z,1683922485.458 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-05-12T20:17:26.653Z,1683922646.653 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T20:17:26.653Z,1683922646.653 [Default:CheckIn:C.Wait] Stopped
2023-05-12T20:17:26.653Z,1683922646.653 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T20:17:26.654Z,1683922646.654 [Default:CheckIn:D] Running Loop=1
2023-05-12T20:17:27.063Z,1683922647.063 [Default:CheckIn:D] Stopped
2023-05-12T20:17:27.063Z,1683922647.063 [Default:CheckIn:E] Running Loop=1
2023-05-12T20:17:27.441Z,1683922647.441 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.975274 min 
2023-05-12T20:17:27.441Z,1683922647.441 [Default:CheckIn:E] Stopped
2023-05-12T20:17:27.441Z,1683922647.441 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T20:17:27.441Z,1683922647.441 [Default:CheckIn] Stopped
2023-05-12T20:17:27.441Z,1683922647.441 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T20:17:27.441Z,1683922647.441 [Default:CheckIn](INFO): Running loop #2
2023-05-12T20:17:27.441Z,1683922647.441 [Default:CheckIn] Running Loop=2
2023-05-12T20:17:27.441Z,1683922647.441 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T20:17:27.441Z,1683922647.441 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T20:17:46.049Z,1683922666.049 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-05-12T20:17:46.050Z,1683922666.050 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:17:46.060Z,1683922666.060 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:17:46.462Z,1683922666.462 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:17:46.462Z,1683922666.462 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-05-12T20:20:47.027Z,1683922847.027 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-05-12T20:20:47.027Z,1683922847.027 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:20:47.082Z,1683922847.082 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:20:47.447Z,1683922847.447 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:20:47.447Z,1683922847.447 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-05-12T20:22:27.622Z,1683922947.622 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T20:17:27.4Z
2023-05-12T20:22:27.622Z,1683922947.622 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T20:22:27.622Z,1683922947.622 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T20:22:34.562Z,1683922954.562 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T200232/Courier0007.lzma
2023-05-12T20:22:35.565Z,1683922955.565 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0007.lzma.bak
2023-05-12T20:22:35.565Z,1683922955.565 [DataOverHttps](INFO): SBD MOMSN=18301184
2023-05-12T20:22:51.483Z,1683922971.483 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20230512T200232/Express0008.lzma
2023-05-12T20:22:52.485Z,1683922972.485 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0008.lzma.bak
2023-05-12T20:22:52.485Z,1683922972.485 [DataOverHttps](INFO): SBD MOMSN=18301186
2023-05-12T20:22:53.939Z,1683922973.939 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T20:22:53.939Z,1683922973.939 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T20:22:53.939Z,1683922973.939 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T20:23:48.034Z,1683923028.034 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-05-12T20:23:48.034Z,1683923028.034 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:23:48.044Z,1683923028.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:23:48.441Z,1683923028.441 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:23:48.441Z,1683923028.441 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-05-12T20:24:07.396Z,1683923047.396 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T20:24:07.396Z,1683923047.396 [NAL9602] Data Fault, FailCount= 2
2023-05-12T20:24:07.396Z,1683923047.396 [NAL9602](ERROR): Data Fault
2023-05-12T20:24:07.431Z,1683923047.431 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T20:24:07.792Z,1683923047.792 [NAL9602](INFO): Powering down
2023-05-12T20:24:08.635Z,1683923048.635 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T20:24:08.635Z,1683923048.635 [NAL9602] No Fault, FailCount= 2
2023-05-12T20:24:38.096Z,1683923078.096 [NAL9602](INFO): Powering up NAL9602
2023-05-12T20:24:49.009Z,1683923089.009 [NAL9602](INFO): NAL9602 initialized
2023-05-12T20:26:49.003Z,1683923209.003 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-05-12T20:26:49.003Z,1683923209.003 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:26:49.013Z,1683923209.013 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:26:49.418Z,1683923209.418 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:26:49.418Z,1683923209.418 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-05-12T20:27:54.468Z,1683923274.468 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T20:27:54.469Z,1683923274.469 [Default:CheckIn:C.Wait] Stopped
2023-05-12T20:27:54.469Z,1683923274.469 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T20:27:54.469Z,1683923274.469 [Default:CheckIn:D] Running Loop=1
2023-05-12T20:27:54.877Z,1683923274.877 [Default:CheckIn:D] Stopped
2023-05-12T20:27:54.877Z,1683923274.877 [Default:CheckIn:E] Running Loop=1
2023-05-12T20:27:55.279Z,1683923275.279 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.438841 min 
2023-05-12T20:27:55.279Z,1683923275.279 [Default:CheckIn:E] Stopped
2023-05-12T20:27:55.279Z,1683923275.279 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T20:27:55.279Z,1683923275.279 [Default:CheckIn] Stopped
2023-05-12T20:27:55.280Z,1683923275.280 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T20:27:55.280Z,1683923275.280 [Default:CheckIn](INFO): Running loop #3
2023-05-12T20:27:55.280Z,1683923275.280 [Default:CheckIn] Running Loop=3
2023-05-12T20:27:55.280Z,1683923275.280 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T20:27:55.280Z,1683923275.280 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T20:29:50.054Z,1683923390.054 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-05-12T20:29:50.054Z,1683923390.054 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:29:50.065Z,1683923390.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:29:50.457Z,1683923390.457 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:29:50.457Z,1683923390.457 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-05-12T20:32:51.011Z,1683923571.011 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-05-12T20:32:51.011Z,1683923571.011 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:32:51.022Z,1683923571.022 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:32:51.437Z,1683923571.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:32:51.438Z,1683923571.438 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-05-12T20:32:55.485Z,1683923575.485 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T20:27:55.3Z
2023-05-12T20:32:55.485Z,1683923575.485 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T20:32:55.485Z,1683923575.485 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T20:33:03.186Z,1683923583.186 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T200232/Courier0010.lzma
2023-05-12T20:33:04.189Z,1683923584.189 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0010.lzma.bak
2023-05-12T20:33:04.189Z,1683923584.189 [DataOverHttps](INFO): SBD MOMSN=18301211
2023-05-12T20:33:20.063Z,1683923600.063 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230512T200232/Express0011.lzma
2023-05-12T20:33:21.065Z,1683923601.065 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0011.lzma.bak
2023-05-12T20:33:21.065Z,1683923601.065 [DataOverHttps](INFO): SBD MOMSN=18301213
2023-05-12T20:33:22.548Z,1683923602.548 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T20:33:22.549Z,1683923602.549 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T20:33:22.549Z,1683923602.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T20:34:51.397Z,1683923691.397 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T20:34:51.397Z,1683923691.397 [NAL9602] Data Fault, FailCount= 3
2023-05-12T20:34:51.397Z,1683923691.397 [NAL9602](ERROR): Data Fault
2023-05-12T20:34:51.441Z,1683923691.441 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T20:34:51.800Z,1683923691.800 [NAL9602](INFO): Powering down
2023-05-12T20:34:52.627Z,1683923692.627 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T20:34:52.627Z,1683923692.627 [NAL9602] No Fault, FailCount= 3
2023-05-12T20:35:22.102Z,1683923722.102 [NAL9602](INFO): Powering up NAL9602
2023-05-12T20:35:33.008Z,1683923733.008 [NAL9602](INFO): NAL9602 initialized
2023-05-12T20:35:52.032Z,1683923752.032 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-05-12T20:35:52.032Z,1683923752.032 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:35:52.058Z,1683923752.058 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:35:52.427Z,1683923752.427 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:35:52.427Z,1683923752.427 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-05-12T20:38:23.111Z,1683923903.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T20:38:23.111Z,1683923903.111 [Default:CheckIn:C.Wait] Stopped
2023-05-12T20:38:23.111Z,1683923903.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T20:38:23.111Z,1683923903.111 [Default:CheckIn:D] Running Loop=1
2023-05-12T20:38:23.507Z,1683923903.507 [Default:CheckIn:D] Stopped
2023-05-12T20:38:23.507Z,1683923903.507 [Default:CheckIn:E] Running Loop=1
2023-05-12T20:38:23.942Z,1683923903.942 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.916007 min 
2023-05-12T20:38:23.943Z,1683923903.943 [Default:CheckIn:E] Stopped
2023-05-12T20:38:23.943Z,1683923903.943 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T20:38:23.943Z,1683923903.943 [Default:CheckIn] Stopped
2023-05-12T20:38:23.943Z,1683923903.943 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T20:38:23.943Z,1683923903.943 [Default:CheckIn](INFO): Running loop #4
2023-05-12T20:38:23.943Z,1683923903.943 [Default:CheckIn] Running Loop=4
2023-05-12T20:38:23.943Z,1683923903.943 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T20:38:23.944Z,1683923903.944 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T20:38:52.996Z,1683923932.996 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-05-12T20:38:52.996Z,1683923932.996 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:38:53.006Z,1683923933.006 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:38:53.419Z,1683923933.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:38:53.419Z,1683923933.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-05-12T20:41:54.040Z,1683924114.040 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-05-12T20:41:54.040Z,1683924114.040 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:41:54.066Z,1683924114.066 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:41:54.454Z,1683924114.454 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:41:54.454Z,1683924114.454 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-05-12T20:43:24.131Z,1683924204.131 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T20:38:23.9Z
2023-05-12T20:43:24.131Z,1683924204.131 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T20:43:24.131Z,1683924204.131 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T20:43:31.066Z,1683924211.066 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T200232/Courier0013.lzma
2023-05-12T20:43:32.069Z,1683924212.069 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0013.lzma.bak
2023-05-12T20:43:32.069Z,1683924212.069 [DataOverHttps](INFO): SBD MOMSN=18301233
2023-05-12T20:43:47.912Z,1683924227.912 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230512T200232/Express0014.lzma
2023-05-12T20:43:48.905Z,1683924228.905 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0014.lzma.bak
2023-05-12T20:43:48.905Z,1683924228.905 [DataOverHttps](INFO): SBD MOMSN=18301235
2023-05-12T20:43:50.381Z,1683924230.381 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T20:43:50.381Z,1683924230.381 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T20:43:50.381Z,1683924230.381 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T20:44:55.016Z,1683924295.016 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2023-05-12T20:44:55.016Z,1683924295.016 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:44:55.027Z,1683924295.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:44:55.424Z,1683924295.424 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:44:55.424Z,1683924295.424 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2023-05-12T20:45:36.199Z,1683924336.199 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T20:45:36.199Z,1683924336.199 [NAL9602] Data Fault, FailCount= 4
2023-05-12T20:45:36.199Z,1683924336.199 [NAL9602](ERROR): Data Fault
2023-05-12T20:45:36.236Z,1683924336.236 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T20:45:36.596Z,1683924336.596 [NAL9602](INFO): Powering down
2023-05-12T20:45:37.425Z,1683924337.425 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T20:45:37.425Z,1683924337.425 [NAL9602] No Fault, FailCount= 4
2023-05-12T20:46:06.902Z,1683924366.902 [NAL9602](INFO): Powering up NAL9602
2023-05-12T20:46:17.809Z,1683924377.809 [NAL9602](INFO): NAL9602 initialized
2023-05-12T20:47:56.024Z,1683924476.024 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2023-05-12T20:47:56.024Z,1683924476.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:47:56.047Z,1683924476.047 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:47:56.410Z,1683924476.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:47:56.410Z,1683924476.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2023-05-12T20:48:50.932Z,1683924530.932 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T20:48:50.933Z,1683924530.933 [Default:CheckIn:C.Wait] Stopped
2023-05-12T20:48:50.933Z,1683924530.933 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T20:48:50.933Z,1683924530.933 [Default:CheckIn:D] Running Loop=1
2023-05-12T20:48:51.337Z,1683924531.337 [Default:CheckIn:D] Stopped
2023-05-12T20:48:51.337Z,1683924531.337 [Default:CheckIn:E] Running Loop=1
2023-05-12T20:48:51.751Z,1683924531.751 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.379834 min 
2023-05-12T20:48:51.751Z,1683924531.751 [Default:CheckIn:E] Stopped
2023-05-12T20:48:51.751Z,1683924531.751 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T20:48:51.751Z,1683924531.751 [Default:CheckIn] Stopped
2023-05-12T20:48:51.751Z,1683924531.751 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T20:48:51.751Z,1683924531.751 [Default:CheckIn](INFO): Running loop #5
2023-05-12T20:48:51.751Z,1683924531.751 [Default:CheckIn] Running Loop=5
2023-05-12T20:48:51.752Z,1683924531.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T20:48:51.752Z,1683924531.752 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T20:50:56.975Z,1683924656.975 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2023-05-12T20:50:56.975Z,1683924656.975 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:50:56.986Z,1683924656.986 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:50:57.404Z,1683924657.404 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:50:57.404Z,1683924657.404 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2023-05-12T20:53:51.968Z,1683924831.968 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T20:48:51.8Z
2023-05-12T20:53:51.968Z,1683924831.968 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T20:53:51.968Z,1683924831.968 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T20:53:58.035Z,1683924838.035 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2023-05-12T20:53:58.035Z,1683924838.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:53:58.082Z,1683924838.082 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:53:58.454Z,1683924838.454 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:53:58.454Z,1683924838.454 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2023-05-12T20:53:59.766Z,1683924839.766 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T200232/Courier0016.lzma
2023-05-12T20:54:00.885Z,1683924840.885 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0016.lzma.bak
2023-05-12T20:54:00.885Z,1683924840.885 [DataOverHttps](INFO): SBD MOMSN=18301256
2023-05-12T20:54:16.586Z,1683924856.586 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T200232/Express0017.lzma
2023-05-12T20:54:17.589Z,1683924857.589 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0017.lzma.bak
2023-05-12T20:54:17.589Z,1683924857.589 [DataOverHttps](INFO): SBD MOMSN=18301258
2023-05-12T20:54:19.021Z,1683924859.021 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T20:54:19.021Z,1683924859.021 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T20:54:19.021Z,1683924859.021 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T20:56:21.438Z,1683924981.438 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T20:56:21.438Z,1683924981.438 [NAL9602] Data Fault, FailCount= 5
2023-05-12T20:56:21.438Z,1683924981.438 [NAL9602](ERROR): Data Fault
2023-05-12T20:56:21.462Z,1683924981.462 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T20:56:21.463Z,1683924981.463 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-05-12T20:56:21.836Z,1683924981.836 [NAL9602](INFO): Powering down
2023-05-12T20:56:21.929Z,1683924981.929 [CommandExec](FAULT): Scheduling is paused
2023-05-12T20:56:21.929Z,1683924981.929 [CBIT](INFO): Critical error at 20230512T205621
2023-05-12T20:56:21.930Z,1683924981.930 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-05-12T20:56:59.015Z,1683925019.015 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2023-05-12T20:56:59.015Z,1683925019.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T20:56:59.025Z,1683925019.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T20:56:59.437Z,1683925019.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T20:56:59.437Z,1683925019.437 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2023-05-12T20:58:21.892Z,1683925101.892 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T20:58:21.893Z,1683925101.893 [NAL9602] No Fault, FailCount= 5
2023-05-12T20:58:22.229Z,1683925102.229 [NAL9602](INFO): Powering up NAL9602
2023-05-12T20:58:33.143Z,1683925113.143 [NAL9602](INFO): NAL9602 initialized
2023-05-12T20:59:19.609Z,1683925159.609 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T20:59:19.609Z,1683925159.609 [Default:CheckIn:C.Wait] Stopped
2023-05-12T20:59:19.609Z,1683925159.609 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T20:59:19.609Z,1683925159.609 [Default:CheckIn:D] Running Loop=1
2023-05-12T20:59:20.049Z,1683925160.049 [Default:CheckIn:D] Stopped
2023-05-12T20:59:20.049Z,1683925160.049 [Default:CheckIn:E] Running Loop=1
2023-05-12T20:59:20.437Z,1683925160.437 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.858362 min 
2023-05-12T20:59:20.437Z,1683925160.437 [Default:CheckIn:E] Stopped
2023-05-12T20:59:20.437Z,1683925160.437 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T20:59:20.437Z,1683925160.437 [Default:CheckIn] Stopped
2023-05-12T20:59:20.437Z,1683925160.437 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T20:59:20.437Z,1683925160.437 [Default:CheckIn](INFO): Running loop #6
2023-05-12T20:59:20.438Z,1683925160.438 [Default:CheckIn] Running Loop=6
2023-05-12T20:59:20.438Z,1683925160.438 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T20:59:20.438Z,1683925160.438 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T21:00:00.028Z,1683925200.028 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2023-05-12T21:00:00.028Z,1683925200.028 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:00:00.039Z,1683925200.039 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:00:00.431Z,1683925200.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:00:00.431Z,1683925200.431 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2023-05-12T21:03:01.004Z,1683925381.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2023-05-12T21:03:01.004Z,1683925381.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:03:01.015Z,1683925381.015 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:03:01.418Z,1683925381.418 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:03:01.418Z,1683925381.418 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2023-05-12T21:04:20.614Z,1683925460.614 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T20:59:20.4Z
2023-05-12T21:04:20.614Z,1683925460.614 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T21:04:20.614Z,1683925460.614 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T21:04:27.923Z,1683925467.923 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20230512T200232/Courier0019.lzma
2023-05-12T21:04:28.925Z,1683925468.925 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0019.lzma.bak
2023-05-12T21:04:28.925Z,1683925468.925 [DataOverHttps](INFO): SBD MOMSN=18301281
2023-05-12T21:04:29.977Z,1683925469.977 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2023-05-12T21:04:45.043Z,1683925485.043 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20230512T200232/Express0020.lzma
2023-05-12T21:04:46.045Z,1683925486.045 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0020.lzma.bak
2023-05-12T21:04:46.045Z,1683925486.045 [DataOverHttps](INFO): SBD MOMSN=18301287
2023-05-12T21:04:47.253Z,1683925487.253 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T21:04:47.253Z,1683925487.253 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T21:04:47.253Z,1683925487.253 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T21:06:02.057Z,1683925562.057 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2023-05-12T21:06:02.058Z,1683925562.058 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:06:02.080Z,1683925562.080 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:06:02.437Z,1683925562.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:06:02.437Z,1683925562.437 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2023-05-12T21:08:34.293Z,1683925714.293 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T21:08:34.293Z,1683925714.293 [NAL9602] Data Fault, FailCount= 1
2023-05-12T21:08:34.293Z,1683925714.293 [NAL9602](ERROR): Data Fault
2023-05-12T21:08:34.348Z,1683925714.348 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T21:08:34.696Z,1683925714.696 [NAL9602](INFO): Powering down
2023-05-12T21:08:35.529Z,1683925715.529 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T21:08:35.529Z,1683925715.529 [NAL9602] No Fault, FailCount= 1
2023-05-12T21:09:02.987Z,1683925742.987 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2023-05-12T21:09:02.987Z,1683925742.987 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:09:02.997Z,1683925742.997 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:09:03.396Z,1683925743.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:09:03.396Z,1683925743.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2023-05-12T21:09:05.000Z,1683925745.000 [NAL9602](INFO): Powering up NAL9602
2023-05-12T21:09:15.915Z,1683925755.915 [NAL9602](INFO): NAL9602 initialized
2023-05-12T21:09:47.856Z,1683925787.856 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T21:09:47.856Z,1683925787.856 [Default:CheckIn:C.Wait] Stopped
2023-05-12T21:09:47.856Z,1683925787.856 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T21:09:47.856Z,1683925787.856 [Default:CheckIn:D] Running Loop=1
2023-05-12T21:09:48.274Z,1683925788.274 [Default:CheckIn:D] Stopped
2023-05-12T21:09:48.274Z,1683925788.274 [Default:CheckIn:E] Running Loop=1
2023-05-12T21:09:48.669Z,1683925788.669 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.328792 min 
2023-05-12T21:09:48.669Z,1683925788.669 [Default:CheckIn:E] Stopped
2023-05-12T21:09:48.669Z,1683925788.669 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T21:09:48.669Z,1683925788.669 [Default:CheckIn] Stopped
2023-05-12T21:09:48.669Z,1683925788.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T21:09:48.669Z,1683925788.669 [Default:CheckIn](INFO): Running loop #7
2023-05-12T21:09:48.669Z,1683925788.669 [Default:CheckIn] Running Loop=7
2023-05-12T21:09:48.669Z,1683925788.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T21:09:48.670Z,1683925788.670 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T21:12:04.025Z,1683925924.025 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2023-05-12T21:12:04.025Z,1683925924.025 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:12:04.060Z,1683925924.060 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:12:04.418Z,1683925924.418 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:12:04.419Z,1683925924.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2023-05-12T21:12:52.491Z,1683925972.491 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-05-12T21:14:48.849Z,1683926088.849 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T21:09:48.7Z
2023-05-12T21:14:48.849Z,1683926088.849 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T21:14:48.849Z,1683926088.849 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T21:14:56.486Z,1683926096.486 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T200232/Courier0022.lzma
2023-05-12T21:14:57.489Z,1683926097.489 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0022.lzma.bak
2023-05-12T21:14:57.489Z,1683926097.489 [DataOverHttps](INFO): SBD MOMSN=18301387
2023-05-12T21:15:05.011Z,1683926105.011 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2023-05-12T21:15:05.011Z,1683926105.011 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:15:05.022Z,1683926105.022 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:15:05.456Z,1683926105.456 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:15:05.456Z,1683926105.456 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2023-05-12T21:15:13.291Z,1683926113.291 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T200232/Express0023.lzma
2023-05-12T21:15:14.293Z,1683926114.293 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0023.lzma.bak
2023-05-12T21:15:14.293Z,1683926114.293 [DataOverHttps](INFO): SBD MOMSN=18301389
2023-05-12T21:15:15.953Z,1683926115.953 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T21:15:15.953Z,1683926115.953 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T21:15:15.953Z,1683926115.953 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T21:18:06.045Z,1683926286.045 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2023-05-12T21:18:06.045Z,1683926286.045 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:18:06.055Z,1683926286.055 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:18:06.431Z,1683926286.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:18:06.431Z,1683926286.431 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2023-05-12T21:18:43.588Z,1683926323.588 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-12T21:18:43.590Z,1683926323.590 [BPC1](INFO): Received data from all battery sticks.
2023-05-12T21:19:19.521Z,1683926359.521 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T21:19:19.522Z,1683926359.522 [NAL9602] Data Fault, FailCount= 2
2023-05-12T21:19:19.522Z,1683926359.522 [NAL9602](ERROR): Data Fault
2023-05-12T21:19:19.546Z,1683926359.546 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T21:19:19.930Z,1683926359.930 [NAL9602](INFO): Powering down
2023-05-12T21:19:20.773Z,1683926360.773 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T21:19:20.774Z,1683926360.774 [NAL9602] No Fault, FailCount= 2
2023-05-12T21:19:50.232Z,1683926390.232 [NAL9602](INFO): Powering up NAL9602
2023-05-12T21:20:01.136Z,1683926401.136 [NAL9602](INFO): NAL9602 initialized
2023-05-12T21:20:16.497Z,1683926416.497 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T21:20:16.497Z,1683926416.497 [Default:CheckIn:C.Wait] Stopped
2023-05-12T21:20:16.497Z,1683926416.497 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T21:20:16.497Z,1683926416.497 [Default:CheckIn:D] Running Loop=1
2023-05-12T21:20:16.894Z,1683926416.894 [Default:CheckIn:D] Stopped
2023-05-12T21:20:16.894Z,1683926416.894 [Default:CheckIn:E] Running Loop=1
2023-05-12T21:20:17.308Z,1683926417.308 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.805778 min 
2023-05-12T21:20:17.308Z,1683926417.308 [Default:CheckIn:E] Stopped
2023-05-12T21:20:17.308Z,1683926417.308 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T21:20:17.308Z,1683926417.308 [Default:CheckIn] Stopped
2023-05-12T21:20:17.308Z,1683926417.308 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T21:20:17.308Z,1683926417.308 [Default:CheckIn](INFO): Running loop #8
2023-05-12T21:20:17.308Z,1683926417.308 [Default:CheckIn] Running Loop=8
2023-05-12T21:20:17.308Z,1683926417.308 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T21:20:17.308Z,1683926417.308 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T21:21:07.036Z,1683926467.036 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2023-05-12T21:21:07.036Z,1683926467.036 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:21:07.047Z,1683926467.047 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:21:07.438Z,1683926467.438 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:21:07.438Z,1683926467.438 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2023-05-12T21:24:08.029Z,1683926648.029 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2023-05-12T21:24:08.029Z,1683926648.029 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:24:08.039Z,1683926648.039 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:24:08.417Z,1683926648.417 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:24:08.417Z,1683926648.417 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2023-05-12T21:24:16.096Z,1683926656.096 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2023-05-12T21:25:17.506Z,1683926717.506 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T21:20:17.3Z
2023-05-12T21:25:17.506Z,1683926717.506 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T21:25:17.506Z,1683926717.506 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T21:25:24.778Z,1683926724.778 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20230512T200232/Courier0025.lzma
2023-05-12T21:25:25.781Z,1683926725.781 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0025.lzma.bak
2023-05-12T21:25:25.781Z,1683926725.781 [DataOverHttps](INFO): SBD MOMSN=18301418
2023-05-12T21:25:41.676Z,1683926741.676 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T200232/Express0026.lzma
2023-05-12T21:25:42.677Z,1683926742.677 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0026.lzma.bak
2023-05-12T21:25:42.677Z,1683926742.677 [DataOverHttps](INFO): SBD MOMSN=18301420
2023-05-12T21:25:44.197Z,1683926744.197 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T21:25:44.197Z,1683926744.197 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T21:25:44.197Z,1683926744.197 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T21:27:09.027Z,1683926829.027 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2023-05-12T21:27:09.027Z,1683926829.027 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:27:09.038Z,1683926829.038 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:27:09.443Z,1683926829.443 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:27:09.443Z,1683926829.443 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2023-05-12T21:30:03.513Z,1683927003.513 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T21:30:03.513Z,1683927003.513 [NAL9602] Data Fault, FailCount= 3
2023-05-12T21:30:03.513Z,1683927003.513 [NAL9602](ERROR): Data Fault
2023-05-12T21:30:03.561Z,1683927003.561 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T21:30:03.922Z,1683927003.922 [NAL9602](INFO): Powering down
2023-05-12T21:30:04.757Z,1683927004.757 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T21:30:04.757Z,1683927004.757 [NAL9602] No Fault, FailCount= 3
2023-05-12T21:30:10.011Z,1683927010.011 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2023-05-12T21:30:10.011Z,1683927010.011 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:30:10.021Z,1683927010.021 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:30:10.396Z,1683927010.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:30:10.396Z,1683927010.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29
2023-05-12T21:30:34.216Z,1683927034.216 [NAL9602](INFO): Powering up NAL9602
2023-05-12T21:30:44.781Z,1683927044.781 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T21:30:44.781Z,1683927044.781 [Default:CheckIn:C.Wait] Stopped
2023-05-12T21:30:44.781Z,1683927044.781 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T21:30:44.781Z,1683927044.781 [Default:CheckIn:D] Running Loop=1
2023-05-12T21:30:45.140Z,1683927045.140 [NAL9602](INFO): NAL9602 initialized
2023-05-12T21:30:45.149Z,1683927045.149 [Default:CheckIn:D] Stopped
2023-05-12T21:30:45.149Z,1683927045.149 [Default:CheckIn:E] Running Loop=1
2023-05-12T21:30:45.554Z,1683927045.554 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.276693 min 
2023-05-12T21:30:45.554Z,1683927045.554 [Default:CheckIn:E] Stopped
2023-05-12T21:30:45.554Z,1683927045.554 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T21:30:45.554Z,1683927045.554 [Default:CheckIn] Stopped
2023-05-12T21:30:45.554Z,1683927045.554 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T21:30:45.555Z,1683927045.555 [Default:CheckIn](INFO): Running loop #9
2023-05-12T21:30:45.555Z,1683927045.555 [Default:CheckIn] Running Loop=9
2023-05-12T21:30:45.555Z,1683927045.555 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T21:30:45.555Z,1683927045.555 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T21:33:11.008Z,1683927191.008 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30
2023-05-12T21:33:11.008Z,1683927191.008 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:33:11.019Z,1683927191.019 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:33:11.434Z,1683927191.434 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:33:11.434Z,1683927191.434 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30
2023-05-12T21:35:45.733Z,1683927345.733 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T21:30:45.6Z
2023-05-12T21:35:45.733Z,1683927345.733 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T21:35:45.734Z,1683927345.734 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T21:35:53.334Z,1683927353.334 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T200232/Courier0028.lzma
2023-05-12T21:35:54.337Z,1683927354.337 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0028.lzma.bak
2023-05-12T21:35:54.337Z,1683927354.337 [DataOverHttps](INFO): SBD MOMSN=18301443
2023-05-12T21:36:10.307Z,1683927370.307 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230512T200232/Express0029.lzma
2023-05-12T21:36:11.309Z,1683927371.309 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0029.lzma.bak
2023-05-12T21:36:11.309Z,1683927371.309 [DataOverHttps](INFO): SBD MOMSN=18301445
2023-05-12T21:36:12.033Z,1683927372.033 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31
2023-05-12T21:36:12.033Z,1683927372.033 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:36:12.043Z,1683927372.043 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:36:12.449Z,1683927372.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:36:12.450Z,1683927372.450 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31
2023-05-12T21:36:12.808Z,1683927372.808 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T21:36:12.808Z,1683927372.808 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T21:36:12.808Z,1683927372.808 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T21:39:13.003Z,1683927553.003 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32
2023-05-12T21:39:13.004Z,1683927553.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:39:13.018Z,1683927553.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:39:13.429Z,1683927553.429 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:39:13.429Z,1683927553.429 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32
2023-05-12T21:40:47.533Z,1683927647.533 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T21:40:47.533Z,1683927647.533 [NAL9602] Data Fault, FailCount= 4
2023-05-12T21:40:47.533Z,1683927647.533 [NAL9602](ERROR): Data Fault
2023-05-12T21:40:47.616Z,1683927647.616 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T21:40:47.938Z,1683927647.938 [NAL9602](INFO): Powering down
2023-05-12T21:40:48.787Z,1683927648.787 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T21:40:48.787Z,1683927648.787 [NAL9602] No Fault, FailCount= 4
2023-05-12T21:41:13.380Z,1683927673.380 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T21:41:13.380Z,1683927673.380 [Default:CheckIn:C.Wait] Stopped
2023-05-12T21:41:13.380Z,1683927673.380 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T21:41:13.380Z,1683927673.380 [Default:CheckIn:D] Running Loop=1
2023-05-12T21:41:13.784Z,1683927673.784 [Default:CheckIn:D] Stopped
2023-05-12T21:41:13.784Z,1683927673.784 [Default:CheckIn:E] Running Loop=1
2023-05-12T21:41:14.207Z,1683927674.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.753947 min 
2023-05-12T21:41:14.207Z,1683927674.207 [Default:CheckIn:E] Stopped
2023-05-12T21:41:14.208Z,1683927674.208 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T21:41:14.208Z,1683927674.208 [Default:CheckIn] Stopped
2023-05-12T21:41:14.208Z,1683927674.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T21:41:14.208Z,1683927674.208 [Default:CheckIn](INFO): Running loop #10
2023-05-12T21:41:14.208Z,1683927674.208 [Default:CheckIn] Running Loop=10
2023-05-12T21:41:14.208Z,1683927674.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T21:41:14.208Z,1683927674.208 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T21:41:18.221Z,1683927678.221 [NAL9602](INFO): Powering up NAL9602
2023-05-12T21:41:29.129Z,1683927689.129 [NAL9602](INFO): NAL9602 initialized
2023-05-12T21:42:13.995Z,1683927733.995 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 33
2023-05-12T21:42:13.995Z,1683927733.995 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:42:14.031Z,1683927734.031 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:42:14.394Z,1683927734.394 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:42:14.394Z,1683927734.394 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 33
2023-05-12T21:45:14.992Z,1683927914.992 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 34
2023-05-12T21:45:14.992Z,1683927914.992 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:45:15.027Z,1683927915.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:45:15.462Z,1683927915.462 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:45:15.462Z,1683927915.462 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 34
2023-05-12T21:46:14.366Z,1683927974.366 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T21:41:14.2Z
2023-05-12T21:46:14.366Z,1683927974.366 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T21:46:14.366Z,1683927974.366 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T21:46:21.527Z,1683927981.527 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230512T200232/Courier0031.lzma
2023-05-12T21:46:22.529Z,1683927982.529 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0031.lzma.bak
2023-05-12T21:46:22.529Z,1683927982.529 [DataOverHttps](INFO): SBD MOMSN=18301471
2023-05-12T21:46:38.467Z,1683927998.467 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230512T200232/Express0032.lzma
2023-05-12T21:46:39.481Z,1683927999.481 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0032.lzma.bak
2023-05-12T21:46:39.481Z,1683927999.481 [DataOverHttps](INFO): SBD MOMSN=18301477
2023-05-12T21:46:41.048Z,1683928001.048 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T21:46:41.048Z,1683928001.048 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T21:46:41.048Z,1683928001.048 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T21:48:16.033Z,1683928096.033 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 35
2023-05-12T21:48:16.033Z,1683928096.033 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:48:16.056Z,1683928096.056 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:48:16.410Z,1683928096.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:48:16.410Z,1683928096.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 35
2023-05-12T21:51:16.975Z,1683928276.975 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 36
2023-05-12T21:51:16.975Z,1683928276.975 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:51:16.986Z,1683928276.986 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:51:17.385Z,1683928277.385 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:51:17.385Z,1683928277.385 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 36
2023-05-12T21:51:30.705Z,1683928290.705 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T21:51:30.705Z,1683928290.705 [NAL9602] Data Fault, FailCount= 5
2023-05-12T21:51:30.705Z,1683928290.705 [NAL9602](ERROR): Data Fault
2023-05-12T21:51:30.722Z,1683928290.722 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T21:51:30.722Z,1683928290.722 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-05-12T21:51:31.104Z,1683928291.104 [NAL9602](INFO): Powering down
2023-05-12T21:51:31.149Z,1683928291.149 [CBIT](INFO): Critical error at 20230512T215130
2023-05-12T21:51:41.620Z,1683928301.620 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T21:51:41.620Z,1683928301.620 [Default:CheckIn:C.Wait] Stopped
2023-05-12T21:51:41.620Z,1683928301.620 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T21:51:41.620Z,1683928301.620 [Default:CheckIn:D] Running Loop=1
2023-05-12T21:51:42.040Z,1683928302.040 [Default:CheckIn:D] Stopped
2023-05-12T21:51:42.040Z,1683928302.040 [Default:CheckIn:E] Running Loop=1
2023-05-12T21:51:42.431Z,1683928302.431 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.224878 min 
2023-05-12T21:51:42.431Z,1683928302.431 [Default:CheckIn:E] Stopped
2023-05-12T21:51:42.431Z,1683928302.431 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T21:51:42.431Z,1683928302.431 [Default:CheckIn] Stopped
2023-05-12T21:51:42.431Z,1683928302.431 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T21:51:42.432Z,1683928302.432 [Default:CheckIn](INFO): Running loop #11
2023-05-12T21:51:42.432Z,1683928302.432 [Default:CheckIn] Running Loop=11
2023-05-12T21:51:42.432Z,1683928302.432 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T21:51:42.432Z,1683928302.432 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T21:53:30.710Z,1683928410.710 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T21:53:30.710Z,1683928410.710 [NAL9602] No Fault, FailCount= 5
2023-05-12T21:53:31.097Z,1683928411.097 [NAL9602](INFO): Powering up NAL9602
2023-05-12T21:53:42.004Z,1683928422.004 [NAL9602](INFO): NAL9602 initialized
2023-05-12T21:54:18.009Z,1683928458.009 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 37
2023-05-12T21:54:18.009Z,1683928458.009 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:54:18.020Z,1683928458.020 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:54:18.417Z,1683928458.417 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:54:18.417Z,1683928458.417 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 37
2023-05-12T21:56:42.618Z,1683928602.618 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T21:51:42.4Z
2023-05-12T21:56:42.618Z,1683928602.618 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T21:56:42.618Z,1683928602.618 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T21:56:49.702Z,1683928609.702 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20230512T200232/Courier0034.lzma
2023-05-12T21:56:50.705Z,1683928610.705 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0034.lzma.bak
2023-05-12T21:56:50.705Z,1683928610.705 [DataOverHttps](INFO): SBD MOMSN=18301562
2023-05-12T21:57:06.399Z,1683928626.399 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230512T200232/Express0035.lzma
2023-05-12T21:57:07.401Z,1683928627.401 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0035.lzma.bak
2023-05-12T21:57:07.401Z,1683928627.401 [DataOverHttps](INFO): SBD MOMSN=18301565
2023-05-12T21:57:08.886Z,1683928628.886 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T21:57:08.886Z,1683928628.886 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T21:57:08.886Z,1683928628.886 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T21:57:18.967Z,1683928638.967 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 38
2023-05-12T21:57:18.967Z,1683928638.967 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T21:57:18.977Z,1683928638.977 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T21:57:19.378Z,1683928639.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T21:57:19.378Z,1683928639.378 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 38
2023-05-12T22:00:19.979Z,1683928819.979 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 39
2023-05-12T22:00:19.979Z,1683928819.979 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:00:19.990Z,1683928819.990 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:00:20.366Z,1683928820.366 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:00:20.366Z,1683928820.366 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 39
2023-05-12T22:02:09.460Z,1683928929.460 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T22:02:09.461Z,1683928929.461 [Default:CheckIn:C.Wait] Stopped
2023-05-12T22:02:09.461Z,1683928929.461 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T22:02:09.461Z,1683928929.461 [Default:CheckIn:D] Running Loop=1
2023-05-12T22:02:09.845Z,1683928929.845 [Default:CheckIn:D] Stopped
2023-05-12T22:02:09.845Z,1683928929.845 [Default:CheckIn:E] Running Loop=1
2023-05-12T22:02:10.272Z,1683928930.272 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.688306 min 
2023-05-12T22:02:10.273Z,1683928930.273 [Default:CheckIn:E] Stopped
2023-05-12T22:02:10.273Z,1683928930.273 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T22:02:10.273Z,1683928930.273 [Default:CheckIn] Stopped
2023-05-12T22:02:10.273Z,1683928930.273 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T22:02:10.273Z,1683928930.273 [Default:CheckIn](INFO): Running loop #12
2023-05-12T22:02:10.273Z,1683928930.273 [Default:CheckIn] Running Loop=12
2023-05-12T22:02:10.273Z,1683928930.273 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T22:02:10.273Z,1683928930.273 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T22:03:20.963Z,1683929000.963 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 40
2023-05-12T22:03:20.963Z,1683929000.963 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:03:20.990Z,1683929000.990 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:03:21.377Z,1683929001.377 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:03:21.377Z,1683929001.377 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 40
2023-05-12T22:03:28.233Z,1683929008.233 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2023-05-12T22:03:44.384Z,1683929024.384 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T22:03:44.385Z,1683929024.385 [NAL9602] Data Fault, FailCount= 1
2023-05-12T22:03:44.385Z,1683929024.385 [NAL9602](ERROR): Data Fault
2023-05-12T22:03:44.466Z,1683929024.466 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T22:03:44.788Z,1683929024.788 [NAL9602](INFO): Powering down
2023-05-12T22:03:45.630Z,1683929025.630 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T22:03:45.630Z,1683929025.630 [NAL9602] No Fault, FailCount= 1
2023-05-12T22:04:15.092Z,1683929055.092 [NAL9602](INFO): Powering up NAL9602
2023-05-12T22:04:26.001Z,1683929066.000 [NAL9602](INFO): NAL9602 initialized
2023-05-12T22:06:22.010Z,1683929182.010 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 41
2023-05-12T22:06:22.010Z,1683929182.010 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:06:22.025Z,1683929182.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:06:22.382Z,1683929182.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:06:22.382Z,1683929182.382 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 41
2023-05-12T22:07:10.457Z,1683929230.457 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T22:02:10.3Z
2023-05-12T22:07:10.457Z,1683929230.457 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T22:07:10.457Z,1683929230.457 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T22:07:17.848Z,1683929237.848 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T200232/Courier0037.lzma
2023-05-12T22:07:18.849Z,1683929238.849 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0037.lzma.bak
2023-05-12T22:07:18.849Z,1683929238.849 [DataOverHttps](INFO): SBD MOMSN=18301593
2023-05-12T22:07:35.139Z,1683929255.139 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T200232/Express0038.lzma
2023-05-12T22:07:36.141Z,1683929256.141 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0038.lzma.bak
2023-05-12T22:07:36.141Z,1683929256.141 [DataOverHttps](INFO): SBD MOMSN=18301609
2023-05-12T22:07:37.510Z,1683929257.510 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T22:07:37.510Z,1683929257.510 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T22:07:37.510Z,1683929257.510 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T22:09:22.993Z,1683929362.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 42
2023-05-12T22:09:22.993Z,1683929362.993 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:09:23.004Z,1683929363.004 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:09:23.404Z,1683929363.404 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:09:23.404Z,1683929363.404 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 42
2023-05-12T22:12:24.063Z,1683929544.063 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 43
2023-05-12T22:12:24.063Z,1683929544.063 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:12:24.094Z,1683929544.094 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:12:24.476Z,1683929544.476 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:12:24.476Z,1683929544.476 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 43
2023-05-12T22:12:38.181Z,1683929558.181 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T22:12:38.181Z,1683929558.181 [Default:CheckIn:C.Wait] Stopped
2023-05-12T22:12:38.181Z,1683929558.181 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T22:12:38.182Z,1683929558.182 [Default:CheckIn:D] Running Loop=1
2023-05-12T22:12:38.580Z,1683929558.580 [Default:CheckIn:D] Stopped
2023-05-12T22:12:38.580Z,1683929558.580 [Default:CheckIn:E] Running Loop=1
2023-05-12T22:12:38.974Z,1683929558.974 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.167212 min 
2023-05-12T22:12:38.974Z,1683929558.974 [Default:CheckIn:E] Stopped
2023-05-12T22:12:38.974Z,1683929558.974 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T22:12:38.974Z,1683929558.974 [Default:CheckIn] Stopped
2023-05-12T22:12:38.974Z,1683929558.974 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T22:12:38.974Z,1683929558.974 [Default:CheckIn](INFO): Running loop #13
2023-05-12T22:12:38.974Z,1683929558.974 [Default:CheckIn] Running Loop=13
2023-05-12T22:12:38.974Z,1683929558.974 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T22:12:38.975Z,1683929558.975 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T22:14:28.440Z,1683929668.440 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T22:14:28.441Z,1683929668.441 [NAL9602] Data Fault, FailCount= 2
2023-05-12T22:14:28.441Z,1683929668.441 [NAL9602](ERROR): Data Fault
2023-05-12T22:14:28.458Z,1683929668.458 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T22:14:28.841Z,1683929668.841 [NAL9602](INFO): Powering down
2023-05-12T22:14:29.658Z,1683929669.658 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T22:14:29.658Z,1683929669.658 [NAL9602] No Fault, FailCount= 2
2023-05-12T22:14:59.136Z,1683929699.136 [NAL9602](INFO): Powering up NAL9602
2023-05-12T22:15:10.048Z,1683929710.048 [NAL9602](INFO): NAL9602 initialized
2023-05-12T22:15:25.037Z,1683929725.037 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 44
2023-05-12T22:15:25.037Z,1683929725.037 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:15:25.048Z,1683929725.048 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:15:25.451Z,1683929725.451 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:15:25.451Z,1683929725.451 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 44
2023-05-12T22:17:39.137Z,1683929859.137 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T22:12:38.0Z
2023-05-12T22:17:39.137Z,1683929859.137 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T22:17:39.137Z,1683929859.137 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T22:17:46.830Z,1683929866.830 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T200232/Courier0040.lzma
2023-05-12T22:17:47.833Z,1683929867.833 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0040.lzma.bak
2023-05-12T22:17:47.833Z,1683929867.833 [DataOverHttps](INFO): SBD MOMSN=18301797
2023-05-12T22:18:04.159Z,1683929884.159 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T200232/Express0041.lzma
2023-05-12T22:18:05.161Z,1683929885.161 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0041.lzma.bak
2023-05-12T22:18:05.161Z,1683929885.161 [DataOverHttps](INFO): SBD MOMSN=18301809
2023-05-12T22:18:06.621Z,1683929886.621 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T22:18:06.621Z,1683929886.621 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T22:18:06.621Z,1683929886.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T22:18:26.030Z,1683929906.030 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 45
2023-05-12T22:18:26.030Z,1683929906.030 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:18:26.064Z,1683929906.064 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:18:26.427Z,1683929906.427 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:18:26.427Z,1683929906.427 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 45
2023-05-12T22:21:27.008Z,1683930087.008 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 46
2023-05-12T22:21:27.008Z,1683930087.008 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:21:27.019Z,1683930087.019 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:21:27.431Z,1683930087.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:21:27.431Z,1683930087.431 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 46
2023-05-12T22:23:07.184Z,1683930187.184 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T22:23:07.184Z,1683930187.184 [Default:CheckIn:C.Wait] Stopped
2023-05-12T22:23:07.184Z,1683930187.184 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T22:23:07.184Z,1683930187.184 [Default:CheckIn:D] Running Loop=1
2023-05-12T22:23:07.601Z,1683930187.601 [Default:CheckIn:D] Stopped
2023-05-12T22:23:07.601Z,1683930187.601 [Default:CheckIn:E] Running Loop=1
2023-05-12T22:23:08.027Z,1683930188.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.650911 min 
2023-05-12T22:23:08.047Z,1683930188.047 [Default:CheckIn:E] Stopped
2023-05-12T22:23:08.047Z,1683930188.047 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T22:23:08.047Z,1683930188.047 [Default:CheckIn] Stopped
2023-05-12T22:23:08.047Z,1683930188.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T22:23:08.047Z,1683930188.047 [Default:CheckIn](INFO): Running loop #14
2023-05-12T22:23:08.048Z,1683930188.048 [Default:CheckIn] Running Loop=14
2023-05-12T22:23:08.048Z,1683930188.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T22:23:08.048Z,1683930188.048 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T22:24:28.019Z,1683930268.019 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 47
2023-05-12T22:24:28.020Z,1683930268.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:24:28.034Z,1683930268.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:24:28.414Z,1683930268.414 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:24:28.414Z,1683930268.414 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 47
2023-05-12T22:25:13.237Z,1683930313.237 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T22:25:13.237Z,1683930313.237 [NAL9602] Data Fault, FailCount= 3
2023-05-12T22:25:13.237Z,1683930313.237 [NAL9602](ERROR): Data Fault
2023-05-12T22:25:13.254Z,1683930313.254 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T22:25:13.696Z,1683930313.696 [NAL9602](INFO): Powering down
2023-05-12T22:25:14.476Z,1683930314.476 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T22:25:14.476Z,1683930314.476 [NAL9602] No Fault, FailCount= 3
2023-05-12T22:25:43.966Z,1683930343.966 [NAL9602](INFO): Powering up NAL9602
2023-05-12T22:25:54.845Z,1683930354.845 [NAL9602](INFO): NAL9602 initialized
2023-05-12T22:26:12.611Z,1683930372.611 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-12T22:26:12.614Z,1683930372.614 [BPC1](INFO): Received data from all battery sticks.
2023-05-12T22:27:28.980Z,1683930448.980 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 48
2023-05-12T22:27:28.980Z,1683930448.980 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:27:28.990Z,1683930448.990 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:27:29.405Z,1683930449.405 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:27:29.405Z,1683930449.405 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 48
2023-05-12T22:28:08.215Z,1683930488.215 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T22:23:08.0Z
2023-05-12T22:28:08.215Z,1683930488.215 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T22:28:08.215Z,1683930488.215 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T22:28:15.434Z,1683930495.434 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20230512T200232/Courier0043.lzma
2023-05-12T22:28:16.437Z,1683930496.437 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0043.lzma.bak
2023-05-12T22:28:16.437Z,1683930496.437 [DataOverHttps](INFO): SBD MOMSN=18301963
2023-05-12T22:28:32.336Z,1683930512.336 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230512T200232/Express0044.lzma
2023-05-12T22:28:33.337Z,1683930513.337 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0044.lzma.bak
2023-05-12T22:28:33.337Z,1683930513.337 [DataOverHttps](INFO): SBD MOMSN=18301965
2023-05-12T22:28:34.840Z,1683930514.840 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T22:28:34.840Z,1683930514.840 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T22:28:34.840Z,1683930514.840 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T22:30:30.022Z,1683930630.022 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 49
2023-05-12T22:30:30.022Z,1683930630.022 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:30:30.049Z,1683930630.049 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:30:30.395Z,1683930630.395 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:30:30.395Z,1683930630.395 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 49
2023-05-12T22:33:31.008Z,1683930811.008 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 50
2023-05-12T22:33:31.008Z,1683930811.008 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:33:31.018Z,1683930811.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:33:31.401Z,1683930811.401 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:33:31.401Z,1683930811.401 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 50
2023-05-12T22:33:35.430Z,1683930815.430 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T22:33:35.430Z,1683930815.430 [Default:CheckIn:C.Wait] Stopped
2023-05-12T22:33:35.430Z,1683930815.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T22:33:35.430Z,1683930815.430 [Default:CheckIn:D] Running Loop=1
2023-05-12T22:33:35.849Z,1683930815.849 [Default:CheckIn:D] Stopped
2023-05-12T22:33:35.849Z,1683930815.849 [Default:CheckIn:E] Running Loop=1
2023-05-12T22:33:36.264Z,1683930816.264 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.121696 min 
2023-05-12T22:33:36.264Z,1683930816.264 [Default:CheckIn:E] Stopped
2023-05-12T22:33:36.264Z,1683930816.264 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T22:33:36.264Z,1683930816.264 [Default:CheckIn] Stopped
2023-05-12T22:33:36.265Z,1683930816.265 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T22:33:36.265Z,1683930816.265 [Default:CheckIn](INFO): Running loop #15
2023-05-12T22:33:36.265Z,1683930816.265 [Default:CheckIn] Running Loop=15
2023-05-12T22:33:36.265Z,1683930816.265 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T22:33:36.265Z,1683930816.265 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T22:35:57.221Z,1683930957.221 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T22:35:57.221Z,1683930957.221 [NAL9602] Data Fault, FailCount= 4
2023-05-12T22:35:57.221Z,1683930957.221 [NAL9602](ERROR): Data Fault
2023-05-12T22:35:57.271Z,1683930957.271 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T22:35:57.628Z,1683930957.628 [NAL9602](INFO): Powering down
2023-05-12T22:35:58.461Z,1683930958.461 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T22:35:58.461Z,1683930958.461 [NAL9602] No Fault, FailCount= 4
2023-05-12T22:36:27.944Z,1683930987.944 [NAL9602](INFO): Powering up NAL9602
2023-05-12T22:36:32.015Z,1683930992.015 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 51
2023-05-12T22:36:32.015Z,1683930992.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:36:32.030Z,1683930992.030 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:36:32.388Z,1683930992.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:36:32.388Z,1683930992.388 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 51
2023-05-12T22:36:38.836Z,1683930998.836 [NAL9602](INFO): NAL9602 initialized
2023-05-12T22:38:36.422Z,1683931116.422 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T22:33:36.3Z
2023-05-12T22:38:36.422Z,1683931116.422 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T22:38:36.422Z,1683931116.422 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T22:38:44.242Z,1683931124.242 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T200232/Courier0046.lzma
2023-05-12T22:38:45.245Z,1683931125.245 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0046.lzma.bak
2023-05-12T22:38:45.245Z,1683931125.245 [DataOverHttps](INFO): SBD MOMSN=18301969
2023-05-12T22:39:00.995Z,1683931140.995 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230512T200232/Express0047.lzma
2023-05-12T22:39:01.997Z,1683931141.997 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0047.lzma.bak
2023-05-12T22:39:01.997Z,1683931141.997 [DataOverHttps](INFO): SBD MOMSN=18301971
2023-05-12T22:39:03.489Z,1683931143.489 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T22:39:03.489Z,1683931143.489 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T22:39:03.489Z,1683931143.489 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T22:39:32.993Z,1683931172.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 52
2023-05-12T22:39:32.993Z,1683931172.993 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:39:33.003Z,1683931173.003 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:39:33.386Z,1683931173.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:39:33.386Z,1683931173.386 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 52
2023-05-12T22:42:33.993Z,1683931353.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 53
2023-05-12T22:42:33.993Z,1683931353.993 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:42:34.004Z,1683931354.004 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:42:34.394Z,1683931354.394 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:42:34.394Z,1683931354.394 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 53
2023-05-12T22:44:04.081Z,1683931444.081 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T22:44:04.082Z,1683931444.082 [Default:CheckIn:C.Wait] Stopped
2023-05-12T22:44:04.082Z,1683931444.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T22:44:04.082Z,1683931444.082 [Default:CheckIn:D] Running Loop=1
2023-05-12T22:44:04.485Z,1683931444.485 [Default:CheckIn:D] Stopped
2023-05-12T22:44:04.485Z,1683931444.485 [Default:CheckIn:E] Running Loop=1
2023-05-12T22:44:04.914Z,1683931444.914 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 157.598958 min 
2023-05-12T22:44:04.914Z,1683931444.914 [Default:CheckIn:E] Stopped
2023-05-12T22:44:04.914Z,1683931444.914 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T22:44:04.914Z,1683931444.914 [Default:CheckIn] Stopped
2023-05-12T22:44:04.914Z,1683931444.914 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T22:44:04.914Z,1683931444.914 [Default:CheckIn](INFO): Running loop #16
2023-05-12T22:44:04.914Z,1683931444.914 [Default:CheckIn] Running Loop=16
2023-05-12T22:44:04.914Z,1683931444.914 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T22:44:04.914Z,1683931444.914 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T22:45:34.970Z,1683931534.970 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 54
2023-05-12T22:45:34.970Z,1683931534.970 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:45:34.980Z,1683931534.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:45:35.396Z,1683931535.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:45:35.396Z,1683931535.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 54
2023-05-12T22:46:40.397Z,1683931600.397 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T22:46:40.397Z,1683931600.397 [NAL9602] Data Fault, FailCount= 5
2023-05-12T22:46:40.397Z,1683931600.397 [NAL9602](ERROR): Data Fault
2023-05-12T22:46:40.419Z,1683931600.419 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T22:46:40.447Z,1683931600.447 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-05-12T22:46:40.804Z,1683931600.804 [NAL9602](INFO): Powering down
2023-05-12T22:46:40.826Z,1683931600.826 [CBIT](INFO): Critical error at 20230512T224640
2023-05-12T22:47:53.973Z,1683931673.973 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2023-05-12T22:48:35.999Z,1683931715.999 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 55
2023-05-12T22:48:35.999Z,1683931715.999 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-12T22:48:36.034Z,1683931716.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-12T22:48:36.411Z,1683931716.411 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-12T22:48:36.411Z,1683931716.411 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 55
2023-05-12T22:48:40.406Z,1683931720.406 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T22:48:40.406Z,1683931720.406 [NAL9602] No Fault, FailCount= 5
2023-05-12T22:48:40.792Z,1683931720.792 [NAL9602](INFO): Powering up NAL9602
2023-05-12T22:48:51.701Z,1683931731.701 [NAL9602](INFO): NAL9602 initialized
2023-05-12T22:49:05.040Z,1683931745.040 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T22:44:04.9Z
2023-05-12T22:49:05.041Z,1683931745.041 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T22:49:05.041Z,1683931745.041 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T22:49:12.667Z,1683931752.667 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20230512T200232/Courier0049.lzma
2023-05-12T22:49:13.669Z,1683931753.669 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Courier0049.lzma.bak
2023-05-12T22:49:13.669Z,1683931753.669 [DataOverHttps](INFO): SBD MOMSN=18301989
2023-05-12T22:49:29.587Z,1683931769.587 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230512T200232/Express0050.lzma
2023-05-12T22:49:30.723Z,1683931770.723 [DataOverHttps](INFO): Moved sent file to Logs/20230512T200232/Express0050.lzma.bak
2023-05-12T22:49:30.723Z,1683931770.723 [DataOverHttps](INFO): SBD MOMSN=18301992
2023-05-12T22:49:32.137Z,1683931772.137 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T22:49:32.137Z,1683931772.137 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T22:49:32.137Z,1683931772.137 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T22:51:01.820Z,1683931861.820 [CommandExec](IMPORTANT): got command quit
2023-05-12T22:51:02.823Z,1683931862.823 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:02.823Z,1683931862.823 [CommandExec](INFO): Uninitializing the command executive.
2023-05-12T22:51:02.823Z,1683931862.823 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-12T22:51:02.824Z,1683931862.824 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:02.951Z,1683931862.951 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-12T22:51:02.951Z,1683931862.951 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-12T22:51:02.951Z,1683931862.951 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:02.952Z,1683931862.952 [NavChartDb](INFO): Join timeout helper Thread ID is 8750
2023-05-12T22:51:03.171Z,1683931863.171 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:03.172Z,1683931863.172 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:03.179Z,1683931863.179 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-05-12T22:51:03.179Z,1683931863.179 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:03.180Z,1683931863.180 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 8751
2023-05-12T22:51:03.555Z,1683931863.555 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:03.555Z,1683931863.555 [WetLabsUBAT](INFO): Powering down
2023-05-12T22:51:03.556Z,1683931863.556 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:03.567Z,1683931863.567 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-12T22:51:03.567Z,1683931863.567 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:03.567Z,1683931863.567 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8752
2023-05-12T22:51:03.671Z,1683931863.671 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:03.672Z,1683931863.672 [WetLabsBB2FL](INFO): Powering down
2023-05-12T22:51:03.672Z,1683931863.672 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:03.689Z,1683931863.689 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-12T22:51:03.689Z,1683931863.689 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:03.690Z,1683931863.690 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8753
2023-05-12T22:51:03.983Z,1683931863.983 [CTD_Seabird](INFO): Powering down
2023-05-12T22:51:03.995Z,1683931863.995 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:03.995Z,1683931863.995 [CTD_Seabird](INFO): Powering down
2023-05-12T22:51:04.013Z,1683931864.013 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:04.031Z,1683931864.031 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-12T22:51:04.031Z,1683931864.031 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:04.032Z,1683931864.032 [Radio_Surface](INFO): Join timeout helper Thread ID is 8754
2023-05-12T22:51:04.363Z,1683931864.363 [Radio_Surface](INFO): Powering down
2023-05-12T22:51:04.364Z,1683931864.364 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:04.364Z,1683931864.364 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:04.389Z,1683931864.389 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-12T22:51:04.390Z,1683931864.390 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:04.390Z,1683931864.390 [Onboard](INFO): Join timeout helper Thread ID is 8755
2023-05-12T22:51:05.183Z,1683931865.183 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:05.184Z,1683931865.184 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.196Z,1683931865.196 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-12T22:51:05.196Z,1683931865.196 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.196Z,1683931865.196 [DataOverHttps](INFO): Join timeout helper Thread ID is 8756
2023-05-12T22:51:05.291Z,1683931865.291 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:05.292Z,1683931865.292 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.296Z,1683931865.296 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-12T22:51:05.296Z,1683931865.296 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.297Z,1683931865.297 [DATMMP](INFO): Join timeout helper Thread ID is 8757
2023-05-12T22:51:05.503Z,1683931865.503 [DATMMP](INFO): Stop
2023-05-12T22:51:05.503Z,1683931865.503 [DATMMP](INFO): uninitialize
2023-05-12T22:51:05.504Z,1683931865.504 [DATMMP](INFO): Powering down
2023-05-12T22:51:05.576Z,1683931865.576 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:05.576Z,1683931865.576 [DATMMP](INFO): uninitialize
2023-05-12T22:51:05.576Z,1683931865.576 [DATMMP](INFO): Powering down
2023-05-12T22:51:05.577Z,1683931865.577 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.588Z,1683931865.588 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-12T22:51:05.588Z,1683931865.588 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.588Z,1683931865.588 [BackseatComponent](INFO): Join timeout helper Thread ID is 8758
2023-05-12T22:51:05.631Z,1683931865.631 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:05.632Z,1683931865.632 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.638Z,1683931865.638 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-12T22:51:05.638Z,1683931865.638 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.639Z,1683931865.639 [logger](INFO): Join timeout helper Thread ID is 8759
2023-05-12T22:51:05.657Z,1683931865.657 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:05.657Z,1683931865.657 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.672Z,1683931865.672 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-12T22:51:05.672Z,1683931865.672 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.672Z,1683931865.672 [CommandLine](INFO): Join timeout helper Thread ID is 8760
2023-05-12T22:51:05.723Z,1683931865.723 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:05.724Z,1683931865.724 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.744Z,1683931865.744 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-12T22:51:05.744Z,1683931865.744 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.744Z,1683931865.744 [CommandExec](INFO): Join timeout helper Thread ID is 8761
2023-05-12T22:51:05.745Z,1683931865.745 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-12T22:51:05.745Z,1683931865.745 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:05.746Z,1683931865.746 [controlThread](INFO): Join timeout helper Thread ID is 8762
2023-05-12T22:51:05.811Z,1683931865.811 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T22:51:05.812Z,1683931865.812 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-12T22:51:05.814Z,1683931865.814 [AHRS_M2](INFO): Powering down
2023-05-12T22:51:05.888Z,1683931865.888 [NAL9602](INFO): Powering down
2023-05-12T22:51:05.959Z,1683931865.959 [RDI_Pathfinder](INFO): Powering down
2023-05-12T22:51:05.961Z,1683931865.961 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-12T22:51:05.961Z,1683931865.961 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-12T22:51:05.962Z,1683931865.962 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-12T22:51:05.962Z,1683931865.962 [MissionManager](INFO): Uninitializing Mission Default
2023-05-12T22:51:05.962Z,1683931865.962 [Default] Stopped
2023-05-12T22:51:05.963Z,1683931865.963 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-12T22:51:05.963Z,1683931865.963 [Default:B.GoToSurface] Stopped
2023-05-12T22:51:05.963Z,1683931865.963 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T22:51:05.963Z,1683931865.963 [Default:CheckIn] Stopped
2023-05-12T22:51:05.963Z,1683931865.963 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T22:51:05.963Z,1683931865.963 [Default:CheckIn:C.Wait] Stopped
2023-05-12T22:51:05.963Z,1683931865.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T22:51:05.966Z,1683931865.966 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-12T22:51:05.966Z,1683931865.966 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-12T22:51:05.966Z,1683931865.966 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-12T22:51:05.967Z,1683931865.967 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-12T22:51:05.967Z,1683931865.967 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-12T22:51:05.967Z,1683931865.967 [BuoyancyServo](INFO): Powering down
2023-05-12T22:51:05.979Z,1683931865.979 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-12T22:51:05.979Z,1683931865.979 [ElevatorServo](INFO): Powering down
2023-05-12T22:51:05.980Z,1683931865.980 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-12T22:51:05.980Z,1683931865.980 [MassServo](INFO): Powering down
2023-05-12T22:51:05.981Z,1683931865.981 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-12T22:51:05.981Z,1683931865.981 [RudderServo](INFO): Powering down
2023-05-12T22:51:05.982Z,1683931865.982 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-05-12T22:51:05.982Z,1683931865.982 [ThrusterServo](INFO): Powering down
2023-05-12T22:51:05.983Z,1683931865.983 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-12T22:51:05.983Z,1683931865.983 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-12T22:51:05.984Z,1683931865.984 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-12T22:51:05.984Z,1683931865.984 [CBIT](DEBUG): Powering off loads.
2023-05-12T22:51:05.995Z,1683931865.995 [CBIT](DEBUG): Disabling WDT.
2023-05-12T22:51:06.007Z,1683931866.007 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-12T22:51:06.008Z,1683931866.008 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.068Z,1683931866.068 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.071Z,1683931866.071 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.077Z,1683931866.077 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.083Z,1683931866.083 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.123Z,1683931866.123 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.182Z,1683931866.182 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.183Z,1683931866.183 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.187Z,1683931866.187 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.194Z,1683931866.194 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T22:51:06.255Z,1683931866.255 [logger ThreadHandler](INFO): Thread cancelled.