2023-06-06T15:19:25.983Z,1686064765.983 [Supervisor](DEBUG): Initializing supervisor.
2023-06-06T15:19:25.987Z,1686064765.987 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-06-06T15:19:25.988Z,1686064765.988 [SyncHandler](INFO): Protected caller Thread ID is 380
2023-06-06T15:19:25.988Z,1686064765.988 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-06-06T15:19:25.989Z,1686064765.989 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-06-06T15:19:25.989Z,1686064765.989 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 381
2023-06-06T15:19:25.993Z,1686064765.993 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-06-06T15:19:26.010Z,1686064766.010 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-06-06T15:19:26.011Z,1686064766.011 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-06-06T15:19:26.012Z,1686064766.012 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 382
2023-06-06T15:19:26.016Z,1686064766.016 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-06-06T15:19:26.017Z,1686064766.017 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-06-06T15:19:26.017Z,1686064766.017 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 383
2023-06-06T15:19:26.019Z,1686064766.019 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-06-06T15:19:26.020Z,1686064766.020 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-06-06T15:19:26.020Z,1686064766.020 [logger ThreadHandler](INFO): Protected caller Thread ID is 384
2023-06-06T15:19:26.024Z,1686064766.024 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-06-06T15:19:26.024Z,1686064766.024 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-06-06T15:19:26.026Z,1686064766.026 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-06-06T15:19:26.282Z,1686064766.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-06-06T15:19:26.282Z,1686064766.282 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-06-06T15:19:26.362Z,1686064766.362 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-06-06T15:19:26.809Z,1686064766.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-06-06T15:19:26.809Z,1686064766.809 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-06-06T15:19:27.115Z,1686064767.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-06-06T15:19:27.116Z,1686064767.116 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-06-06T15:19:27.211Z,1686064767.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-06-06T15:19:27.212Z,1686064767.212 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-06-06T15:19:27.583Z,1686064767.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-06-06T15:19:27.584Z,1686064767.584 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-06-06T15:19:27.784Z,1686064767.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-06-06T15:19:27.784Z,1686064767.784 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-06-06T15:19:28.272Z,1686064768.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-06-06T15:19:28.272Z,1686064768.272 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-06-06T15:19:28.404Z,1686064768.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-06-06T15:19:28.405Z,1686064768.405 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-06-06T15:19:28.484Z,1686064768.484 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-06-06T15:19:29.185Z,1686064769.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-06-06T15:19:29.185Z,1686064769.185 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-06-06T15:19:29.542Z,1686064769.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-06-06T15:19:29.543Z,1686064769.543 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-06-06T15:19:29.726Z,1686064769.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-06-06T15:19:29.727Z,1686064769.727 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-06-06T15:19:29.836Z,1686064769.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-06-06T15:19:29.837Z,1686064769.837 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-06-06T15:19:30.052Z,1686064770.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-06-06T15:19:30.053Z,1686064770.053 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-06-06T15:19:30.282Z,1686064770.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-06-06T15:19:30.284Z,1686064770.284 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-06-06T15:19:30.285Z,1686064770.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-06-06T15:19:30.376Z,1686064770.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-06-06T15:19:30.460Z,1686064770.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-06-06T15:19:30.559Z,1686064770.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-06-06T15:19:30.689Z,1686064770.689 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-06-06T15:19:31.055Z,1686064771.055 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-06-06T15:19:31.280Z,1686064771.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-06-06T15:19:31.565Z,1686064771.565 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-06-06T15:19:31.673Z,1686064771.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-06-06T15:19:31.862Z,1686064771.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-06-06T15:19:31.994Z,1686064771.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-06-06T15:19:32.100Z,1686064772.100 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-06-06T15:19:32.357Z,1686064772.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-06-06T15:19:32.357Z,1686064772.357 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-06-06T15:19:32.366Z,1686064772.366 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-06-06T15:19:32.416Z,1686064772.416 [VerticalControl](DEBUG): Construct VerticalControl.
2023-06-06T15:19:32.473Z,1686064772.473 [VerticalControl] Loaded
2023-06-06T15:19:32.473Z,1686064772.473 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-06-06T15:19:32.476Z,1686064772.476 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-06-06T15:19:32.516Z,1686064772.516 [HorizontalControl] Loaded
2023-06-06T15:19:32.516Z,1686064772.516 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-06-06T15:19:32.519Z,1686064772.519 [SpeedControl](DEBUG): Construct SpeedControl.
2023-06-06T15:19:32.522Z,1686064772.522 [SpeedControl] Loaded
2023-06-06T15:19:32.522Z,1686064772.522 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-06-06T15:19:32.524Z,1686064772.524 [LoopControl](DEBUG): Construct LoopControl.
2023-06-06T15:19:32.525Z,1686064772.525 [LoopControl] Loaded
2023-06-06T15:19:32.525Z,1686064772.525 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-06-06T15:19:32.525Z,1686064772.525 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-06-06T15:19:32.526Z,1686064772.526 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-06-06T15:19:32.536Z,1686064772.536 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-06-06T15:19:32.537Z,1686064772.537 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-06-06T15:19:32.679Z,1686064772.679 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-06-06T15:19:32.679Z,1686064772.679 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-06-06T15:19:32.805Z,1686064772.805 [BuoyancyServo] Loaded
2023-06-06T15:19:32.805Z,1686064772.805 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-06-06T15:19:32.824Z,1686064772.824 [ElevatorServo] Loaded
2023-06-06T15:19:32.825Z,1686064772.825 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-06-06T15:19:32.843Z,1686064772.843 [MassServo] Loaded
2023-06-06T15:19:32.843Z,1686064772.843 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-06-06T15:19:32.861Z,1686064772.861 [RudderServo] Loaded
2023-06-06T15:19:32.862Z,1686064772.862 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-06-06T15:19:32.878Z,1686064772.878 [ThrusterServo] Loaded
2023-06-06T15:19:32.879Z,1686064772.879 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-06-06T15:19:32.879Z,1686064772.879 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-06-06T15:19:32.880Z,1686064772.880 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-06-06T15:19:32.988Z,1686064772.988 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-06-06T15:19:32.988Z,1686064772.988 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-06-06T15:19:33.002Z,1686064773.002 [NavChart] Loaded
2023-06-06T15:19:33.002Z,1686064773.002 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-06-06T15:19:33.008Z,1686064773.008 [UniversalFixResidualReporter] Loaded
2023-06-06T15:19:33.008Z,1686064773.008 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-06-06T15:19:33.008Z,1686064773.008 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-06-06T15:19:33.009Z,1686064773.009 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-06-06T15:19:33.063Z,1686064773.063 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-06-06T15:19:33.063Z,1686064773.063 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-06-06T15:19:33.470Z,1686064773.470 [AHRS_M2] Loaded
2023-06-06T15:19:33.470Z,1686064773.470 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-06-06T15:19:34.284Z,1686064774.284 [BPC1] Loaded
2023-06-06T15:19:34.285Z,1686064774.285 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-06-06T15:19:34.357Z,1686064774.357 [DataOverHttps] Loaded
2023-06-06T15:19:34.357Z,1686064774.357 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-06-06T15:19:34.358Z,1686064774.358 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2023-06-06T15:19:34.358Z,1686064774.358 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 473
2023-06-06T15:19:34.379Z,1686064774.379 [Depth_Keller] Loaded
2023-06-06T15:19:34.379Z,1686064774.379 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-06-06T15:19:34.384Z,1686064774.384 [DropWeight] Loaded
2023-06-06T15:19:34.384Z,1686064774.384 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-06-06T15:19:34.445Z,1686064774.445 [NAL9602] Loaded
2023-06-06T15:19:34.445Z,1686064774.445 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-06-06T15:19:34.474Z,1686064774.474 [Onboard] Loaded
2023-06-06T15:19:34.475Z,1686064774.475 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-06-06T15:19:34.476Z,1686064774.476 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2023-06-06T15:19:34.476Z,1686064774.476 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 474
2023-06-06T15:19:34.489Z,1686064774.489 [Power24vConverter] Loaded
2023-06-06T15:19:34.489Z,1686064774.489 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-06-06T15:19:34.502Z,1686064774.502 [Radio_Surface] Loaded
2023-06-06T15:19:34.503Z,1686064774.503 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-06-06T15:19:34.504Z,1686064774.504 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0
2023-06-06T15:19:34.504Z,1686064774.504 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 475
2023-06-06T15:19:34.505Z,1686064774.505 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-06-06T15:19:34.506Z,1686064774.506 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-06-06T15:19:34.556Z,1686064774.556 [DepthRateCalculator] Loaded
2023-06-06T15:19:34.556Z,1686064774.556 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-06-06T15:19:34.561Z,1686064774.561 [PitchRateCalculator] Loaded
2023-06-06T15:19:34.561Z,1686064774.561 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-06-06T15:19:34.570Z,1686064774.570 [SpeedCalculator] Loaded
2023-06-06T15:19:34.570Z,1686064774.570 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-06-06T15:19:34.574Z,1686064774.574 [YawRateCalculator] Loaded
2023-06-06T15:19:34.575Z,1686064774.575 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-06-06T15:19:34.593Z,1686064774.593 [ElevatorOffsetCalculator] Loaded
2023-06-06T15:19:34.594Z,1686064774.594 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-06-06T15:19:34.594Z,1686064774.594 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-06-06T15:19:34.595Z,1686064774.595 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-06-06T15:19:34.718Z,1686064774.718 [CANONSampler] Loaded
2023-06-06T15:19:34.718Z,1686064774.718 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-06-06T15:19:34.778Z,1686064774.778 [CTD_Seabird] Loaded
2023-06-06T15:19:34.778Z,1686064774.778 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-06-06T15:19:34.779Z,1686064774.779 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A194E0
2023-06-06T15:19:34.779Z,1686064774.779 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 476
2023-06-06T15:19:34.799Z,1686064774.799 [PAR_Licor] Loaded
2023-06-06T15:19:34.800Z,1686064774.800 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-06-06T15:19:34.830Z,1686064774.830 [WetLabsBB2FL] Loaded
2023-06-06T15:19:34.830Z,1686064774.830 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-06-06T15:19:34.831Z,1686064774.831 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0
2023-06-06T15:19:34.831Z,1686064774.831 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 477
2023-06-06T15:19:34.832Z,1686064774.832 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-06-06T15:19:34.833Z,1686064774.833 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-06-06T15:19:34.858Z,1686064774.858 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-06-06T15:19:34.859Z,1686064774.859 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-06-06T15:19:35.153Z,1686064775.153 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-06-06T15:19:35.154Z,1686064775.154 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-06-06T15:19:35.288Z,1686064775.288 [SBIT](DEBUG): Construct Startup Built In Test.
2023-06-06T15:19:35.297Z,1686064775.297 [SBIT] Loaded
2023-06-06T15:19:35.297Z,1686064775.297 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-06-06T15:19:35.300Z,1686064775.300 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-06-06T15:19:35.312Z,1686064775.312 [IBIT] Loaded
2023-06-06T15:19:35.313Z,1686064775.313 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-06-06T15:19:35.318Z,1686064775.318 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-06-06T15:19:35.420Z,1686064775.420 [CBIT] Loaded
2023-06-06T15:19:35.421Z,1686064775.421 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-06-06T15:19:35.421Z,1686064775.421 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-06-06T15:19:35.422Z,1686064775.422 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-06-06T15:19:35.515Z,1686064775.515 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-06-06T15:19:35.521Z,1686064775.521 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-06-06T15:19:35.524Z,1686064775.524 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-06-06T15:19:35.535Z,1686064775.535 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-06-06T15:19:35.536Z,1686064775.536 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B814E0
2023-06-06T15:19:35.536Z,1686064775.536 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 478
2023-06-06T15:19:35.541Z,1686064775.541 [Supervisor](INFO): Main Thread ID is 379
2023-06-06T15:19:35.541Z,1686064775.541 [Supervisor](DEBUG): Running supervisor.
2023-06-06T15:19:35.542Z,1686064775.542 [CommandExec ThreadHandler](INFO): Handler Thread ID is 479
2023-06-06T15:19:35.542Z,1686064775.542 [CommandExec](INFO): Initializing the command executive.
2023-06-06T15:19:35.544Z,1686064775.544 [CommandLine ThreadHandler](INFO): Handler Thread ID is 480
2023-06-06T15:19:35.546Z,1686064775.546 [controlThread ThreadHandler](INFO): Handler Thread ID is 481
2023-06-06T15:19:35.546Z,1686064775.546 [controlThread](DEBUG): Initializing ControlThread
2023-06-06T15:19:35.547Z,1686064775.547 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-06-06T15:19:35.549Z,1686064775.549 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-06-06T15:19:35.550Z,1686064775.550 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-06-06T15:19:35.550Z,1686064775.550 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-06-06T15:19:35.552Z,1686064775.552 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-06-06T15:19:35.552Z,1686064775.552 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-06-06T15:19:35.557Z,1686064775.557 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-06-06T15:19:35.557Z,1686064775.557 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-06-06T15:19:35.558Z,1686064775.558 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-06-06T15:19:35.558Z,1686064775.558 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-06-06T15:19:35.558Z,1686064775.558 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-06-06T15:19:35.564Z,1686064775.564 [SBIT](INFO): Initialize SBIT Component.
2023-06-06T15:19:35.564Z,1686064775.564 [SBIT](IMPORTANT): git: 2023-03-30-8-g9f4e94ede
2023-06-06T15:19:35.564Z,1686064775.564 [SBIT](INFO): git hash: 9f4e94ede96dd26b46ac6117d56fbd84feccae72
2023-06-06T15:19:35.565Z,1686064775.565 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-06-06T15:19:35.566Z,1686064775.566 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-06-06T15:19:35.567Z,1686064775.567 [SBIT](INFO): Beginning SBIT in 61.000000 seconds.
2023-06-06T15:19:35.568Z,1686064775.568 [IBIT](INFO): Initialize IBIT Component.
2023-06-06T15:19:35.569Z,1686064775.569 [CBIT](DEBUG): Initialize CBIT Component.
2023-06-06T15:19:35.569Z,1686064775.569 [logger ThreadHandler](INFO): Handler Thread ID is 482
2023-06-06T15:19:35.579Z,1686064775.579 [CBIT](DEBUG): Initialized mux pins.
2023-06-06T15:19:35.579Z,1686064775.579 [CBIT](DEBUG): Initializing the watchdog timer.
2023-06-06T15:19:35.588Z,1686064775.588 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 483
2023-06-06T15:19:35.589Z,1686064775.589 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-06-06T15:19:35.600Z,1686064775.600 [Onboard ThreadHandler](INFO): Handler Thread ID is 484
2023-06-06T15:19:35.604Z,1686064775.604 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-06-06T15:19:35.604Z,1686064775.604 [CBIT](DEBUG): Initializing heartbeat.
2023-06-06T15:19:35.620Z,1686064775.620 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 485
2023-06-06T15:19:35.640Z,1686064775.640 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 486
2023-06-06T15:19:35.640Z,1686064775.640 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-06-06T15:19:35.645Z,1686064775.645 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 488
2023-06-06T15:19:35.648Z,1686064775.648 [WetLabsBB2FL](INFO): Powering up
2023-06-06T15:19:35.649Z,1686064775.649 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 490
2023-06-06T15:19:35.652Z,1686064775.652 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-06-06T15:19:35.653Z,1686064775.653 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-06-06T15:19:35.653Z,1686064775.653 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-06-06T15:19:35.653Z,1686064775.653 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-06-06T15:19:35.653Z,1686064775.653 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-06-06T15:19:35.653Z,1686064775.653 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-06-06T15:19:35.653Z,1686064775.653 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-06-06T15:19:35.654Z,1686064775.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-06-06T15:19:35.675Z,1686064775.675 [CBIT](DEBUG): Deactivating GF circuits.
2023-06-06T15:19:35.675Z,1686064775.675 [CBIT](DEBUG): Deactivating emergency mode.
2023-06-06T15:19:35.715Z,1686064775.715 [CBIT](DEBUG): Backplane powered.
2023-06-06T15:19:35.716Z,1686064775.716 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-06-06T15:19:35.729Z,1686064775.729 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-06-06T15:19:35.741Z,1686064775.741 [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-06-06T15:19:35.742Z,1686064775.742 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-06-06T15:19:35.980Z,1686064775.980 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-06-06T15:19:35.981Z,1686064775.981 [Default:A.Wait](DEBUG): Construct Wait.
2023-06-06T15:19:35.995Z,1686064775.995 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-06-06T15:19:36.023Z,1686064776.023 [Radio_Surface](INFO): Powering up
2023-06-06T15:19:36.061Z,1686064776.061 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-06-06T15:19:36.064Z,1686064776.064 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-06-06T15:19:36.090Z,1686064776.090 [Default:E.Execute](DEBUG): Construct Execute.
2023-06-06T15:19:36.093Z,1686064776.093 [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-06-06T15:19:36.098Z,1686064776.098 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-06-06T15:19:36.124Z,1686064776.124 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-06-06T15:19:36.210Z,1686064776.210 [Power24vConverter](INFO): Powering up.
2023-06-06T15:19:36.211Z,1686064776.211 [CANONSampler](INFO): Powering down
2023-06-06T15:19:36.246Z,1686064776.246 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-06-06T15:19:36.252Z,1686064776.252 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-06-06T15:19:36.253Z,1686064776.253 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-06-06T15:19:36.260Z,1686064776.260 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-06-06T15:19:36.261Z,1686064776.261 [MassServo](DEBUG): Initializing EZServoServo.
2023-06-06T15:19:36.272Z,1686064776.272 [MassServo](DEBUG): Initializing MassServo.
2023-06-06T15:19:36.272Z,1686064776.272 [RudderServo](DEBUG): Initializing EZServoServo.
2023-06-06T15:19:36.280Z,1686064776.280 [RudderServo](DEBUG): Initializing RudderServo.
2023-06-06T15:19:36.280Z,1686064776.280 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-06-06T15:19:36.292Z,1686064776.292 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-06-06T15:19:38.260Z,1686064778.260 [WetLabsBB2FL](INFO): Powering down
2023-06-06T15:19:42.672Z,1686064782.672 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-06-06T15:19:49.106Z,1686064789.106 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004901
2023-06-06T15:20:04.793Z,1686064804.793 [NAL9602](INFO): Powering up NAL9602
2023-06-06T15:20:15.701Z,1686064815.701 [NAL9602](INFO): NAL9602 initialized
2023-06-06T15:20:37.165Z,1686064837.165 [SBIT](IMPORTANT): Beginning Startup BIT
2023-06-06T15:20:37.169Z,1686064837.169 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T15:20:48.056Z,1686064848.056 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-06-06T15:20:48.445Z,1686064848.445 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T15:20:59.456Z,1686064859.456 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-06-06T15:20:59.844Z,1686064859.844 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T15:21:10.788Z,1686064870.788 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-06-06T15:21:10.789Z,1686064870.789 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-06-06T15:21:24.392Z,1686064884.392 [CommandExec](IMPORTANT): got command failComponent
2023-06-06T15:21:24.392Z,1686064884.392 [CommandExec](IMPORTANT): Failed components:
2023-06-06T15:21:24.392Z,1686064884.392 [CommandExec](IMPORTANT): No failed Components.
2023-06-06T15:21:27.516Z,1686064887.516 [CommandExec](IMPORTANT): got command failComponent
2023-06-06T15:21:27.516Z,1686064887.516 [CommandExec](IMPORTANT): Failed components:
2023-06-06T15:21:27.517Z,1686064887.517 [CommandExec](IMPORTANT): No failed Components.
2023-06-06T15:21:29.592Z,1686064889.592 [CommandExec](IMPORTANT): got command gfscan
2023-06-06T15:21:29.675Z,1686064889.675 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T15:21:31.290Z,1686064891.290 [SBIT](IMPORTANT): SBIT PASSED
2023-06-06T15:21:31.291Z,1686064891.291 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-06-06T15:21:31.292Z,1686064891.292 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-06-06T15:21:31.292Z,1686064891.292 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=14 count;

2023-06-06T15:21:31.292Z,1686064891.292 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-06-06T15:21:31.292Z,1686064891.292 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-06-06T15:21:31.292Z,1686064891.292 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-06-06T15:21:31.292Z,1686064891.292 [SBIT](IMPORTANT): RudderServo.offsetAngle=0 degree;

2023-06-06T15:21:31.292Z,1686064891.292 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2023-06-06T15:21:31.292Z,1686064891.292 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=233.035828 cubic_centimeter;

2023-06-06T15:21:31.293Z,1686064891.293 [SBIT](IMPORTANT): VerticalControl.massDefault=-10.313124 millimeter;

2023-06-06T15:21:31.689Z,1686064891.689 [MissionManager](IMPORTANT): Started mission Startup
2023-06-06T15:21:31.689Z,1686064891.689 [Startup] Running Loop=1
2023-06-06T15:21:31.689Z,1686064891.689 [Startup](DEBUG): Aggregate::initialize Startup
2023-06-06T15:21:31.689Z,1686064891.689 [Startup:A.GoToSurface] Running Loop=1
2023-06-06T15:21:31.689Z,1686064891.689 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-06-06T15:21:31.690Z,1686064891.690 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-06-06T15:21:31.690Z,1686064891.690 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-06-06T15:21:31.691Z,1686064891.691 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-06-06T15:21:31.691Z,1686064891.691 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-06-06T15:21:31.692Z,1686064891.692 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-06-06T15:21:31.693Z,1686064891.693 [Startup:StartupSatComms] Running Loop=1
2023-06-06T15:21:31.693Z,1686064891.693 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-06-06T15:21:31.693Z,1686064891.693 [Startup:StartupSatComms:A] Running Loop=1
2023-06-06T15:21:32.103Z,1686064892.103 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-06-06T15:21:40.599Z,1686064900.599 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002867
CHAN A1 (24V): 0.000431
CHAN A2 (12V): -0.002319
CHAN A3 (5V): -0.001878
CHAN B0 (3.3V): 0.000284
CHAN B1 (3.15aV): 0.000398
CHAN B2 (3.15bV): -0.000019
CHAN B3 (GND): -0.000183
OPEN: -0.000547
Full Scale: +/- 1 mA
2023-06-06T15:22:21.456Z,1686064941.456 [CommandExec](IMPORTANT): got command failComponent
2023-06-06T15:22:21.456Z,1686064941.456 [CommandExec](IMPORTANT): Failed components:
2023-06-06T15:22:21.456Z,1686064941.456 [CommandExec](IMPORTANT): No failed Components.
2023-06-06T15:22:31.982Z,1686064951.982 [Startup:StartupSatComms:A](INFO): Timed out from 2023-06-06T15:21:31.7Z
2023-06-06T15:22:31.982Z,1686064951.982 [Startup:StartupSatComms:A] Stopped
2023-06-06T15:22:31.982Z,1686064951.982 [Startup:StartupSatComms:B] Running Loop=1
2023-06-06T15:22:32.289Z,1686064952.289 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-06-06T15:22:35.908Z,1686064955.908 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-06-06T15:22:35.908Z,1686064955.908 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:22:35.946Z,1686064955.946 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:22:36.334Z,1686064956.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:22:36.334Z,1686064956.334 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-06-06T15:22:39.161Z,1686064959.161 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230606T151925/Courier0000.lzma
2023-06-06T15:22:40.153Z,1686064960.153 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0000.lzma.bak
2023-06-06T15:22:40.153Z,1686064960.153 [DataOverHttps](INFO): SBD MOMSN=18399417
2023-06-06T15:22:55.785Z,1686064975.785 [DataOverHttps](INFO): Sending 989 bytes from file Logs/20230606T151925/Express0001.lzma
2023-06-06T15:22:56.785Z,1686064976.785 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0001.lzma.bak
2023-06-06T15:22:56.785Z,1686064976.785 [DataOverHttps](INFO): SBD MOMSN=18399420
2023-06-06T15:22:58.121Z,1686064978.121 [Startup:StartupSatComms:B] Stopped
2023-06-06T15:22:58.121Z,1686064978.121 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-06-06T15:22:58.121Z,1686064978.121 [Startup:StartupSatComms] Stopped
2023-06-06T15:22:58.121Z,1686064978.121 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-06-06T15:22:58.122Z,1686064978.122 [Startup](INFO): Completed Startup
2023-06-06T15:22:58.122Z,1686064978.122 [MissionManager](INFO): Startup is completed.
2023-06-06T15:22:58.122Z,1686064978.122 [MissionManager](INFO): Uninitializing Mission Startup
2023-06-06T15:22:58.122Z,1686064978.122 [Startup] Stopped
2023-06-06T15:22:58.122Z,1686064978.122 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-06-06T15:22:58.122Z,1686064978.122 [Startup:A.GoToSurface] Stopped
2023-06-06T15:22:58.122Z,1686064978.122 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-06-06T15:22:58.584Z,1686064978.584 [MissionManager](IMPORTANT): Started mission Default
2023-06-06T15:22:58.584Z,1686064978.584 [Default] Running Loop=1
2023-06-06T15:22:58.584Z,1686064978.584 [Default](DEBUG): Aggregate::initialize Default
2023-06-06T15:22:58.584Z,1686064978.584 [Default:B.GoToSurface] Running Loop=1
2023-06-06T15:22:58.584Z,1686064978.584 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-06-06T15:22:58.584Z,1686064978.584 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-06-06T15:22:58.585Z,1686064978.585 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-06-06T15:22:58.585Z,1686064978.585 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-06-06T15:22:58.585Z,1686064978.585 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-06-06T15:22:58.586Z,1686064978.586 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-06-06T15:22:58.586Z,1686064978.586 [Default:A.Wait] Running Loop=1
2023-06-06T15:22:58.586Z,1686064978.586 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-06-06T15:23:11.872Z,1686064991.872 [Default:A.Wait](INFO): Done Waiting.
2023-06-06T15:23:11.872Z,1686064991.872 [Default:A.Wait] Stopped
2023-06-06T15:23:11.872Z,1686064991.872 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T15:23:12.285Z,1686064992.285 [Default:CheckIn] Running Loop=1
2023-06-06T15:23:12.285Z,1686064992.285 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T15:23:12.285Z,1686064992.285 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T15:23:12.786Z,1686064992.786 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-06-06T15:24:38.700Z,1686065078.700 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:24:54.057Z,1686065094.057 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:25:09.002Z,1686065109.002 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:25:19.114Z,1686065119.114 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-06-06T15:25:23.948Z,1686065123.948 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:25:36.913Z,1686065136.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-06-06T15:25:36.913Z,1686065136.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:25:36.923Z,1686065136.923 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:25:37.337Z,1686065137.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:25:37.337Z,1686065137.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-06-06T15:25:39.300Z,1686065139.300 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:25:54.248Z,1686065154.248 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:26:09.196Z,1686065169.196 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:26:24.548Z,1686065184.548 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:26:40.707Z,1686065200.707 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:26:48.672Z,1686065208.672 [CommandExec](IMPORTANT): got command burn on
2023-06-06T15:26:48.672Z,1686065208.672 [CommandExec](IMPORTANT): Activating dropweight wire
2023-06-06T15:26:57.516Z,1686065217.516 [CommandExec](IMPORTANT): got command burn off
2023-06-06T15:26:57.516Z,1686065217.516 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-06-06T15:28:12.454Z,1686065292.454 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-06T15:23:12.3Z
2023-06-06T15:28:12.454Z,1686065292.454 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T15:28:12.454Z,1686065292.454 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T15:28:12.854Z,1686065292.854 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-06-06T15:28:20.111Z,1686065300.111 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230606T151925/Courier0004.lzma
2023-06-06T15:28:21.113Z,1686065301.113 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0004.lzma.bak
2023-06-06T15:28:21.113Z,1686065301.113 [DataOverHttps](INFO): SBD MOMSN=18399454
2023-06-06T15:28:36.667Z,1686065316.667 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20230606T151925/Express0005.lzma
2023-06-06T15:28:37.669Z,1686065317.669 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0005.lzma.bak
2023-06-06T15:28:37.669Z,1686065317.669 [DataOverHttps](INFO): SBD MOMSN=18399456
2023-06-06T15:28:37.896Z,1686065317.896 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-06-06T15:28:37.896Z,1686065317.896 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:28:37.907Z,1686065317.907 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:28:38.314Z,1686065318.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:28:38.314Z,1686065318.314 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-06-06T15:28:38.693Z,1686065318.693 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T15:28:38.693Z,1686065318.693 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T15:28:38.693Z,1686065318.693 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T15:29:37.262Z,1686065377.262 [Power24vConverter](INFO): Powering down.
2023-06-06T15:30:17.665Z,1686065417.665 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-06-06T15:30:17.665Z,1686065417.665 [NAL9602] Data Fault, FailCount= 1
2023-06-06T15:30:17.666Z,1686065417.666 [NAL9602](ERROR): Data Fault
2023-06-06T15:30:17.682Z,1686065417.682 [CBIT](ERROR): Data Fault in component: NAL9602
2023-06-06T15:30:18.075Z,1686065418.075 [NAL9602](INFO): Powering down
2023-06-06T15:30:18.892Z,1686065418.892 [CBIT](INFO): Clearing failed state for component NAL9602
2023-06-06T15:30:18.892Z,1686065418.892 [NAL9602] No Fault, FailCount= 1
2023-06-06T15:30:48.369Z,1686065448.369 [NAL9602](INFO): Powering up NAL9602
2023-06-06T15:30:59.273Z,1686065459.273 [NAL9602](INFO): NAL9602 initialized
2023-06-06T15:31:38.875Z,1686065498.875 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-06-06T15:31:38.875Z,1686065498.875 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:31:38.914Z,1686065498.914 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:31:39.327Z,1686065499.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:31:39.327Z,1686065499.327 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-06-06T15:33:39.266Z,1686065619.266 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T15:33:39.266Z,1686065619.266 [Default:CheckIn:C.Wait] Stopped
2023-06-06T15:33:39.266Z,1686065619.266 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T15:33:39.266Z,1686065619.266 [Default:CheckIn:D] Running Loop=1
2023-06-06T15:33:39.681Z,1686065619.681 [Default:CheckIn:D] Stopped
2023-06-06T15:33:39.681Z,1686065619.681 [Default:CheckIn:E] Running Loop=1
2023-06-06T15:33:40.078Z,1686065620.078 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.684952 min 
2023-06-06T15:33:40.078Z,1686065620.078 [Default:CheckIn:E] Stopped
2023-06-06T15:33:40.078Z,1686065620.078 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T15:33:40.078Z,1686065620.078 [Default:CheckIn] Stopped
2023-06-06T15:33:40.078Z,1686065620.078 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T15:33:40.078Z,1686065620.078 [Default:CheckIn](INFO): Running loop #2
2023-06-06T15:33:40.078Z,1686065620.078 [Default:CheckIn] Running Loop=2
2023-06-06T15:33:40.079Z,1686065620.079 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T15:33:40.079Z,1686065620.079 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T15:34:03.492Z,1686065643.492 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:34:18.440Z,1686065658.440 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:34:33.795Z,1686065673.795 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:34:39.897Z,1686065679.897 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-06-06T15:34:39.897Z,1686065679.897 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:34:39.908Z,1686065679.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:34:40.282Z,1686065680.282 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:34:40.282Z,1686065680.282 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-06-06T15:34:49.548Z,1686065689.548 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:35:05.304Z,1686065705.304 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:35:19.548Z,1686065719.548 [CommandExec](IMPORTANT): got command failComponent
2023-06-06T15:35:19.548Z,1686065719.548 [CommandExec](IMPORTANT): Failed components:
2023-06-06T15:35:19.548Z,1686065719.548 [CommandExec](IMPORTANT): No failed Components.
2023-06-06T15:35:20.252Z,1686065720.252 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:35:35.604Z,1686065735.604 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:35:51.361Z,1686065751.361 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:36:06.308Z,1686065766.308 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T15:36:26.509Z,1686065786.509 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2023-06-06T15:36:26.515Z,1686065786.515 [BPC1](INFO): Calculating totals. Valid battery stick count: 45. Valid reserve battery stick count: 5.
2023-06-06T15:36:26.519Z,1686065786.519 [BPC1](INFO): Received data from all battery sticks.
2023-06-06T15:36:29.932Z,1686065789.932 [CommandExec](IMPORTANT): got command failComponent
2023-06-06T15:36:29.932Z,1686065789.932 [CommandExec](IMPORTANT): Failed components:
2023-06-06T15:36:29.932Z,1686065789.932 [CommandExec](IMPORTANT): No failed Components.
2023-06-06T15:36:33.892Z,1686065793.892 [CommandExec](IMPORTANT): got command gfscan
2023-06-06T15:36:34.215Z,1686065794.215 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T15:36:37.033Z,1686065797.033 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-06-06T15:36:45.134Z,1686065805.134 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001280
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002401
CHAN A3 (5V): -0.001384
CHAN B0 (3.3V): -0.000310
CHAN B1 (3.15aV): 0.000048
CHAN B2 (3.15bV): 0.000042
CHAN B3 (GND): -0.000797
OPEN: -0.000474
Full Scale: +/- 1 mA
2023-06-06T15:37:40.884Z,1686065860.884 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-06-06T15:37:40.884Z,1686065860.884 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:37:40.894Z,1686065860.894 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:37:41.274Z,1686065861.274 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:37:41.274Z,1686065861.274 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-06-06T15:38:15.228Z,1686065895.228 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:38:40.249Z,1686065920.249 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-06T15:33:40.1Z
2023-06-06T15:38:40.250Z,1686065920.250 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T15:38:40.250Z,1686065920.250 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T15:38:46.352Z,1686065926.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:39:17.484Z,1686065957.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:39:48.640Z,1686065988.640 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:40:19.764Z,1686066019.764 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:40:22.776Z,1686066022.776 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.779495
2023-06-06T15:40:41.872Z,1686066041.872 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-06-06T15:40:41.872Z,1686066041.872 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:40:41.882Z,1686066041.882 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:40:42.300Z,1686066042.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:40:42.300Z,1686066042.300 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-06-06T15:40:53.929Z,1686066053.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:41:02.041Z,1686066062.041 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-06-06T15:41:02.041Z,1686066062.041 [NAL9602] Data Fault, FailCount= 2
2023-06-06T15:41:02.041Z,1686066062.041 [NAL9602](ERROR): Data Fault
2023-06-06T15:41:02.087Z,1686066062.087 [CBIT](ERROR): Data Fault in component: NAL9602
2023-06-06T15:41:02.449Z,1686066062.449 [NAL9602](INFO): Powering down
2023-06-06T15:41:03.279Z,1686066063.279 [CBIT](INFO): Clearing failed state for component NAL9602
2023-06-06T15:41:03.279Z,1686066063.279 [NAL9602] No Fault, FailCount= 2
2023-06-06T15:41:25.051Z,1686066085.051 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:41:32.749Z,1686066092.749 [NAL9602](INFO): Powering up NAL9602
2023-06-06T15:41:43.664Z,1686066103.664 [NAL9602](INFO): NAL9602 initialized
2023-06-06T15:41:56.176Z,1686066116.176 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:42:27.324Z,1686066147.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:42:58.448Z,1686066178.448 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:43:29.572Z,1686066209.572 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:43:42.858Z,1686066222.858 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-06-06T15:43:42.858Z,1686066222.858 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:43:42.872Z,1686066222.872 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:43:43.258Z,1686066223.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:43:43.258Z,1686066223.258 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-06-06T15:44:00.712Z,1686066240.712 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:44:31.844Z,1686066271.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:44:50.335Z,1686066290.335 [NAL9602](INFO): SBD MO Status=2, MOMSN=43354, MT Status=2, MTMSN=0
2023-06-06T15:44:50.336Z,1686066290.336 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-06-06T15:45:02.968Z,1686066302.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-06-06T15:45:07.342Z,1686066307.342 [NAL9602](INFO): SBD MO Status=2, MOMSN=43354, MT Status=2, MTMSN=0
2023-06-06T15:45:07.342Z,1686066307.342 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-06-06T15:45:11.188Z,1686066311.188 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003470
2023-06-06T15:45:18.363Z,1686066318.363 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230606T151925/Courier0007.lzma
2023-06-06T15:45:19.365Z,1686066319.365 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0007.lzma.bak
2023-06-06T15:45:19.365Z,1686066319.365 [DataOverHttps](INFO): SBD MOMSN=18399515
2023-06-06T15:45:23.172Z,1686066323.172 [NAL9602](INFO): SBD MO Status=2, MOMSN=43354, MT Status=2, MTMSN=0
2023-06-06T15:45:23.173Z,1686066323.173 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-06-06T15:45:37.095Z,1686066337.095 [DataOverHttps](INFO): Sending 431 bytes from file Logs/20230606T151925/Express0008.lzma
2023-06-06T15:45:38.097Z,1686066338.097 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0008.lzma.bak
2023-06-06T15:45:38.097Z,1686066338.097 [DataOverHttps](INFO): SBD MOMSN=18399517
2023-06-06T15:45:39.378Z,1686066339.378 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T15:45:39.378Z,1686066339.378 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T15:45:39.378Z,1686066339.378 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T15:46:44.044Z,1686066404.044 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-06-06T15:46:44.044Z,1686066404.044 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:46:44.075Z,1686066404.075 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:46:44.461Z,1686066404.461 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:46:44.461Z,1686066404.461 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-06-06T15:49:18.361Z,1686066558.361 [DataOverHttps](IMPORTANT): SBD MTMSN=20230606T154917
2023-06-06T15:49:25.571Z,1686066565.571 [DataOverHttps](INFO): Received command: ibit
2023-06-06T15:49:25.640Z,1686066565.640 [CommandExec](IMPORTANT): got command ibit
2023-06-06T15:49:25.646Z,1686066565.646 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-06-06T15:49:25.646Z,1686066565.646 [IBIT](IMPORTANT): Beginning control surface checks.
2023-06-06T15:49:25.650Z,1686066565.650 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T15:49:28.532Z,1686066568.532 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-06-06T15:49:36.539Z,1686066576.539 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-06-06T15:49:36.941Z,1686066576.941 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T15:49:39.788Z,1686066579.788 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-06-06T15:49:45.011Z,1686066585.011 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-06-06T15:49:45.011Z,1686066585.011 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-06T15:49:45.072Z,1686066585.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-06T15:49:45.488Z,1686066585.488 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-06T15:49:45.488Z,1686066585.488 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-06-06T15:49:47.920Z,1686066587.920 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-06-06T15:49:48.352Z,1686066588.352 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T15:49:51.156Z,1686066591.156 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-06-06T15:49:59.233Z,1686066599.233 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002468
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002180
CHAN A3 (5V): -0.001622
CHAN B0 (3.3V): -0.000177
CHAN B1 (3.15aV): 0.000227
CHAN B2 (3.15bV): -0.000522
CHAN B3 (GND): -0.000724
OPEN: -0.000689
Full Scale: +/- 1 mA
2023-06-06T15:50:31.923Z,1686066631.923 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155030.00,A,3648.16598,N,12147.25844,W,3.032,337.38,060623,,,A*75
2023-06-06T15:50:31.926Z,1686066631.926 [NAL9602](INFO): GPS fix at 20230606T155030: (36.802766, -121.787641)
2023-06-06T15:50:31.943Z,1686066631.943 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802765 Longitude: -121.787643
2023-06-06T15:50:32.354Z,1686066632.354 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.770000
2023-06-06T15:50:32.354Z,1686066632.354 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-06-06T15:50:32.354Z,1686066632.354 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-06-06T15:50:32.762Z,1686066632.762 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-06-06T15:50:32.762Z,1686066632.762 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-06-06T15:50:32.765Z,1686066632.765 [IBIT](IMPORTANT): Pressure:9.953397 PSI
2023-06-06T15:50:32.765Z,1686066632.765 [IBIT](IMPORTANT): Humidity:2.508851 %
2023-06-06T15:50:33.152Z,1686066633.152 [IBIT](IMPORTANT): Vehicle Pitch:-2.107988 degrees
2023-06-06T15:50:33.153Z,1686066633.153 [IBIT](IMPORTANT): Vehicle  Roll:-3.878391 degrees
2023-06-06T15:50:33.153Z,1686066633.153 [IBIT](IMPORTANT): Vehicle  Heading:130.409286 degrees
2023-06-06T15:50:33.560Z,1686066633.560 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-06-06T15:50:33.561Z,1686066633.561 [IBIT](IMPORTANT): buoyancyNeutral: 233.035828 cc
2023-06-06T15:50:33.561Z,1686066633.561 [IBIT](IMPORTANT): massDefault: -1.031312 cm
2023-06-06T15:50:33.561Z,1686066633.561 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-06-06T15:50:33.562Z,1686066633.562 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-06-06T15:50:33.562Z,1686066633.562 [IBIT](IMPORTANT): IBIT PASSED
2023-06-06T15:50:40.014Z,1686066640.014 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T15:50:40.015Z,1686066640.015 [Default:CheckIn:C.Wait] Stopped
2023-06-06T15:50:40.015Z,1686066640.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T15:50:40.015Z,1686066640.015 [Default:CheckIn:D] Running Loop=1
2023-06-06T15:50:40.430Z,1686066640.430 [Default:CheckIn:D] Stopped
2023-06-06T15:50:40.430Z,1686066640.430 [Default:CheckIn:E] Running Loop=1
2023-06-06T15:50:40.818Z,1686066640.818 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.697439 min 
2023-06-06T15:50:40.819Z,1686066640.819 [Default:CheckIn:E] Stopped
2023-06-06T15:50:40.819Z,1686066640.819 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T15:50:40.819Z,1686066640.819 [Default:CheckIn] Stopped
2023-06-06T15:50:40.819Z,1686066640.819 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T15:50:40.819Z,1686066640.819 [Default:CheckIn](INFO): Running loop #3
2023-06-06T15:50:40.819Z,1686066640.819 [Default:CheckIn] Running Loop=3
2023-06-06T15:50:40.819Z,1686066640.819 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T15:50:40.819Z,1686066640.819 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T15:50:42.836Z,1686066642.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155041.00,A,3648.16218,N,12147.24989,W,2.371,184.95,060623,,,A*75
2023-06-06T15:50:42.838Z,1686066642.838 [NAL9602](INFO): GPS fix at 20230606T155041: (36.802703, -121.787498)
2023-06-06T15:50:42.852Z,1686066642.852 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T15:50:42.852Z,1686066642.852 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T15:50:52.188Z,1686066652.188 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230606T151925/Courier0010.lzma
2023-06-06T15:50:53.189Z,1686066653.189 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0010.lzma.bak
2023-06-06T15:50:53.189Z,1686066653.189 [DataOverHttps](INFO): SBD MOMSN=18399533
2023-06-06T15:51:11.580Z,1686066671.580 [DataOverHttps](INFO): Sending 967 bytes from file Logs/20230606T151925/Express0011.lzma
2023-06-06T15:51:12.581Z,1686066672.581 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0011.lzma.bak
2023-06-06T15:51:12.581Z,1686066672.581 [DataOverHttps](INFO): SBD MOMSN=18399536
2023-06-06T15:51:15.308Z,1686066675.308 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T15:51:28.383Z,1686066688.383 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230606T151925/Express0014.lzma
2023-06-06T15:51:29.385Z,1686066689.385 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0014.lzma.bak
2023-06-06T15:51:29.385Z,1686066689.385 [DataOverHttps](INFO): SBD MOMSN=18399565
2023-06-06T15:51:30.679Z,1686066690.679 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T15:51:30.679Z,1686066690.679 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T15:51:30.679Z,1686066690.679 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T15:56:31.243Z,1686066991.243 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T15:56:31.243Z,1686066991.243 [Default:CheckIn:C.Wait] Stopped
2023-06-06T15:56:31.243Z,1686066991.243 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T15:56:31.243Z,1686066991.243 [Default:CheckIn:D] Running Loop=1
2023-06-06T15:56:31.657Z,1686066991.657 [Default:CheckIn:D] Stopped
2023-06-06T15:56:31.657Z,1686066991.657 [Default:CheckIn:E] Running Loop=1
2023-06-06T15:56:32.080Z,1686066992.080 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.551217 min 
2023-06-06T15:56:32.081Z,1686066992.081 [Default:CheckIn:E] Stopped
2023-06-06T15:56:32.081Z,1686066992.081 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T15:56:32.081Z,1686066992.081 [Default:CheckIn] Stopped
2023-06-06T15:56:32.081Z,1686066992.081 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T15:56:32.081Z,1686066992.081 [Default:CheckIn](INFO): Running loop #4
2023-06-06T15:56:32.081Z,1686066992.081 [Default:CheckIn] Running Loop=4
2023-06-06T15:56:32.081Z,1686066992.081 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T15:56:32.081Z,1686066992.081 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T15:56:34.061Z,1686066994.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155633.00,A,3648.14341,N,12147.22878,W,0.097,45.73,060623,,,A*4D
2023-06-06T15:56:34.064Z,1686066994.064 [NAL9602](INFO): GPS fix at 20230606T155633: (36.802390, -121.787146)
2023-06-06T15:56:34.074Z,1686066994.074 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T15:56:34.074Z,1686066994.074 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T15:56:41.459Z,1686067001.459 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230606T151925/Courier0016.lzma
2023-06-06T15:56:42.461Z,1686067002.461 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0016.lzma.bak
2023-06-06T15:56:42.461Z,1686067002.461 [DataOverHttps](INFO): SBD MOMSN=18399572
2023-06-06T15:56:47.814Z,1686067007.814 [NAL9602](INFO): SBD MO Status=1, MOMSN=43354, MT Status=0, MTMSN=0
2023-06-06T15:56:47.814Z,1686067007.814 [NAL9602](INFO): No messages in MT queue
2023-06-06T15:57:01.135Z,1686067021.135 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230606T151925/Express0017.lzma
2023-06-06T15:57:02.137Z,1686067022.137 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0017.lzma.bak
2023-06-06T15:57:02.137Z,1686067022.137 [DataOverHttps](INFO): SBD MOMSN=18399575
2023-06-06T15:57:03.209Z,1686067023.209 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T15:57:03.209Z,1686067023.209 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T15:57:03.210Z,1686067023.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T15:57:18.553Z,1686067038.553 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:00:13.004Z,1686067213.004 [DataOverHttps](IMPORTANT): SBD MTMSN=20230606T160012
2023-06-06T16:00:22.315Z,1686067222.315 [DataOverHttps](INFO): Received command: ibit
2023-06-06T16:00:22.320Z,1686067222.320 [CommandExec](IMPORTANT): got command ibit
2023-06-06T16:00:22.469Z,1686067222.469 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-06-06T16:00:22.469Z,1686067222.469 [IBIT](IMPORTANT): Beginning control surface checks.
2023-06-06T16:00:22.472Z,1686067222.472 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T16:00:24.034Z,1686067224.034 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160023.00,A,3648.14356,N,12147.22892,W,0.058,45.73,060623,,,A*4D
2023-06-06T16:00:24.037Z,1686067224.037 [NAL9602](INFO): GPS fix at 20230606T160023: (36.802393, -121.787149)
2023-06-06T16:00:25.388Z,1686067225.388 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-06-06T16:00:33.347Z,1686067233.347 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-06-06T16:00:33.748Z,1686067233.748 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T16:00:36.574Z,1686067236.574 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-06-06T16:00:44.719Z,1686067244.719 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-06-06T16:00:45.172Z,1686067245.172 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T16:00:48.037Z,1686067248.037 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-06-06T16:00:56.087Z,1686067256.087 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002678
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.001626
CHAN A3 (5V): -0.001462
CHAN B0 (3.3V): -0.000274
CHAN B1 (3.15aV): -0.000078
CHAN B2 (3.15bV): 0.000111
CHAN B3 (GND): -0.000266
OPEN: -0.000389
Full Scale: +/- 1 mA
2023-06-06T16:02:13.181Z,1686067333.181 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2023-06-06T16:02:13.591Z,1686067333.591 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.690001
2023-06-06T16:02:13.595Z,1686067333.595 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-06-06T16:02:13.596Z,1686067333.596 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-06-06T16:02:13.985Z,1686067333.985 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-06-06T16:02:13.986Z,1686067333.986 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-06-06T16:02:13.986Z,1686067333.986 [IBIT](IMPORTANT): Pressure:9.962729 PSI
2023-06-06T16:02:13.986Z,1686067333.986 [IBIT](IMPORTANT): Humidity:2.460017 %
2023-06-06T16:02:14.389Z,1686067334.389 [IBIT](IMPORTANT): Vehicle Pitch:-0.563186 degrees
2023-06-06T16:02:14.389Z,1686067334.389 [IBIT](IMPORTANT): Vehicle  Roll:-3.355004 degrees
2023-06-06T16:02:14.389Z,1686067334.389 [IBIT](IMPORTANT): Vehicle  Heading:20.304401 degrees
2023-06-06T16:02:14.788Z,1686067334.788 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-06-06T16:02:14.788Z,1686067334.788 [IBIT](IMPORTANT): buoyancyNeutral: 233.035828 cc
2023-06-06T16:02:14.789Z,1686067334.789 [IBIT](IMPORTANT): massDefault: -1.031312 cm
2023-06-06T16:02:14.789Z,1686067334.789 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-06-06T16:02:14.789Z,1686067334.789 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-06-06T16:02:14.789Z,1686067334.789 [IBIT](IMPORTANT): IBIT FAILED
2023-06-06T16:02:15.198Z,1686067335.198 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:02:15.199Z,1686067335.199 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:02:15.199Z,1686067335.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:02:15.199Z,1686067335.199 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:02:15.596Z,1686067335.596 [Default:CheckIn:D] Stopped
2023-06-06T16:02:15.596Z,1686067335.596 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:02:16.034Z,1686067336.034 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.283533 min 
2023-06-06T16:02:16.034Z,1686067336.034 [Default:CheckIn:E] Stopped
2023-06-06T16:02:16.034Z,1686067336.034 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:02:16.034Z,1686067336.034 [Default:CheckIn] Stopped
2023-06-06T16:02:16.034Z,1686067336.034 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:02:16.034Z,1686067336.034 [Default:CheckIn](INFO): Running loop #5
2023-06-06T16:02:16.034Z,1686067336.034 [Default:CheckIn] Running Loop=5
2023-06-06T16:02:16.034Z,1686067336.034 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:02:16.035Z,1686067336.035 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:02:30.199Z,1686067350.199 [NAL9602](INFO): SBD MO Status=1, MOMSN=43355, MT Status=0, MTMSN=0
2023-06-06T16:02:30.199Z,1686067350.199 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:02:31.414Z,1686067351.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160230.00,A,3648.14455,N,12147.22973,W,0.117,45.73,060623,,,A*4D
2023-06-06T16:02:31.417Z,1686067351.417 [NAL9602](INFO): GPS fix at 20230606T160230: (36.802409, -121.787162)
2023-06-06T16:02:31.427Z,1686067351.427 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:02:31.427Z,1686067351.427 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:02:38.623Z,1686067358.623 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230606T151925/Courier0019.lzma
2023-06-06T16:02:39.625Z,1686067359.625 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0019.lzma.bak
2023-06-06T16:02:39.625Z,1686067359.625 [DataOverHttps](INFO): SBD MOMSN=18399587
2023-06-06T16:02:57.992Z,1686067377.992 [DataOverHttps](INFO): Sending 770 bytes from file Logs/20230606T151925/Express0020.lzma
2023-06-06T16:02:58.993Z,1686067378.993 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0020.lzma.bak
2023-06-06T16:02:58.993Z,1686067378.993 [DataOverHttps](INFO): SBD MOMSN=18399591
2023-06-06T16:03:03.392Z,1686067383.392 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:03:14.655Z,1686067394.655 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230606T151925/Express0021.lzma
2023-06-06T16:03:15.657Z,1686067395.657 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0021.lzma.bak
2023-06-06T16:03:15.657Z,1686067395.657 [DataOverHttps](INFO): SBD MOMSN=18399620
2023-06-06T16:03:16.734Z,1686067396.734 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:03:16.734Z,1686067396.734 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:03:16.734Z,1686067396.734 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:04:58.025Z,1686067498.025 [DataOverHttps](IMPORTANT): SBD MTMSN=20230606T160457
2023-06-06T16:05:05.524Z,1686067505.524 [DataOverHttps](INFO): Received command: ibit
2023-06-06T16:05:05.532Z,1686067505.532 [CommandExec](IMPORTANT): got command ibit
2023-06-06T16:05:05.538Z,1686067505.538 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-06-06T16:05:05.538Z,1686067505.538 [IBIT](IMPORTANT): Beginning control surface checks.
2023-06-06T16:05:05.541Z,1686067505.541 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T16:05:07.131Z,1686067507.131 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160506.00,A,3648.14533,N,12147.22821,W,0.175,45.73,060623,,,A*4C
2023-06-06T16:05:07.133Z,1686067507.133 [NAL9602](INFO): GPS fix at 20230606T160506: (36.802422, -121.787137)
2023-06-06T16:05:08.448Z,1686067508.448 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-06-06T16:05:16.466Z,1686067516.466 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002934
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002236
CHAN A3 (5V): -0.001783
CHAN B0 (3.3V): -0.000192
CHAN B1 (3.15aV): -0.000232
CHAN B2 (3.15bV): -0.000535
CHAN B3 (GND): 0.000091
OPEN: -0.000438
Full Scale: +/- 1 mA
2023-06-06T16:05:22.078Z,1686067522.078 [NAL9602](INFO): SBD MO Status=1, MOMSN=43356, MT Status=0, MTMSN=0
2023-06-06T16:05:22.078Z,1686067522.078 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:05:23.290Z,1686067523.290 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160522.00,A,3648.14559,N,12147.22796,W,0.117,45.73,060623,,,A*41
2023-06-06T16:05:23.292Z,1686067523.292 [NAL9602](INFO): GPS fix at 20230606T160522: (36.802427, -121.787133)
2023-06-06T16:05:26.113Z,1686067526.113 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160525.00,A,3648.14569,N,12147.22815,W,0.117,45.73,060623,,,A*41
2023-06-06T16:05:26.115Z,1686067526.115 [NAL9602](INFO): GPS fix at 20230606T160525: (36.802428, -121.787136)
2023-06-06T16:05:28.941Z,1686067528.941 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160528.00,A,3648.14575,N,12147.22821,W,0.078,45.73,060623,,,A*4E
2023-06-06T16:05:28.943Z,1686067528.943 [NAL9602](INFO): GPS fix at 20230606T160528: (36.802429, -121.787137)
2023-06-06T16:05:31.770Z,1686067531.770 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160531.00,A,3648.14578,N,12147.22826,W,0.039,45.73,060623,,,A*49
2023-06-06T16:05:31.773Z,1686067531.773 [NAL9602](INFO): GPS fix at 20230606T160531: (36.802430, -121.787138)
2023-06-06T16:05:35.002Z,1686067535.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160534.00,A,3648.14584,N,12147.22814,W,0.136,45.73,060623,,,A*40
2023-06-06T16:05:35.005Z,1686067535.005 [NAL9602](INFO): GPS fix at 20230606T160534: (36.802431, -121.787136)
2023-06-06T16:05:35.901Z,1686067535.901 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.799875
2023-06-06T16:05:35.901Z,1686067535.901 [IBIT](FAULT): Control surface position failure.
2023-06-06T16:05:37.831Z,1686067537.831 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160537.00,A,3648.14591,N,12147.22812,W,0.039,45.73,060623,,,A*4F
2023-06-06T16:05:37.833Z,1686067537.833 [NAL9602](INFO): GPS fix at 20230606T160537: (36.802432, -121.787135)
2023-06-06T16:05:41.068Z,1686067541.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160540.00,A,3648.14596,N,12147.22811,W,0.039,45.73,060623,,,A*4B
2023-06-06T16:05:41.070Z,1686067541.070 [NAL9602](INFO): GPS fix at 20230606T160540: (36.802433, -121.787135)
2023-06-06T16:05:43.895Z,1686067543.895 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160543.00,A,3648.14596,N,12147.22813,W,0.156,45.73,060623,,,A*42
2023-06-06T16:05:43.897Z,1686067543.897 [NAL9602](INFO): GPS fix at 20230606T160543: (36.802433, -121.787136)
2023-06-06T16:05:47.126Z,1686067547.126 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160546.00,A,3648.14596,N,12147.22815,W,0.019,45.73,060623,,,A*4B
2023-06-06T16:05:47.138Z,1686067547.138 [NAL9602](INFO): GPS fix at 20230606T160546: (36.802433, -121.787136)
2023-06-06T16:05:49.951Z,1686067549.951 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160549.00,A,3648.14597,N,12147.22814,W,0.117,45.73,060623,,,A*4B
2023-06-06T16:05:49.953Z,1686067549.953 [NAL9602](INFO): GPS fix at 20230606T160549: (36.802433, -121.787136)
2023-06-06T16:05:51.599Z,1686067551.599 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.802433 Longitude: -121.787132
2023-06-06T16:05:51.983Z,1686067551.983 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-06-06T16:05:51.984Z,1686067551.984 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-06-06T16:05:51.984Z,1686067551.984 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-06-06T16:05:52.394Z,1686067552.394 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-06-06T16:05:52.394Z,1686067552.394 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-06-06T16:05:52.394Z,1686067552.394 [IBIT](IMPORTANT): Pressure:9.991543 PSI
2023-06-06T16:05:52.395Z,1686067552.395 [IBIT](IMPORTANT): Humidity:2.429496 %
2023-06-06T16:05:52.801Z,1686067552.801 [IBIT](IMPORTANT): Vehicle Pitch:-0.575012 degrees
2023-06-06T16:05:52.802Z,1686067552.802 [IBIT](IMPORTANT): Vehicle  Roll:-3.350651 degrees
2023-06-06T16:05:52.802Z,1686067552.802 [IBIT](IMPORTANT): Vehicle  Heading:20.305223 degrees
2023-06-06T16:05:53.208Z,1686067553.208 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-06-06T16:05:53.208Z,1686067553.208 [IBIT](IMPORTANT): buoyancyNeutral: 233.035828 cc
2023-06-06T16:05:53.208Z,1686067553.208 [IBIT](IMPORTANT): massDefault: -1.031312 cm
2023-06-06T16:05:53.208Z,1686067553.208 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-06-06T16:05:53.209Z,1686067553.209 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-06-06T16:05:53.209Z,1686067553.209 [IBIT](IMPORTANT): IBIT FAILED
2023-06-06T16:06:22.267Z,1686067582.267 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:08:17.418Z,1686067697.418 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:08:17.418Z,1686067697.418 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:08:17.418Z,1686067697.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:08:17.418Z,1686067697.418 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:08:17.822Z,1686067697.822 [Default:CheckIn:D] Stopped
2023-06-06T16:08:17.822Z,1686067697.822 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:08:18.249Z,1686067698.249 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.320638 min 
2023-06-06T16:08:18.249Z,1686067698.249 [Default:CheckIn:E] Stopped
2023-06-06T16:08:18.249Z,1686067698.249 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:08:18.249Z,1686067698.249 [Default:CheckIn] Stopped
2023-06-06T16:08:18.249Z,1686067698.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:08:18.250Z,1686067698.250 [Default:CheckIn](INFO): Running loop #6
2023-06-06T16:08:18.250Z,1686067698.250 [Default:CheckIn] Running Loop=6
2023-06-06T16:08:18.250Z,1686067698.250 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:08:18.250Z,1686067698.250 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:08:20.238Z,1686067700.238 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160819.00,A,3648.14582,N,12147.22870,W,0.039,45.73,060623,,,A*48
2023-06-06T16:08:20.240Z,1686067700.240 [NAL9602](INFO): GPS fix at 20230606T160819: (36.802430, -121.787145)
2023-06-06T16:08:20.304Z,1686067700.304 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:08:20.304Z,1686067700.304 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:08:29.987Z,1686067709.987 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230606T151925/Courier0024.lzma
2023-06-06T16:08:30.989Z,1686067710.989 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0024.lzma.bak
2023-06-06T16:08:30.989Z,1686067710.989 [DataOverHttps](INFO): SBD MOMSN=18399633
2023-06-06T16:08:46.769Z,1686067726.769 [DataOverHttps](INFO): Sending 834 bytes from file Logs/20230606T151925/Express0026.lzma
2023-06-06T16:08:47.769Z,1686067727.769 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0026.lzma.bak
2023-06-06T16:08:47.769Z,1686067727.769 [DataOverHttps](INFO): SBD MOMSN=18399636
2023-06-06T16:08:51.402Z,1686067731.402 [NAL9602](INFO): SBD MO Status=1, MOMSN=43357, MT Status=0, MTMSN=0
2023-06-06T16:08:51.402Z,1686067731.402 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:09:06.347Z,1686067746.347 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230606T151925/Express0029.lzma
2023-06-06T16:09:07.349Z,1686067747.349 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0029.lzma.bak
2023-06-06T16:09:07.349Z,1686067747.349 [DataOverHttps](INFO): SBD MOMSN=18399664
2023-06-06T16:09:08.438Z,1686067748.438 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:09:08.438Z,1686067748.438 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:09:08.438Z,1686067748.438 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:09:22.136Z,1686067762.136 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:11:17.592Z,1686067877.592 [DataOverHttps](IMPORTANT): SBD MTMSN=20230606T161116
2023-06-06T16:11:25.047Z,1686067885.047 [DataOverHttps](INFO): Received command: ibit
2023-06-06T16:11:25.080Z,1686067885.080 [CommandExec](IMPORTANT): got command ibit
2023-06-06T16:11:25.385Z,1686067885.385 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-06-06T16:11:25.385Z,1686067885.385 [IBIT](IMPORTANT): Beginning control surface checks.
2023-06-06T16:11:25.388Z,1686067885.388 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-06T16:11:26.974Z,1686067886.974 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161126.00,A,3648.14695,N,12147.23167,W,0.117,45.73,060623,,,A*4A
2023-06-06T16:11:26.977Z,1686067886.977 [NAL9602](INFO): GPS fix at 20230606T161126: (36.802449, -121.787194)
2023-06-06T16:11:28.292Z,1686067888.292 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-06-06T16:11:36.285Z,1686067896.285 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002645
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002447
CHAN A3 (5V): -0.001268
CHAN B0 (3.3V): 0.000246
CHAN B1 (3.15aV): -0.000414
CHAN B2 (3.15bV): -0.000011
CHAN B3 (GND): -0.000183
OPEN: -0.000230
Full Scale: +/- 1 mA
2023-06-06T16:11:50.410Z,1686067910.410 [NAL9602](INFO): SBD MO Status=2, MOMSN=43358, MT Status=2, MTMSN=0
2023-06-06T16:11:50.410Z,1686067910.410 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-06-06T16:11:56.141Z,1686067916.141 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.797676
2023-06-06T16:11:56.141Z,1686067916.141 [IBIT](FAULT): Control surface position failure.
2023-06-06T16:12:11.836Z,1686067931.836 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802448 Longitude: -121.787193
2023-06-06T16:12:12.237Z,1686067932.237 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-06-06T16:12:12.238Z,1686067932.238 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-06-06T16:12:12.238Z,1686067932.238 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-06-06T16:12:12.649Z,1686067932.649 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-06-06T16:12:12.649Z,1686067932.649 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-06-06T16:12:12.650Z,1686067932.650 [IBIT](IMPORTANT): Pressure:10.005625 PSI
2023-06-06T16:12:12.650Z,1686067932.650 [IBIT](IMPORTANT): Humidity:2.405079 %
2023-06-06T16:12:13.074Z,1686067933.074 [IBIT](IMPORTANT): Vehicle Pitch:-0.579661 degrees
2023-06-06T16:12:13.074Z,1686067933.074 [IBIT](IMPORTANT): Vehicle  Roll:-3.349498 degrees
2023-06-06T16:12:13.074Z,1686067933.074 [IBIT](IMPORTANT): Vehicle  Heading:20.290958 degrees
2023-06-06T16:12:13.452Z,1686067933.452 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-06-06T16:12:13.452Z,1686067933.452 [IBIT](IMPORTANT): buoyancyNeutral: 233.035828 cc
2023-06-06T16:12:13.452Z,1686067933.452 [IBIT](IMPORTANT): massDefault: -1.031312 cm
2023-06-06T16:12:13.453Z,1686067933.453 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-06-06T16:12:13.453Z,1686067933.453 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-06-06T16:12:13.453Z,1686067933.453 [IBIT](IMPORTANT): IBIT FAILED
2023-06-06T16:12:15.054Z,1686067935.054 [NAL9602](INFO): SBD MO Status=1, MOMSN=43358, MT Status=0, MTMSN=0
2023-06-06T16:12:15.054Z,1686067935.054 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:12:45.804Z,1686067965.804 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:14:09.102Z,1686068049.102 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:14:09.102Z,1686068049.102 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:14:09.102Z,1686068049.102 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:14:09.102Z,1686068049.102 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:14:09.506Z,1686068049.506 [Default:CheckIn:D] Stopped
2023-06-06T16:14:09.506Z,1686068049.506 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:14:09.911Z,1686068049.911 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.182039 min 
2023-06-06T16:14:09.912Z,1686068049.912 [Default:CheckIn:E] Stopped
2023-06-06T16:14:09.912Z,1686068049.912 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:14:09.912Z,1686068049.912 [Default:CheckIn] Stopped
2023-06-06T16:14:09.912Z,1686068049.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:14:09.912Z,1686068049.912 [Default:CheckIn](INFO): Running loop #7
2023-06-06T16:14:09.912Z,1686068049.912 [Default:CheckIn] Running Loop=7
2023-06-06T16:14:09.912Z,1686068049.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:14:09.912Z,1686068049.912 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:14:11.918Z,1686068051.918 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161411.00,A,3648.14551,N,12147.22950,W,0.039,45.73,060623,,,A*40
2023-06-06T16:14:11.921Z,1686068051.921 [NAL9602](INFO): GPS fix at 20230606T161411: (36.802425, -121.787158)
2023-06-06T16:14:11.947Z,1686068051.947 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:14:11.948Z,1686068051.948 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:14:21.123Z,1686068061.123 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230606T151925/Courier0031.lzma
2023-06-06T16:14:22.125Z,1686068062.125 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0031.lzma.bak
2023-06-06T16:14:22.125Z,1686068062.125 [DataOverHttps](INFO): SBD MOMSN=18399673
2023-06-06T16:14:27.702Z,1686068067.702 [NAL9602](INFO): SBD MO Status=1, MOMSN=43359, MT Status=0, MTMSN=0
2023-06-06T16:14:27.702Z,1686068067.702 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:14:39.565Z,1686068079.565 [DataOverHttps](INFO): Sending 837 bytes from file Logs/20230606T151925/Express0032.lzma
2023-06-06T16:14:40.565Z,1686068080.565 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0032.lzma.bak
2023-06-06T16:14:40.565Z,1686068080.565 [DataOverHttps](INFO): SBD MOMSN=18399678
2023-06-06T16:14:58.448Z,1686068098.448 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:14:59.199Z,1686068099.199 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20230606T151925/Express0035.lzma
2023-06-06T16:15:00.201Z,1686068100.201 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0035.lzma.bak
2023-06-06T16:15:00.201Z,1686068100.201 [DataOverHttps](INFO): SBD MOMSN=18399706
2023-06-06T16:15:01.298Z,1686068101.298 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:15:01.298Z,1686068101.298 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:15:01.298Z,1686068101.298 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:20:01.882Z,1686068401.882 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:20:01.882Z,1686068401.882 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:20:01.883Z,1686068401.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:20:01.883Z,1686068401.883 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:20:02.299Z,1686068402.299 [Default:CheckIn:D] Stopped
2023-06-06T16:20:02.299Z,1686068402.299 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:20:02.694Z,1686068402.694 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.061910 min 
2023-06-06T16:20:02.694Z,1686068402.694 [Default:CheckIn:E] Stopped
2023-06-06T16:20:02.695Z,1686068402.695 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:20:02.695Z,1686068402.695 [Default:CheckIn] Stopped
2023-06-06T16:20:02.695Z,1686068402.695 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:20:02.695Z,1686068402.695 [Default:CheckIn](INFO): Running loop #8
2023-06-06T16:20:02.695Z,1686068402.695 [Default:CheckIn] Running Loop=8
2023-06-06T16:20:02.695Z,1686068402.695 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:20:02.695Z,1686068402.695 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:20:04.702Z,1686068404.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162004.00,A,3648.14407,N,12147.22778,W,0.039,45.73,060623,,,A*45
2023-06-06T16:20:04.705Z,1686068404.705 [NAL9602](INFO): GPS fix at 20230606T162004: (36.802401, -121.787130)
2023-06-06T16:20:04.715Z,1686068404.715 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:20:04.715Z,1686068404.715 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:20:12.543Z,1686068412.543 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230606T151925/Courier0037.lzma
2023-06-06T16:20:13.545Z,1686068413.545 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0037.lzma.bak
2023-06-06T16:20:13.545Z,1686068413.545 [DataOverHttps](INFO): SBD MOMSN=18399756
2023-06-06T16:20:18.870Z,1686068418.870 [NAL9602](INFO): SBD MO Status=1, MOMSN=43360, MT Status=0, MTMSN=0
2023-06-06T16:20:18.870Z,1686068418.870 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:20:32.491Z,1686068432.491 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230606T151925/Express0038.lzma
2023-06-06T16:20:33.493Z,1686068433.493 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0038.lzma.bak
2023-06-06T16:20:33.493Z,1686068433.493 [DataOverHttps](INFO): SBD MOMSN=18399759
2023-06-06T16:20:34.653Z,1686068434.653 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:20:34.653Z,1686068434.653 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:20:34.653Z,1686068434.653 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:20:49.598Z,1686068449.598 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:25:35.253Z,1686068735.253 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:25:35.253Z,1686068735.253 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:25:35.254Z,1686068735.254 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:25:35.254Z,1686068735.254 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:25:35.662Z,1686068735.662 [Default:CheckIn:D] Stopped
2023-06-06T16:25:35.662Z,1686068735.662 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:25:36.063Z,1686068736.063 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.617965 min 
2023-06-06T16:25:36.063Z,1686068736.063 [Default:CheckIn:E] Stopped
2023-06-06T16:25:36.063Z,1686068736.063 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:25:36.063Z,1686068736.063 [Default:CheckIn] Stopped
2023-06-06T16:25:36.064Z,1686068736.064 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:25:36.064Z,1686068736.064 [Default:CheckIn](INFO): Running loop #9
2023-06-06T16:25:36.064Z,1686068736.064 [Default:CheckIn] Running Loop=9
2023-06-06T16:25:36.064Z,1686068736.064 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:25:36.064Z,1686068736.064 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:25:38.083Z,1686068738.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162537.00,A,3648.15010,N,12147.22235,W,0.019,36.78,060623,,,A*42
2023-06-06T16:25:38.086Z,1686068738.086 [NAL9602](INFO): GPS fix at 20230606T162537: (36.802502, -121.787039)
2023-06-06T16:25:38.096Z,1686068738.096 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:25:38.096Z,1686068738.096 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:25:45.406Z,1686068745.406 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230606T151925/Courier0040.lzma
2023-06-06T16:25:46.405Z,1686068746.405 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0040.lzma.bak
2023-06-06T16:25:46.405Z,1686068746.405 [DataOverHttps](INFO): SBD MOMSN=18399766
2023-06-06T16:26:02.195Z,1686068762.195 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230606T151925/Express0041.lzma
2023-06-06T16:26:02.722Z,1686068762.722 [NAL9602](INFO): SBD MO Status=1, MOMSN=43361, MT Status=0, MTMSN=0
2023-06-06T16:26:02.722Z,1686068762.722 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:26:03.197Z,1686068763.197 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0041.lzma.bak
2023-06-06T16:26:03.197Z,1686068763.197 [DataOverHttps](INFO): SBD MOMSN=18399769
2023-06-06T16:26:04.341Z,1686068764.341 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:26:04.341Z,1686068764.341 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:26:04.341Z,1686068764.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:26:33.424Z,1686068793.424 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:31:04.953Z,1686069064.953 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:31:04.953Z,1686069064.953 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:31:04.953Z,1686069064.953 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:31:04.953Z,1686069064.953 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:31:05.356Z,1686069065.356 [Default:CheckIn:D] Stopped
2023-06-06T16:31:05.356Z,1686069065.356 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:31:05.753Z,1686069065.753 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.112866 min 
2023-06-06T16:31:05.753Z,1686069065.753 [Default:CheckIn:E] Stopped
2023-06-06T16:31:05.753Z,1686069065.753 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:31:05.753Z,1686069065.753 [Default:CheckIn] Stopped
2023-06-06T16:31:05.753Z,1686069065.753 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:31:05.754Z,1686069065.754 [Default:CheckIn](INFO): Running loop #10
2023-06-06T16:31:05.754Z,1686069065.754 [Default:CheckIn] Running Loop=10
2023-06-06T16:31:05.754Z,1686069065.754 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:31:05.754Z,1686069065.754 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:31:07.774Z,1686069067.774 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163107.00,A,3648.15065,N,12147.22224,W,0.136,36.78,060623,,,A*4A
2023-06-06T16:31:07.777Z,1686069067.777 [NAL9602](INFO): GPS fix at 20230606T163107: (36.802511, -121.787037)
2023-06-06T16:31:07.787Z,1686069067.787 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:31:07.787Z,1686069067.787 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:31:15.003Z,1686069075.003 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230606T151925/Courier0043.lzma
2023-06-06T16:31:16.005Z,1686069076.005 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0043.lzma.bak
2023-06-06T16:31:16.005Z,1686069076.005 [DataOverHttps](INFO): SBD MOMSN=18399776
2023-06-06T16:31:31.735Z,1686069091.735 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230606T151925/Express0044.lzma
2023-06-06T16:31:32.737Z,1686069092.737 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0044.lzma.bak
2023-06-06T16:31:32.737Z,1686069092.737 [DataOverHttps](INFO): SBD MOMSN=18399779
2023-06-06T16:31:34.038Z,1686069094.038 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:31:34.038Z,1686069094.038 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:31:34.038Z,1686069094.038 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:31:52.204Z,1686069112.204 [NAL9602](INFO): SBD MO Status=1, MOMSN=43362, MT Status=0, MTMSN=0
2023-06-06T16:31:52.204Z,1686069112.204 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:32:22.912Z,1686069142.912 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:36:34.674Z,1686069394.674 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:36:34.674Z,1686069394.674 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:36:34.674Z,1686069394.674 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:36:34.674Z,1686069394.674 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:36:35.028Z,1686069395.028 [Default:CheckIn:D] Stopped
2023-06-06T16:36:35.028Z,1686069395.028 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:36:35.447Z,1686069395.447 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.607397 min 
2023-06-06T16:36:35.447Z,1686069395.447 [Default:CheckIn:E] Stopped
2023-06-06T16:36:35.447Z,1686069395.447 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:36:35.447Z,1686069395.447 [Default:CheckIn] Stopped
2023-06-06T16:36:35.447Z,1686069395.447 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:36:35.447Z,1686069395.447 [Default:CheckIn](INFO): Running loop #11
2023-06-06T16:36:35.447Z,1686069395.447 [Default:CheckIn] Running Loop=11
2023-06-06T16:36:35.448Z,1686069395.448 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:36:35.448Z,1686069395.448 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:36:37.438Z,1686069397.438 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163636.00,A,3648.40638,N,12147.10774,W,4.179,5.78,060623,,,A*7F
2023-06-06T16:36:37.441Z,1686069397.441 [NAL9602](INFO): GPS fix at 20230606T163636: (36.806773, -121.785129)
2023-06-06T16:36:37.451Z,1686069397.451 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:36:37.451Z,1686069397.451 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:36:44.595Z,1686069404.595 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230606T151925/Courier0046.lzma
2023-06-06T16:36:45.597Z,1686069405.597 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0046.lzma.bak
2023-06-06T16:36:45.597Z,1686069405.597 [DataOverHttps](INFO): SBD MOMSN=18399786
2023-06-06T16:37:03.742Z,1686069423.742 [NAL9602](INFO): SBD MO Status=1, MOMSN=43363, MT Status=0, MTMSN=0
2023-06-06T16:37:03.742Z,1686069423.742 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:37:04.039Z,1686069424.039 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230606T151925/Express0047.lzma
2023-06-06T16:37:05.041Z,1686069425.041 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0047.lzma.bak
2023-06-06T16:37:05.041Z,1686069425.041 [DataOverHttps](INFO): SBD MOMSN=18399789
2023-06-06T16:37:06.178Z,1686069426.178 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:37:06.178Z,1686069426.178 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:37:06.178Z,1686069426.178 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:37:34.477Z,1686069454.477 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:41:19.477Z,1686069679.477 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:41:32.405Z,1686069692.405 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:41:45.333Z,1686069705.333 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:41:58.264Z,1686069718.264 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:42:06.774Z,1686069726.774 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:42:06.774Z,1686069726.774 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:42:06.774Z,1686069726.774 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:42:06.774Z,1686069726.774 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:42:07.166Z,1686069727.166 [Default:CheckIn:D] Stopped
2023-06-06T16:42:07.166Z,1686069727.166 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:42:07.570Z,1686069727.570 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.143034 min 
2023-06-06T16:42:07.570Z,1686069727.570 [Default:CheckIn:E] Stopped
2023-06-06T16:42:07.570Z,1686069727.570 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:42:07.570Z,1686069727.570 [Default:CheckIn] Stopped
2023-06-06T16:42:07.570Z,1686069727.570 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:42:07.571Z,1686069727.571 [Default:CheckIn](INFO): Running loop #12
2023-06-06T16:42:07.571Z,1686069727.571 [Default:CheckIn] Running Loop=12
2023-06-06T16:42:07.571Z,1686069727.571 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:42:07.571Z,1686069727.571 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:42:09.582Z,1686069729.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164208.00,A,3648.32347,N,12147.51516,W,10.361,231.99,060623,,,D*4B
2023-06-06T16:42:09.584Z,1686069729.584 [NAL9602](INFO): GPS fix at 20230606T164208: (36.805391, -121.791919)
2023-06-06T16:42:09.611Z,1686069729.611 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:42:09.611Z,1686069729.611 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:42:11.190Z,1686069731.190 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:42:16.503Z,1686069736.503 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230606T151925/Courier0049.lzma
2023-06-06T16:42:17.505Z,1686069737.505 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0049.lzma.bak
2023-06-06T16:42:17.505Z,1686069737.505 [DataOverHttps](INFO): SBD MOMSN=18399796
2023-06-06T16:42:21.302Z,1686069741.302 [NAL9602](INFO): SBD MO Status=1, MOMSN=43364, MT Status=0, MTMSN=0
2023-06-06T16:42:21.302Z,1686069741.302 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:42:23.733Z,1686069743.733 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:42:35.864Z,1686069755.864 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230606T151925/Express0050.lzma
2023-06-06T16:42:36.277Z,1686069756.277 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:42:36.861Z,1686069756.861 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0050.lzma.bak
2023-06-06T16:42:36.861Z,1686069756.861 [DataOverHttps](INFO): SBD MOMSN=18399799
2023-06-06T16:42:37.913Z,1686069757.913 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:42:37.913Z,1686069757.913 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:42:37.913Z,1686069757.913 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:42:48.807Z,1686069768.807 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:42:52.040Z,1686069772.040 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:43:01.729Z,1686069781.729 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:43:14.253Z,1686069794.253 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:43:27.181Z,1686069807.181 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:43:40.110Z,1686069820.110 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-06-06T16:46:09.993Z,1686069969.993 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-06T16:47:38.490Z,1686070058.490 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:47:38.490Z,1686070058.490 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:47:38.490Z,1686070058.490 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:47:38.490Z,1686070058.490 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:47:38.894Z,1686070058.894 [Default:CheckIn:D] Stopped
2023-06-06T16:47:38.894Z,1686070058.894 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:47:39.302Z,1686070059.302 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.671842 min 
2023-06-06T16:47:39.302Z,1686070059.302 [Default:CheckIn:E] Stopped
2023-06-06T16:47:39.302Z,1686070059.302 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:47:39.302Z,1686070059.302 [Default:CheckIn] Stopped
2023-06-06T16:47:39.302Z,1686070059.302 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:47:39.303Z,1686070059.303 [Default:CheckIn](INFO): Running loop #13
2023-06-06T16:47:39.303Z,1686070059.303 [Default:CheckIn] Running Loop=13
2023-06-06T16:47:39.303Z,1686070059.303 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:47:39.303Z,1686070059.303 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:47:41.324Z,1686070061.324 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164740.00,A,3648.04159,N,12149.18819,W,17.242,264.11,060623,,,D*4C
2023-06-06T16:47:41.327Z,1686070061.327 [NAL9602](INFO): GPS fix at 20230606T164740: (36.800693, -121.819803)
2023-06-06T16:47:41.337Z,1686070061.337 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:47:41.337Z,1686070061.337 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:47:49.083Z,1686070069.083 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230606T151925/Courier0052.lzma
2023-06-06T16:47:50.085Z,1686070070.085 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0052.lzma.bak
2023-06-06T16:47:50.085Z,1686070070.085 [DataOverHttps](INFO): SBD MOMSN=18399808
2023-06-06T16:47:56.690Z,1686070076.690 [NAL9602](INFO): SBD MO Status=1, MOMSN=43365, MT Status=0, MTMSN=0
2023-06-06T16:47:56.690Z,1686070076.690 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:48:06.003Z,1686070086.003 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230606T151925/Express0053.lzma
2023-06-06T16:48:07.005Z,1686070087.005 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0053.lzma.bak
2023-06-06T16:48:07.005Z,1686070087.005 [DataOverHttps](INFO): SBD MOMSN=18399811
2023-06-06T16:48:08.032Z,1686070088.032 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:48:08.032Z,1686070088.032 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:48:08.033Z,1686070088.033 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:48:27.396Z,1686070107.396 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:50:50.817Z,1686070250.817 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:50:57.687Z,1686070257.687 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:51:13.037Z,1686070273.037 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:51:28.389Z,1686070288.389 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:51:43.744Z,1686070303.744 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:51:59.093Z,1686070319.093 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:52:13.640Z,1686070333.640 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:52:28.586Z,1686070348.586 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:52:43.937Z,1686070363.937 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:52:59.747Z,1686070379.747 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-06T16:53:08.656Z,1686070388.656 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:53:08.656Z,1686070388.656 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:53:08.656Z,1686070388.656 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:53:08.656Z,1686070388.656 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:53:09.076Z,1686070389.076 [Default:CheckIn:D] Stopped
2023-06-06T16:53:09.076Z,1686070389.076 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:53:09.458Z,1686070389.458 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.174870 min 
2023-06-06T16:53:09.458Z,1686070389.458 [Default:CheckIn:E] Stopped
2023-06-06T16:53:09.458Z,1686070389.458 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:53:09.458Z,1686070389.458 [Default:CheckIn] Stopped
2023-06-06T16:53:09.458Z,1686070389.458 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:53:09.459Z,1686070389.459 [Default:CheckIn](INFO): Running loop #14
2023-06-06T16:53:09.459Z,1686070389.459 [Default:CheckIn] Running Loop=14
2023-06-06T16:53:09.459Z,1686070389.459 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:53:09.459Z,1686070389.459 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:53:11.471Z,1686070391.471 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165310.00,A,3647.82589,N,12150.89376,W,4.996,258.94,060623,,,A*71
2023-06-06T16:53:11.473Z,1686070391.473 [NAL9602](INFO): GPS fix at 20230606T165310: (36.797098, -121.848229)
2023-06-06T16:53:11.483Z,1686070391.483 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:53:11.483Z,1686070391.483 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:54:01.923Z,1686070441.923 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230606T151925/Courier0055.lzma
2023-06-06T16:54:02.925Z,1686070442.925 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0055.lzma.bak
2023-06-06T16:54:02.925Z,1686070442.925 [DataOverHttps](INFO): SBD MOMSN=18399818
2023-06-06T16:54:19.727Z,1686070459.727 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230606T151925/Express0056.lzma
2023-06-06T16:54:20.729Z,1686070460.729 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Express0056.lzma.bak
2023-06-06T16:54:20.729Z,1686070460.729 [DataOverHttps](INFO): SBD MOMSN=18399821
2023-06-06T16:54:21.797Z,1686070461.797 [Default:CheckIn:Read_Iridium] Stopped
2023-06-06T16:54:21.797Z,1686070461.797 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-06T16:54:21.797Z,1686070461.797 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-06T16:54:29.862Z,1686070469.862 [NAL9602](INFO): SBD MO Status=1, MOMSN=43366, MT Status=0, MTMSN=0
2023-06-06T16:54:29.862Z,1686070469.862 [NAL9602](INFO): No messages in MT queue
2023-06-06T16:55:00.568Z,1686070500.568 [NAL9602](INFO): Not Powering down - fast GPS
2023-06-06T16:59:22.370Z,1686070762.370 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-06T16:59:22.370Z,1686070762.370 [Default:CheckIn:C.Wait] Stopped
2023-06-06T16:59:22.370Z,1686070762.370 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-06T16:59:22.370Z,1686070762.370 [Default:CheckIn:D] Running Loop=1
2023-06-06T16:59:22.772Z,1686070762.772 [Default:CheckIn:D] Stopped
2023-06-06T16:59:22.772Z,1686070762.772 [Default:CheckIn:E] Running Loop=1
2023-06-06T16:59:23.190Z,1686070763.190 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.403133 min 
2023-06-06T16:59:23.190Z,1686070763.190 [Default:CheckIn:E] Stopped
2023-06-06T16:59:23.190Z,1686070763.190 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-06T16:59:23.190Z,1686070763.190 [Default:CheckIn] Stopped
2023-06-06T16:59:23.190Z,1686070763.190 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-06T16:59:23.190Z,1686070763.190 [Default:CheckIn](INFO): Running loop #15
2023-06-06T16:59:23.190Z,1686070763.190 [Default:CheckIn] Running Loop=15
2023-06-06T16:59:23.191Z,1686070763.191 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-06T16:59:23.191Z,1686070763.191 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-06T16:59:25.209Z,1686070765.209 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165924.00,A,3647.84353,N,12150.88382,W,0.991,85.13,060623,,,D*4A
2023-06-06T16:59:25.211Z,1686070765.211 [NAL9602](INFO): GPS fix at 20230606T165924: (36.797392, -121.848064)
2023-06-06T16:59:25.221Z,1686070765.221 [Default:CheckIn:Read_GPS] Stopped
2023-06-06T16:59:25.221Z,1686070765.221 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-06T16:59:35.251Z,1686070775.251 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230606T151925/Courier0058.lzma
2023-06-06T16:59:36.253Z,1686070776.253 [DataOverHttps](INFO): Moved sent file to Logs/20230606T151925/Courier0058.lzma.bak
2023-06-06T16:59:36.254Z,1686070776.254 [DataOverHttps](IMPORTANT): SBD MOMSN=18399830, MTMSN=20230606T165935