2024-06-03T15:11:31.869Z,1717427491.869 [Supervisor](DEBUG): Initializing supervisor.
2024-06-03T15:11:31.873Z,1717427491.873 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-06-03T15:11:31.874Z,1717427491.874 [SyncHandler](INFO): Protected caller Thread ID is 837
2024-06-03T15:11:31.874Z,1717427491.874 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-06-03T15:11:31.875Z,1717427491.875 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-06-03T15:11:31.876Z,1717427491.876 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2024-06-03T15:11:31.879Z,1717427491.879 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-06-03T15:11:31.897Z,1717427491.897 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-06-03T15:11:31.898Z,1717427491.898 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-06-03T15:11:31.898Z,1717427491.898 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2024-06-03T15:11:31.902Z,1717427491.902 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-06-03T15:11:31.903Z,1717427491.903 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-06-03T15:11:31.904Z,1717427491.904 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2024-06-03T15:11:31.906Z,1717427491.906 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-06-03T15:11:31.907Z,1717427491.907 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-06-03T15:11:31.907Z,1717427491.907 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2024-06-03T15:11:31.911Z,1717427491.911 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-06-03T15:11:31.911Z,1717427491.911 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-06-03T15:11:31.915Z,1717427491.915 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-06-03T15:11:32.206Z,1717427492.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-06-03T15:11:32.208Z,1717427492.208 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-06-03T15:11:32.301Z,1717427492.301 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-06-03T15:11:32.848Z,1717427492.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-06-03T15:11:32.850Z,1717427492.850 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-06-03T15:11:33.210Z,1717427493.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-06-03T15:11:33.211Z,1717427493.211 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-06-03T15:11:33.318Z,1717427493.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-06-03T15:11:33.320Z,1717427493.320 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-06-03T15:11:33.737Z,1717427493.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-06-03T15:11:33.739Z,1717427493.739 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-06-03T15:11:33.967Z,1717427493.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-06-03T15:11:33.969Z,1717427493.969 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-06-03T15:11:34.508Z,1717427494.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-06-03T15:11:34.510Z,1717427494.510 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-06-03T15:11:34.657Z,1717427494.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-06-03T15:11:34.659Z,1717427494.659 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-06-03T15:11:34.750Z,1717427494.750 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-06-03T15:11:36.073Z,1717427496.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-06-03T15:11:36.074Z,1717427496.074 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-06-03T15:11:36.471Z,1717427496.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-06-03T15:11:36.471Z,1717427496.471 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-06-03T15:11:36.676Z,1717427496.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-06-03T15:11:36.677Z,1717427496.677 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-06-03T15:11:36.801Z,1717427496.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-06-03T15:11:36.802Z,1717427496.802 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-06-03T15:11:37.040Z,1717427497.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-06-03T15:11:37.041Z,1717427497.041 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-06-03T15:11:37.303Z,1717427497.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-06-03T15:11:37.305Z,1717427497.305 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-06-03T15:11:37.307Z,1717427497.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-06-03T15:11:37.411Z,1717427497.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-06-03T15:11:37.508Z,1717427497.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-06-03T15:11:37.622Z,1717427497.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-06-03T15:11:37.717Z,1717427497.717 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-06-03T15:11:37.831Z,1717427497.831 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-06-03T15:11:37.935Z,1717427497.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-06-03T15:11:38.059Z,1717427498.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-06-03T15:11:38.238Z,1717427498.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-06-03T15:11:38.389Z,1717427498.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-06-03T15:11:38.511Z,1717427498.511 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-06-03T15:11:38.784Z,1717427498.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-06-03T15:11:38.785Z,1717427498.785 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-06-03T15:11:38.797Z,1717427498.797 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-06-03T15:11:38.868Z,1717427498.868 [VerticalControl](DEBUG): Construct VerticalControl.
2024-06-03T15:11:38.928Z,1717427498.928 [VerticalControl] Loaded
2024-06-03T15:11:38.928Z,1717427498.928 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-06-03T15:11:38.931Z,1717427498.931 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-06-03T15:11:38.978Z,1717427498.978 [HorizontalControl] Loaded
2024-06-03T15:11:38.978Z,1717427498.978 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-06-03T15:11:38.980Z,1717427498.980 [SpeedControl](DEBUG): Construct SpeedControl.
2024-06-03T15:11:38.984Z,1717427498.984 [SpeedControl] Loaded
2024-06-03T15:11:38.984Z,1717427498.984 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-06-03T15:11:38.987Z,1717427498.987 [LoopControl](DEBUG): Construct LoopControl.
2024-06-03T15:11:38.987Z,1717427498.987 [LoopControl] Loaded
2024-06-03T15:11:38.988Z,1717427498.988 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-06-03T15:11:38.988Z,1717427498.988 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-06-03T15:11:38.990Z,1717427498.990 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-06-03T15:11:39.006Z,1717427499.006 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-06-03T15:11:39.006Z,1717427499.006 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-06-03T15:11:39.219Z,1717427499.219 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-06-03T15:11:39.220Z,1717427499.220 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-06-03T15:11:39.405Z,1717427499.405 [BuoyancyServo] Loaded
2024-06-03T15:11:39.405Z,1717427499.405 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-06-03T15:11:39.427Z,1717427499.427 [ElevatorServo] Loaded
2024-06-03T15:11:39.427Z,1717427499.427 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-06-03T15:11:39.447Z,1717427499.447 [MassServo] Loaded
2024-06-03T15:11:39.448Z,1717427499.448 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-06-03T15:11:39.468Z,1717427499.468 [RudderServo] Loaded
2024-06-03T15:11:39.468Z,1717427499.468 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-06-03T15:11:39.484Z,1717427499.484 [ThrusterHE] Loaded
2024-06-03T15:11:39.485Z,1717427499.485 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-06-03T15:11:39.485Z,1717427499.485 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-06-03T15:11:39.486Z,1717427499.486 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-06-03T15:11:39.622Z,1717427499.622 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-06-03T15:11:39.622Z,1717427499.622 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-06-03T15:11:39.636Z,1717427499.636 [NavChart] Loaded
2024-06-03T15:11:39.636Z,1717427499.636 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-06-03T15:11:39.641Z,1717427499.641 [UniversalFixResidualReporter] Loaded
2024-06-03T15:11:39.642Z,1717427499.642 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-06-03T15:11:39.642Z,1717427499.642 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-06-03T15:11:39.643Z,1717427499.643 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-06-03T15:11:39.726Z,1717427499.726 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-06-03T15:11:39.726Z,1717427499.726 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-06-03T15:11:40.481Z,1717427500.481 [AHRS_M2] Loaded
2024-06-03T15:11:40.481Z,1717427500.481 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-06-03T15:11:41.300Z,1717427501.300 [BPC1] Loaded
2024-06-03T15:11:41.300Z,1717427501.300 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-06-03T15:11:41.441Z,1717427501.441 [DataOverHttps] Loaded
2024-06-03T15:11:41.441Z,1717427501.441 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-06-03T15:11:41.442Z,1717427501.442 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-06-03T15:11:41.443Z,1717427501.443 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923
2024-06-03T15:11:41.466Z,1717427501.466 [Depth_Keller] Loaded
2024-06-03T15:11:41.466Z,1717427501.466 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-06-03T15:11:41.481Z,1717427501.481 [DropWeight] Loaded
2024-06-03T15:11:41.509Z,1717427501.509 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-06-03T15:11:41.694Z,1717427501.694 [NAL9602] Loaded
2024-06-03T15:11:41.695Z,1717427501.695 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-06-03T15:11:41.724Z,1717427501.724 [Onboard] Loaded
2024-06-03T15:11:41.725Z,1717427501.725 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-06-03T15:11:41.726Z,1717427501.726 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-06-03T15:11:41.726Z,1717427501.726 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924
2024-06-03T15:11:41.733Z,1717427501.733 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2024-06-03T15:11:41.747Z,1717427501.747 [PowerOnly] Loaded
2024-06-03T15:11:41.747Z,1717427501.747 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-06-03T15:11:41.771Z,1717427501.771 [Power24vConverter] Loaded
2024-06-03T15:11:41.771Z,1717427501.771 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-06-03T15:11:41.802Z,1717427501.802 [Radio_Surface] Loaded
2024-06-03T15:11:41.802Z,1717427501.802 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-06-03T15:11:41.804Z,1717427501.804 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-06-03T15:11:41.804Z,1717427501.804 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925
2024-06-03T15:11:42.080Z,1717427502.080 [DAT] Loaded
2024-06-03T15:11:42.081Z,1717427502.081 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-06-03T15:11:42.082Z,1717427502.082 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C14E0
2024-06-03T15:11:42.082Z,1717427502.082 [DAT ThreadHandler](INFO): Protected caller Thread ID is 926
2024-06-03T15:11:42.083Z,1717427502.083 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-06-03T15:11:42.083Z,1717427502.083 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-06-03T15:11:42.175Z,1717427502.175 [DepthRateCalculator] Loaded
2024-06-03T15:11:42.175Z,1717427502.175 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-06-03T15:11:42.180Z,1717427502.180 [PitchRateCalculator] Loaded
2024-06-03T15:11:42.180Z,1717427502.180 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-06-03T15:11:42.189Z,1717427502.189 [SpeedCalculator] Loaded
2024-06-03T15:11:42.189Z,1717427502.189 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-06-03T15:11:42.193Z,1717427502.193 [YawRateCalculator] Loaded
2024-06-03T15:11:42.194Z,1717427502.194 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-06-03T15:11:42.213Z,1717427502.213 [ElevatorOffsetCalculator] Loaded
2024-06-03T15:11:42.213Z,1717427502.213 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-06-03T15:11:42.213Z,1717427502.213 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-06-03T15:11:42.214Z,1717427502.214 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-06-03T15:11:42.402Z,1717427502.402 [CANONSampler] Loaded
2024-06-03T15:11:42.403Z,1717427502.403 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-06-03T15:11:42.467Z,1717427502.467 [CTD_Seabird] Loaded
2024-06-03T15:11:42.468Z,1717427502.468 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-06-03T15:11:42.469Z,1717427502.469 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-06-03T15:11:42.469Z,1717427502.469 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2024-06-03T15:11:42.489Z,1717427502.489 [PAR_Licor] Loaded
2024-06-03T15:11:42.490Z,1717427502.490 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-06-03T15:11:42.534Z,1717427502.534 [WetLabsBB2FL] Loaded
2024-06-03T15:11:42.534Z,1717427502.534 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-06-03T15:11:42.535Z,1717427502.535 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0
2024-06-03T15:11:42.535Z,1717427502.535 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2024-06-03T15:11:42.536Z,1717427502.536 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-06-03T15:11:42.537Z,1717427502.537 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-06-03T15:11:42.580Z,1717427502.580 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-06-03T15:11:42.580Z,1717427502.580 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-06-03T15:11:42.979Z,1717427502.979 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-06-03T15:11:42.981Z,1717427502.981 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-06-03T15:11:43.171Z,1717427503.171 [SBIT](DEBUG): Construct Startup Built In Test.
2024-06-03T15:11:43.180Z,1717427503.180 [SBIT] Loaded
2024-06-03T15:11:43.180Z,1717427503.180 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-06-03T15:11:43.183Z,1717427503.183 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-06-03T15:11:43.196Z,1717427503.196 [IBIT] Loaded
2024-06-03T15:11:43.196Z,1717427503.196 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-06-03T15:11:43.202Z,1717427503.202 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-06-03T15:11:43.308Z,1717427503.308 [CBIT] Loaded
2024-06-03T15:11:43.308Z,1717427503.308 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-06-03T15:11:43.308Z,1717427503.308 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-06-03T15:11:43.309Z,1717427503.309 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-06-03T15:11:43.451Z,1717427503.451 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-06-03T15:11:43.457Z,1717427503.457 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-06-03T15:11:43.460Z,1717427503.460 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-06-03T15:11:43.472Z,1717427503.472 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-06-03T15:11:43.473Z,1717427503.473 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-06-03T15:11:43.473Z,1717427503.473 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2024-06-03T15:11:43.478Z,1717427503.478 [Supervisor](INFO): Main Thread ID is 830
2024-06-03T15:11:43.478Z,1717427503.478 [Supervisor](DEBUG): Running supervisor.
2024-06-03T15:11:43.479Z,1717427503.479 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2024-06-03T15:11:43.479Z,1717427503.479 [CommandExec](INFO): Initializing the command executive.
2024-06-03T15:11:43.481Z,1717427503.481 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2024-06-03T15:11:43.483Z,1717427503.483 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2024-06-03T15:11:43.483Z,1717427503.483 [controlThread](DEBUG): Initializing ControlThread
2024-06-03T15:11:43.484Z,1717427503.484 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-06-03T15:11:43.486Z,1717427503.486 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-06-03T15:11:43.486Z,1717427503.486 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-06-03T15:11:43.487Z,1717427503.487 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-06-03T15:11:43.489Z,1717427503.489 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-06-03T15:11:43.489Z,1717427503.489 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-06-03T15:11:43.495Z,1717427503.495 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-06-03T15:11:43.495Z,1717427503.495 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-06-03T15:11:43.495Z,1717427503.495 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-06-03T15:11:43.496Z,1717427503.496 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-06-03T15:11:43.496Z,1717427503.496 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-06-03T15:11:43.501Z,1717427503.501 [SBIT](INFO): Initialize SBIT Component.
2024-06-03T15:11:43.502Z,1717427503.502 [SBIT](IMPORTANT): git: 2024-05-22-4-g7f87fec24
2024-06-03T15:11:43.502Z,1717427503.502 [SBIT](INFO): git hash: 7f87fec24fb6035f396fafcb25281721c3938346
2024-06-03T15:11:43.502Z,1717427503.502 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-06-03T15:11:43.503Z,1717427503.503 [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
2024-06-03T15:11:43.504Z,1717427503.504 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2024-06-03T15:11:43.505Z,1717427503.505 [IBIT](INFO): Initialize IBIT Component.
2024-06-03T15:11:43.506Z,1717427503.506 [CBIT](DEBUG): Initialize CBIT Component.
2024-06-03T15:11:43.507Z,1717427503.507 [logger ThreadHandler](INFO): Handler Thread ID is 933
2024-06-03T15:11:43.517Z,1717427503.517 [CBIT](DEBUG): Initialized mux pins.
2024-06-03T15:11:43.517Z,1717427503.517 [CBIT](DEBUG): Initializing the watchdog timer.
2024-06-03T15:11:43.525Z,1717427503.525 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2024-06-03T15:11:43.527Z,1717427503.527 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-06-03T15:11:43.537Z,1717427503.537 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2024-06-03T15:11:43.542Z,1717427503.542 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-06-03T15:11:43.542Z,1717427503.542 [CBIT](DEBUG): Initializing heartbeat.
2024-06-03T15:11:43.559Z,1717427503.559 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2024-06-03T15:11:43.570Z,1717427503.570 [DAT ThreadHandler](INFO): Handler Thread ID is 937
2024-06-03T15:11:43.571Z,1717427503.571 [DAT](INFO): Powering up
2024-06-03T15:11:43.571Z,1717427503.571 [DAT](DEBUG): Initializing DAT.
2024-06-03T15:11:43.573Z,1717427503.573 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2024-06-03T15:11:43.575Z,1717427503.575 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-06-03T15:11:43.578Z,1717427503.578 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2024-06-03T15:11:43.582Z,1717427503.582 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2024-06-03T15:11:43.583Z,1717427503.583 [WetLabsBB2FL](INFO): Powering up
2024-06-03T15:11:43.591Z,1717427503.591 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-06-03T15:11:43.591Z,1717427503.591 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-06-03T15:11:43.591Z,1717427503.591 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-06-03T15:11:43.591Z,1717427503.591 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-06-03T15:11:43.591Z,1717427503.591 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-06-03T15:11:43.591Z,1717427503.591 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-06-03T15:11:43.591Z,1717427503.591 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-06-03T15:11:43.592Z,1717427503.592 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-06-03T15:11:43.613Z,1717427503.613 [CBIT](DEBUG): Deactivating GF circuits.
2024-06-03T15:11:43.613Z,1717427503.613 [CBIT](DEBUG): Deactivating emergency mode.
2024-06-03T15:11:43.653Z,1717427503.653 [CBIT](DEBUG): Backplane powered.
2024-06-03T15:11:43.654Z,1717427503.654 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-06-03T15:11:43.654Z,1717427503.654 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-06-03T15:11:43.655Z,1717427503.655 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-06-03T15:11:43.661Z,1717427503.661 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-06-03T15:11:43.711Z,1717427503.711 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-06-03T15:11:43.715Z,1717427503.715 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-03T15:11:43.759Z,1717427503.759 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-06-03T15:11:43.760Z,1717427503.760 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-06-03T15:11:43.760Z,1717427503.760 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-06-03T15:11:43.762Z,1717427503.762 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-06-03T15:11:43.961Z,1717427503.961 [Radio_Surface](INFO): Powering up
2024-06-03T15:11:44.065Z,1717427504.065 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-06-03T15:11:44.071Z,1717427504.071 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-06-03T15:11:44.072Z,1717427504.072 [Default:A.Wait](DEBUG): Construct Wait.
2024-06-03T15:11:44.091Z,1717427504.091 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-03T15:11:44.112Z,1717427504.112 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-06-03T15:11:44.138Z,1717427504.138 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-06-03T15:11:44.152Z,1717427504.152 [Default:E.Execute](DEBUG): Construct Execute.
2024-06-03T15:11:44.163Z,1717427504.163 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-06-03T15:11:44.167Z,1717427504.167 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,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,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-06-03T15:11:44.190Z,1717427504.190 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-06-03T15:11:44.357Z,1717427504.357 [PowerOnly](INFO): Powering up loadControl
2024-06-03T15:11:44.374Z,1717427504.374 [Power24vConverter](INFO): Powering up.
2024-06-03T15:11:44.374Z,1717427504.374 [CANONSampler](INFO): Powering down
2024-06-03T15:11:44.431Z,1717427504.431 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-06-03T15:11:44.437Z,1717427504.437 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-06-03T15:11:44.438Z,1717427504.438 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-03T15:11:44.445Z,1717427504.445 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-03T15:11:44.446Z,1717427504.446 [MassServo](DEBUG): Initializing EZServoServo.
2024-06-03T15:11:44.453Z,1717427504.453 [MassServo](DEBUG): Initializing MassServo.
2024-06-03T15:11:44.454Z,1717427504.454 [RudderServo](DEBUG): Initializing EZServoServo.
2024-06-03T15:11:44.461Z,1717427504.461 [RudderServo](DEBUG): Initializing RudderServo.
2024-06-03T15:11:44.462Z,1717427504.462 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-06-03T15:11:44.469Z,1717427504.469 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-06-03T15:11:45.689Z,1717427505.689 [WetLabsBB2FL](INFO): Powering down
2024-06-03T15:11:50.071Z,1717427510.071 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-06-03T15:11:56.422Z,1717427516.422 [DAT](INFO): DAT read:
2024-06-03T15:11:56.423Z,1717427516.423 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-06-03T15:11:58.186Z,1717427518.186 [DAT](INFO): DAT read: MF Frequency Band
2024-06-03T15:11:58.187Z,1717427518.187 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-06-03T15:11:58.188Z,1717427518.188 [DAT](INFO): DAT read: May 30 2024 23:28:24
2024-06-03T15:11:59.194Z,1717427519.194 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-06-03T15:11:59.195Z,1717427519.195 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-06-03T15:11:59.196Z,1717427519.196 [DAT](INFO): commRate: 1200
2024-06-03T15:12:01.261Z,1717427521.261 [DAT](INFO): entering command mode
2024-06-03T15:12:01.461Z,1717427521.461 [DAT](INFO): DAT read:
2024-06-03T15:12:01.462Z,1717427521.462 [DAT](INFO): DAT read: user:1>
2024-06-03T15:12:01.462Z,1717427521.462 [DAT](INFO): setting verbose to 3
2024-06-03T15:12:01.714Z,1717427521.714 [DAT](INFO): DAT read: user:1>
2024-06-03T15:12:01.715Z,1717427521.715 [DAT](INFO): DAT read: Verbose | 3
2024-06-03T15:12:01.715Z,1717427521.715 [DAT](INFO): set verbose to 3
2024-06-03T15:12:01.715Z,1717427521.715 [DAT](INFO): setting DatVerbose to 27440
2024-06-03T15:12:01.966Z,1717427521.966 [DAT](INFO): DAT read: user:2>
2024-06-03T15:12:01.967Z,1717427521.967 [DAT](INFO): DAT read: DatVerbose | 27440
2024-06-03T15:12:01.967Z,1717427521.967 [DAT](INFO): set DatVerbose to 27440
2024-06-03T15:12:01.967Z,1717427521.967 [DAT](INFO): setting transmit power to 8
2024-06-03T15:12:02.217Z,1717427522.217 [DAT](INFO): DAT read: user:3>
2024-06-03T15:12:02.218Z,1717427522.218 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-06-03T15:12:02.219Z,1717427522.219 [DAT](INFO): set transmit power to 8
2024-06-03T15:12:02.219Z,1717427522.219 [DAT](INFO): setting local address to 8
2024-06-03T15:12:02.472Z,1717427522.472 [DAT](INFO): DAT read: user:4>
2024-06-03T15:12:02.474Z,1717427522.474 [DAT](INFO): DAT read: LocalAddr | 8
2024-06-03T15:12:02.474Z,1717427522.474 [DAT](INFO): set local address to 8
2024-06-03T15:12:02.477Z,1717427522.477 [DAT](INFO): Setting time to: 15:12:2 And date to:6/3/2024
2024-06-03T15:12:02.725Z,1717427522.725 [DAT](INFO): DAT read: user:5>
2024-06-03T15:12:02.726Z,1717427522.726 [DAT](INFO): DAT read: Mon Jun 3, 2024 15:12:02
2024-06-03T15:12:02.727Z,1717427522.727 [DAT](INFO): Local DAT time set to Mon Jun 3, 2024 15:12:02
2024-06-03T15:12:12.055Z,1717427532.055 [NAL9602](INFO): Powering up NAL9602
2024-06-03T15:12:22.963Z,1717427542.963 [NAL9602](INFO): NAL9602 initialized
2024-06-03T15:12:22.988Z,1717427542.988 [SBIT](IMPORTANT): Beginning Startup BIT
2024-06-03T15:12:23.012Z,1717427543.012 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-03T15:12:33.893Z,1717427553.893 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-03T15:12:34.308Z,1717427554.308 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-03T15:12:45.310Z,1717427565.310 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-03T15:12:45.722Z,1717427565.722 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-03T15:12:50.839Z,1717427570.839 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T15:12:56.524Z,1717427576.524 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-03T15:12:56.524Z,1717427576.524 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-06-03T15:13:03.763Z,1717427583.763 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T15:13:16.690Z,1717427596.690 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T15:13:17.156Z,1717427597.156 [SBIT](IMPORTANT): SBIT PASSED
2024-06-03T15:13:17.156Z,1717427597.156 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-06-03T15:13:17.157Z,1717427597.157 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-06-03T15:13:17.157Z,1717427597.157 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=238.004663 cubic_centimeter;
2024-06-03T15:13:17.157Z,1717427597.157 [SBIT](IMPORTANT): VerticalControl.massDefault=4.845744 millimeter;
2024-06-03T15:13:17.157Z,1717427597.157 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;
2024-06-03T15:13:17.158Z,1717427597.158 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;
2024-06-03T15:13:17.531Z,1717427597.531 [MissionManager](IMPORTANT): Started mission Startup
2024-06-03T15:13:17.531Z,1717427597.531 [Startup] Running Loop=1
2024-06-03T15:13:17.531Z,1717427597.531 [Startup](DEBUG): Aggregate::initialize Startup
2024-06-03T15:13:17.532Z,1717427597.532 [Startup:A.GoToSurface] Running Loop=1
2024-06-03T15:13:17.532Z,1717427597.532 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-03T15:13:17.532Z,1717427597.532 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-03T15:13:17.533Z,1717427597.533 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-03T15:13:17.553Z,1717427597.553 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-03T15:13:17.554Z,1717427597.554 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-03T15:13:17.554Z,1717427597.554 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-03T15:13:17.554Z,1717427597.554 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-03T15:13:17.556Z,1717427597.556 [Startup:StartupSatComms] Running Loop=1
2024-06-03T15:13:17.556Z,1717427597.556 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-06-03T15:13:17.556Z,1717427597.556 [Startup:StartupSatComms:A] Running Loop=1
2024-06-03T15:13:18.019Z,1717427598.019 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-06-03T15:13:30.022Z,1717427610.022 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T15:13:42.546Z,1717427622.546 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T15:13:55.474Z,1717427635.474 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T15:14:08.404Z,1717427648.404 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T15:14:12.456Z,1717427652.456 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005556
2024-06-03T15:14:17.958Z,1717427657.958 [Startup:StartupSatComms:A](INFO): Timed out from 2024-06-03T15:13:17.6Z
2024-06-03T15:14:17.958Z,1717427657.958 [Startup:StartupSatComms:A] Stopped
2024-06-03T15:14:17.959Z,1717427657.959 [Startup:StartupSatComms:B] Running Loop=1
2024-06-03T15:14:18.383Z,1717427658.383 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-03T15:14:25.441Z,1717427665.441 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240603T151131/Courier0000.lzma
2024-06-03T15:14:26.443Z,1717427666.443 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0000.lzma.bak
2024-06-03T15:14:26.443Z,1717427666.443 [DataOverHttps](INFO): SBD MOMSN=21111887
2024-06-03T15:14:42.104Z,1717427682.104 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240603T150956/Express0001.lzma
2024-06-03T15:14:43.106Z,1717427683.106 [DataOverHttps](INFO): Moved sent file to Logs/20240603T150956/Express0001.lzma.bak
2024-06-03T15:14:43.107Z,1717427683.107 [DataOverHttps](INFO): SBD MOMSN=21111893
2024-06-03T15:14:43.822Z,1717427683.822 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-06-03T15:14:43.822Z,1717427683.822 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-03T15:14:43.832Z,1717427683.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-03T15:14:44.244Z,1717427684.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-03T15:14:44.244Z,1717427684.244 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-06-03T15:14:58.702Z,1717427698.702 [DataOverHttps](INFO): Sending 709 bytes from file Logs/20240603T151131/Express0001.lzma
2024-06-03T15:14:59.702Z,1717427699.702 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0001.lzma.bak
2024-06-03T15:14:59.703Z,1717427699.703 [DataOverHttps](INFO): SBD MOMSN=21111899
2024-06-03T15:15:00.798Z,1717427700.798 [Startup:StartupSatComms:B] Stopped
2024-06-03T15:15:00.799Z,1717427700.799 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-06-03T15:15:00.799Z,1717427700.799 [Startup:StartupSatComms] Stopped
2024-06-03T15:15:00.799Z,1717427700.799 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-06-03T15:15:00.800Z,1717427700.800 [Startup](INFO): Completed Startup
2024-06-03T15:15:00.800Z,1717427700.800 [MissionManager](INFO): Startup is completed.
2024-06-03T15:15:00.800Z,1717427700.800 [MissionManager](INFO): Uninitializing Mission Startup
2024-06-03T15:15:00.800Z,1717427700.800 [Startup] Stopped
2024-06-03T15:15:00.800Z,1717427700.800 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-06-03T15:15:00.800Z,1717427700.800 [Startup:A.GoToSurface] Stopped
2024-06-03T15:15:00.800Z,1717427700.800 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-03T15:15:01.214Z,1717427701.214 [MissionManager](IMPORTANT): Started mission Default
2024-06-03T15:15:01.214Z,1717427701.214 [Default] Running Loop=1
2024-06-03T15:15:01.214Z,1717427701.214 [Default](DEBUG): Aggregate::initialize Default
2024-06-03T15:15:01.214Z,1717427701.214 [Default:B.GoToSurface] Running Loop=1
2024-06-03T15:15:01.214Z,1717427701.214 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-03T15:15:01.215Z,1717427701.215 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-03T15:15:01.215Z,1717427701.215 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-03T15:15:01.215Z,1717427701.215 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-03T15:15:01.215Z,1717427701.215 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-03T15:15:01.216Z,1717427701.216 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-03T15:15:01.216Z,1717427701.216 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-03T15:15:01.216Z,1717427701.216 [Default:A.Wait] Running Loop=1
2024-06-03T15:15:01.216Z,1717427701.216 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-06-03T15:15:14.513Z,1717427714.513 [Default:A.Wait](INFO): Done Waiting.
2024-06-03T15:15:14.513Z,1717427714.513 [Default:A.Wait] Stopped
2024-06-03T15:15:14.513Z,1717427714.513 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T15:15:14.923Z,1717427714.923 [Default:CheckIn] Running Loop=1
2024-06-03T15:15:14.923Z,1717427714.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T15:15:14.923Z,1717427714.923 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T15:15:15.336Z,1717427715.336 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-06-03T15:17:15.957Z,1717427835.957 [CommandExec](IMPORTANT): got command failComponent
2024-06-03T15:17:15.957Z,1717427835.957 [CommandExec](IMPORTANT): Failed components:
2024-06-03T15:17:15.958Z,1717427835.958 [CommandExec](IMPORTANT): No failed Components.
2024-06-03T15:17:19.186Z,1717427839.186 [CommandExec](IMPORTANT): got command strobe off
2024-06-03T15:17:19.186Z,1717427839.186 [CommandExec](IMPORTANT): Deactivating strobe
2024-06-03T15:17:26.057Z,1717427846.057 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2024-06-03T15:17:26.058Z,1717427846.058 [CommandExec](IMPORTANT): Onboard.Pressure 10.309716 psi
2024-06-03T15:17:26.597Z,1717427846.597 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2024-06-03T15:17:26.600Z,1717427846.600 [BPC1](INFO): Received data from all battery sticks.
2024-06-03T15:17:26.603Z,1717427846.603 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-06-03T15:17:33.680Z,1717427853.680 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2024-06-03T15:17:33.681Z,1717427853.681 [CommandExec](IMPORTANT): Onboard.Humidity 14.473203 %
2024-06-03T15:17:44.785Z,1717427864.785 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-06-03T15:17:44.785Z,1717427864.785 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-03T15:17:44.796Z,1717427864.796 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-03T15:17:45.199Z,1717427865.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-03T15:17:45.199Z,1717427865.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-06-03T15:20:15.131Z,1717428015.131 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-06-03T15:15:14.9Z
2024-06-03T15:20:15.131Z,1717428015.131 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T15:20:15.131Z,1717428015.131 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T15:20:15.548Z,1717428015.548 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-03T15:20:20.502Z,1717428020.502 [CommandExec](IMPORTANT): got command burn on
2024-06-03T15:20:20.502Z,1717428020.502 [CommandExec](IMPORTANT): Activating dropweight wire
2024-06-03T15:20:22.888Z,1717428022.888 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240603T151131/Courier0004.lzma
2024-06-03T15:20:23.890Z,1717428023.890 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0004.lzma.bak
2024-06-03T15:20:23.890Z,1717428023.890 [DataOverHttps](INFO): SBD MOMSN=21111931
2024-06-03T15:20:39.405Z,1717428039.405 [DataOverHttps](INFO): Sending 374 bytes from file Logs/20240603T151131/Express0005.lzma
2024-06-03T15:20:40.406Z,1717428040.406 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0005.lzma.bak
2024-06-03T15:20:40.406Z,1717428040.406 [DataOverHttps](INFO): SBD MOMSN=21111933
2024-06-03T15:20:40.782Z,1717428040.782 [CommandExec](IMPORTANT): got command burn off
2024-06-03T15:20:40.782Z,1717428040.782 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-06-03T15:20:41.478Z,1717428041.478 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T15:20:41.478Z,1717428041.478 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T15:20:41.478Z,1717428041.478 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T15:20:45.944Z,1717428045.944 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-06-03T15:20:45.944Z,1717428045.944 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-03T15:20:45.959Z,1717428045.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-03T15:20:46.375Z,1717428046.375 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-03T15:20:46.375Z,1717428046.375 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-06-03T15:22:25.702Z,1717428145.702 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-06-03T15:22:25.702Z,1717428145.702 [NAL9602] Data Fault, FailCount= 1
2024-06-03T15:22:25.702Z,1717428145.702 [NAL9602](ERROR): Data Fault
2024-06-03T15:22:25.720Z,1717428145.720 [CBIT](ERROR): Data Fault in component: NAL9602
2024-06-03T15:22:26.106Z,1717428146.106 [NAL9602](INFO): Powering down
2024-06-03T15:22:26.947Z,1717428146.947 [CBIT](INFO): Clearing failed state for component NAL9602
2024-06-03T15:22:26.947Z,1717428146.947 [NAL9602] No Fault, FailCount= 1
2024-06-03T15:22:56.403Z,1717428176.403 [NAL9602](INFO): Powering up NAL9602
2024-06-03T15:23:07.314Z,1717428187.314 [NAL9602](INFO): NAL9602 initialized
2024-06-03T15:23:46.929Z,1717428226.929 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-06-03T15:23:46.929Z,1717428226.929 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-03T15:23:46.948Z,1717428226.948 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-03T15:23:47.359Z,1717428227.359 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-03T15:23:47.359Z,1717428227.359 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-06-03T15:25:42.054Z,1717428342.054 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T15:25:42.054Z,1717428342.054 [Default:CheckIn:C.Wait] Stopped
2024-06-03T15:25:42.054Z,1717428342.054 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T15:25:42.054Z,1717428342.054 [Default:CheckIn:D] Running Loop=1
2024-06-03T15:25:42.464Z,1717428342.464 [Default:CheckIn:D] Stopped
2024-06-03T15:25:42.464Z,1717428342.464 [Default:CheckIn:E] Running Loop=1
2024-06-03T15:25:42.873Z,1717428342.873 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.687497 min
2024-06-03T15:25:42.873Z,1717428342.873 [Default:CheckIn:E] Stopped
2024-06-03T15:25:42.873Z,1717428342.873 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T15:25:42.873Z,1717428342.873 [Default:CheckIn] Stopped
2024-06-03T15:25:42.874Z,1717428342.874 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T15:25:42.874Z,1717428342.874 [Default:CheckIn](INFO): Running loop #2
2024-06-03T15:25:42.874Z,1717428342.874 [Default:CheckIn] Running Loop=2
2024-06-03T15:25:42.874Z,1717428342.874 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T15:25:42.874Z,1717428342.874 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T15:26:47.924Z,1717428407.924 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-06-03T15:26:47.924Z,1717428407.924 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-03T15:26:47.943Z,1717428407.943 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-03T15:26:48.351Z,1717428408.351 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-03T15:26:48.351Z,1717428408.351 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-06-03T15:29:49.063Z,1717428589.063 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-06-03T15:29:49.063Z,1717428589.063 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-03T15:29:49.085Z,1717428589.085 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-03T15:29:49.483Z,1717428589.483 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-03T15:29:49.483Z,1717428589.483 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-06-03T15:30:43.239Z,1717428643.239 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-06-03T15:25:42.9Z
2024-06-03T15:30:43.239Z,1717428643.239 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T15:30:43.239Z,1717428643.239 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T15:30:52.448Z,1717428652.448 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240603T151131/Courier0007.lzma
2024-06-03T15:30:53.450Z,1717428653.450 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0007.lzma.bak
2024-06-03T15:30:53.451Z,1717428653.451 [DataOverHttps](INFO): SBD MOMSN=21111964
2024-06-03T15:31:02.253Z,1717428662.253 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153100.00,A,3648.16382,N,12147.28854,W,0.214,0.00,030624,,,A*7B
2024-06-03T15:31:02.256Z,1717428662.256 [NAL9602](INFO): GPS fix at 20240603T153100: (36.802730, -121.788142)
2024-06-03T15:31:11.125Z,1717428671.125 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20240603T151131/Express0008.lzma
2024-06-03T15:31:12.126Z,1717428672.126 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0008.lzma.bak
2024-06-03T15:31:12.126Z,1717428672.126 [DataOverHttps](INFO): SBD MOMSN=21111966
2024-06-03T15:31:13.167Z,1717428673.167 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T15:31:13.167Z,1717428673.167 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T15:31:13.167Z,1717428673.167 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T15:31:34.175Z,1717428694.175 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T15:36:13.919Z,1717428973.919 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T15:36:13.919Z,1717428973.919 [Default:CheckIn:C.Wait] Stopped
2024-06-03T15:36:13.919Z,1717428973.919 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T15:36:13.920Z,1717428973.920 [Default:CheckIn:D] Running Loop=1
2024-06-03T15:36:14.328Z,1717428974.328 [Default:CheckIn:D] Stopped
2024-06-03T15:36:14.328Z,1717428974.328 [Default:CheckIn:E] Running Loop=1
2024-06-03T15:36:14.724Z,1717428974.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.218567 min
2024-06-03T15:36:14.724Z,1717428974.724 [Default:CheckIn:E] Stopped
2024-06-03T15:36:14.724Z,1717428974.724 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T15:36:14.724Z,1717428974.724 [Default:CheckIn] Stopped
2024-06-03T15:36:14.725Z,1717428974.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T15:36:14.725Z,1717428974.725 [Default:CheckIn](INFO): Running loop #3
2024-06-03T15:36:14.725Z,1717428974.725 [Default:CheckIn] Running Loop=3
2024-06-03T15:36:14.725Z,1717428974.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T15:36:14.725Z,1717428974.725 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T15:36:16.736Z,1717428976.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153615.00,A,3648.16628,N,12147.28016,W,0.058,69.22,030624,,,A*46
2024-06-03T15:36:16.739Z,1717428976.739 [NAL9602](INFO): GPS fix at 20240603T153615: (36.802771, -121.788003)
2024-06-03T15:36:16.757Z,1717428976.757 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T15:36:16.757Z,1717428976.757 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T15:36:27.225Z,1717428987.225 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20240603T151131/Courier0010.lzma
2024-06-03T15:36:28.228Z,1717428988.228 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0010.lzma.bak
2024-06-03T15:36:28.228Z,1717428988.228 [DataOverHttps](INFO): SBD MOMSN=21111980
2024-06-03T15:36:44.257Z,1717429004.257 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20240603T151131/Express0011.lzma
2024-06-03T15:36:45.258Z,1717429005.258 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0011.lzma.bak
2024-06-03T15:36:45.258Z,1717429005.258 [DataOverHttps](INFO): SBD MOMSN=21111983
2024-06-03T15:36:46.301Z,1717429006.301 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T15:36:46.301Z,1717429006.301 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T15:36:46.301Z,1717429006.301 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T15:39:00.180Z,1717429140.180 [NAL9602](INFO): SBD MO Status=0, MOMSN=51615, MT Status=0, MTMSN=0
2024-06-03T15:39:00.180Z,1717429140.180 [NAL9602](INFO): No messages in MT queue
2024-06-03T15:39:30.881Z,1717429170.881 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T15:41:46.751Z,1717429306.751 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T15:41:46.751Z,1717429306.751 [Default:CheckIn:C.Wait] Stopped
2024-06-03T15:41:46.751Z,1717429306.751 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T15:41:46.752Z,1717429306.752 [Default:CheckIn:D] Running Loop=1
2024-06-03T15:41:47.165Z,1717429307.165 [Default:CheckIn:D] Stopped
2024-06-03T15:41:47.165Z,1717429307.165 [Default:CheckIn:E] Running Loop=1
2024-06-03T15:41:47.578Z,1717429307.578 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.765855 min
2024-06-03T15:41:47.578Z,1717429307.578 [Default:CheckIn:E] Stopped
2024-06-03T15:41:47.578Z,1717429307.578 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T15:41:47.578Z,1717429307.578 [Default:CheckIn] Stopped
2024-06-03T15:41:47.578Z,1717429307.578 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T15:41:47.579Z,1717429307.579 [Default:CheckIn](INFO): Running loop #4
2024-06-03T15:41:47.579Z,1717429307.579 [Default:CheckIn] Running Loop=4
2024-06-03T15:41:47.579Z,1717429307.579 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T15:41:47.579Z,1717429307.579 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T15:41:49.571Z,1717429309.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154148.00,A,3648.14194,N,12147.22730,W,0.000,72.61,030624,,,A*45
2024-06-03T15:41:49.574Z,1717429309.574 [NAL9602](INFO): GPS fix at 20240603T154148: (36.802366, -121.787122)
2024-06-03T15:41:49.585Z,1717429309.585 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T15:41:49.586Z,1717429309.586 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T15:41:56.964Z,1717429316.964 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240603T151131/Courier0013.lzma
2024-06-03T15:41:57.966Z,1717429317.966 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0013.lzma.bak
2024-06-03T15:41:57.966Z,1717429317.966 [DataOverHttps](INFO): SBD MOMSN=21112002
2024-06-03T15:42:13.892Z,1717429333.892 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240603T151131/Express0014.lzma
2024-06-03T15:42:14.894Z,1717429334.894 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0014.lzma.bak
2024-06-03T15:42:14.894Z,1717429334.894 [DataOverHttps](INFO): SBD MOMSN=21112005
2024-06-03T15:42:16.248Z,1717429336.248 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T15:42:16.248Z,1717429336.248 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T15:42:16.249Z,1717429336.249 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T15:42:32.055Z,1717429352.055 [NAL9602](INFO): SBD MO Status=2, MOMSN=51616, MT Status=2, MTMSN=0
2024-06-03T15:42:32.056Z,1717429352.056 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-03T15:42:57.943Z,1717429377.943 [NAL9602](INFO): SBD MO Status=2, MOMSN=51616, MT Status=2, MTMSN=0
2024-06-03T15:42:57.943Z,1717429377.943 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-03T15:45:01.301Z,1717429501.301 [NAL9602](INFO): SBD MO Status=0, MOMSN=51616, MT Status=0, MTMSN=0
2024-06-03T15:45:01.301Z,1717429501.301 [NAL9602](INFO): No messages in MT queue
2024-06-03T15:45:31.989Z,1717429531.989 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T15:47:16.748Z,1717429636.748 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T15:47:16.748Z,1717429636.748 [Default:CheckIn:C.Wait] Stopped
2024-06-03T15:47:16.749Z,1717429636.749 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T15:47:16.749Z,1717429636.749 [Default:CheckIn:D] Running Loop=1
2024-06-03T15:47:17.166Z,1717429637.166 [Default:CheckIn:D] Stopped
2024-06-03T15:47:17.166Z,1717429637.166 [Default:CheckIn:E] Running Loop=1
2024-06-03T15:47:17.576Z,1717429637.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.265861 min
2024-06-03T15:47:17.576Z,1717429637.576 [Default:CheckIn:E] Stopped
2024-06-03T15:47:17.576Z,1717429637.576 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T15:47:17.576Z,1717429637.576 [Default:CheckIn] Stopped
2024-06-03T15:47:17.576Z,1717429637.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T15:47:17.576Z,1717429637.576 [Default:CheckIn](INFO): Running loop #5
2024-06-03T15:47:17.577Z,1717429637.577 [Default:CheckIn] Running Loop=5
2024-06-03T15:47:17.577Z,1717429637.577 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T15:47:17.577Z,1717429637.577 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T15:47:19.574Z,1717429639.574 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154718.00,A,3648.14218,N,12147.22754,W,0.000,72.61,030624,,,A*43
2024-06-03T15:47:19.577Z,1717429639.577 [NAL9602](INFO): GPS fix at 20240603T154718: (36.802370, -121.787126)
2024-06-03T15:47:19.588Z,1717429639.588 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T15:47:19.588Z,1717429639.588 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T15:47:26.076Z,1717429646.076 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0016.lzma
2024-06-03T15:47:27.080Z,1717429647.080 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0016.lzma.bak
2024-06-03T15:47:27.080Z,1717429647.080 [DataOverHttps](INFO): SBD MOMSN=21112015
2024-06-03T15:47:30.887Z,1717429650.887 [NAL9602](INFO): SBD MO Status=0, MOMSN=51617, MT Status=0, MTMSN=0
2024-06-03T15:47:30.887Z,1717429650.887 [NAL9602](INFO): No messages in MT queue
2024-06-03T15:47:43.036Z,1717429663.036 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240603T151131/Express0017.lzma
2024-06-03T15:47:44.038Z,1717429664.038 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0017.lzma.bak
2024-06-03T15:47:44.038Z,1717429664.038 [DataOverHttps](INFO): SBD MOMSN=21112018
2024-06-03T15:47:45.066Z,1717429665.066 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T15:47:45.066Z,1717429665.066 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T15:47:45.066Z,1717429665.066 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T15:48:01.590Z,1717429681.590 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T15:48:55.630Z,1717429735.630 [DataOverHttps](IMPORTANT): SBD MTMSN=20240603T154854
2024-06-03T15:49:03.433Z,1717429743.433 [DataOverHttps](INFO): Received command: failc
2024-06-03T15:49:03.473Z,1717429743.473 [CommandExec](IMPORTANT): got command failComponent
2024-06-03T15:49:03.473Z,1717429743.473 [CommandExec](IMPORTANT): Failed components:
2024-06-03T15:49:03.473Z,1717429743.473 [CommandExec](IMPORTANT): No failed Components.
2024-06-03T15:49:04.434Z,1717429744.434 [DataOverHttps](IMPORTANT): SBD MTMSN=20240603T154903
2024-06-03T15:49:11.784Z,1717429751.784 [DataOverHttps](INFO): Received command: ibit
2024-06-03T15:49:11.797Z,1717429751.797 [CommandExec](IMPORTANT): got command ibit
2024-06-03T15:49:12.018Z,1717429752.018 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-06-03T15:49:12.018Z,1717429752.018 [IBIT](IMPORTANT): Beginning control surface checks.
2024-06-03T15:49:12.021Z,1717429752.021 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-03T15:49:13.594Z,1717429753.594 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154912.00,A,3648.14257,N,12147.22713,W,0.019,72.61,030624,,,A*47
2024-06-03T15:49:13.597Z,1717429753.597 [NAL9602](INFO): GPS fix at 20240603T154912: (36.802376, -121.787119)
2024-06-03T15:49:22.909Z,1717429762.909 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-03T15:49:23.299Z,1717429763.299 [NAL9602](INFO): SBD MO Status=0, MOMSN=51618, MT Status=0, MTMSN=0
2024-06-03T15:49:23.300Z,1717429763.300 [NAL9602](INFO): No messages in MT queue
2024-06-03T15:49:23.319Z,1717429763.319 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-03T15:49:24.503Z,1717429764.503 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154923.00,A,3648.14267,N,12147.22723,W,0.214,72.61,030624,,,A*4A
2024-06-03T15:49:24.506Z,1717429764.506 [NAL9602](INFO): GPS fix at 20240603T154923: (36.802378, -121.787121)
2024-06-03T15:49:26.931Z,1717429766.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154926.00,A,3648.14266,N,12147.22725,W,0.078,72.61,030624,,,A*40
2024-06-03T15:49:26.934Z,1717429766.934 [NAL9602](INFO): GPS fix at 20240603T154926: (36.802378, -121.787121)
2024-06-03T15:49:30.158Z,1717429770.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154929.00,A,3648.14269,N,12147.22726,W,0.039,72.61,030624,,,A*46
2024-06-03T15:49:30.161Z,1717429770.161 [NAL9602](INFO): GPS fix at 20240603T154929: (36.802378, -121.787121)
2024-06-03T15:49:32.987Z,1717429772.987 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154932.00,A,3648.14276,N,12147.22724,W,0.019,72.61,030624,,,A*42
2024-06-03T15:49:32.990Z,1717429772.990 [NAL9602](INFO): GPS fix at 20240603T154932: (36.802379, -121.787121)
2024-06-03T15:49:34.317Z,1717429774.317 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-06-03T15:49:34.797Z,1717429774.797 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-03T15:49:36.219Z,1717429776.219 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154935.00,A,3648.14280,N,12147.22727,W,0.058,72.61,030624,,,A*4A
2024-06-03T15:49:36.221Z,1717429776.221 [NAL9602](INFO): GPS fix at 20240603T154935: (36.802380, -121.787121)
2024-06-03T15:49:39.051Z,1717429779.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154938.00,A,3648.14285,N,12147.22730,W,0.019,72.61,030624,,,A*41
2024-06-03T15:49:39.053Z,1717429779.053 [NAL9602](INFO): GPS fix at 20240603T154938: (36.802381, -121.787122)
2024-06-03T15:49:41.875Z,1717429781.875 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154941.00,A,3648.14288,N,12147.22731,W,0.058,72.61,030624,,,A*46
2024-06-03T15:49:41.877Z,1717429781.877 [NAL9602](INFO): GPS fix at 20240603T154941: (36.802381, -121.787122)
2024-06-03T15:49:45.135Z,1717429785.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154944.00,A,3648.14288,N,12147.22733,W,0.019,72.61,030624,,,A*44
2024-06-03T15:49:45.137Z,1717429785.137 [NAL9602](INFO): GPS fix at 20240603T154944: (36.802381, -121.787122)
2024-06-03T15:49:45.608Z,1717429785.608 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002444
CHAN A1 (24V): 0.000690
CHAN A2 (12V): -0.001865
CHAN A3 (5V): -0.001338
CHAN B0 (3.3V): -0.000030
CHAN B1 (3.15aV): -0.000319
CHAN B2 (3.15bV): 0.000167
CHAN B3 (GND): 0.000037
OPEN: 4.764929
Full Scale: +/- 1 mA
2024-06-03T15:49:47.937Z,1717429787.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154947.00,A,3648.14287,N,12147.22731,W,0.078,72.61,030624,,,A*4D
2024-06-03T15:49:47.939Z,1717429787.939 [NAL9602](INFO): GPS fix at 20240603T154947: (36.802381, -121.787122)
2024-06-03T15:49:51.167Z,1717429791.167 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154950.00,A,3648.14289,N,12147.22731,W,0.019,72.61,030624,,,A*42
2024-06-03T15:49:51.169Z,1717429791.169 [NAL9602](INFO): GPS fix at 20240603T154950: (36.802382, -121.787122)
2024-06-03T15:49:53.999Z,1717429793.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154953.00,A,3648.14294,N,12147.22730,W,0.019,72.61,030624,,,A*4C
2024-06-03T15:49:54.001Z,1717429794.001 [NAL9602](INFO): GPS fix at 20240603T154953: (36.802382, -121.787122)
2024-06-03T15:49:57.226Z,1717429797.226 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154956.00,A,3648.14299,N,12147.22725,W,0.039,72.61,030624,,,A*42
2024-06-03T15:49:57.228Z,1717429797.228 [NAL9602](INFO): GPS fix at 20240603T154956: (36.802383, -121.787121)
2024-06-03T15:49:58.466Z,1717429798.466 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802383 Longitude: -121.787125
2024-06-03T15:49:58.881Z,1717429798.881 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2024-06-03T15:49:58.882Z,1717429798.882 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-06-03T15:49:58.882Z,1717429798.882 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-06-03T15:49:59.265Z,1717429799.265 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-06-03T15:49:59.266Z,1717429799.266 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-06-03T15:49:59.266Z,1717429799.266 [IBIT](IMPORTANT): Pressure:10.262745 PSI
2024-06-03T15:49:59.266Z,1717429799.266 [IBIT](IMPORTANT): Humidity:14.143571 %
2024-06-03T15:49:59.666Z,1717429799.666 [IBIT](IMPORTANT): Vehicle Pitch:-0.482325 degrees
2024-06-03T15:49:59.666Z,1717429799.666 [IBIT](IMPORTANT): Vehicle Roll:2.175690 degrees
2024-06-03T15:49:59.667Z,1717429799.667 [IBIT](IMPORTANT): Vehicle Heading:201.505112 degrees
2024-06-03T15:50:00.094Z,1717429800.094 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-06-03T15:50:00.094Z,1717429800.094 [IBIT](IMPORTANT): buoyancyNeutral: 238.004669 cc
2024-06-03T15:50:00.095Z,1717429800.095 [IBIT](IMPORTANT): massDefault: 0.484574 cm
2024-06-03T15:50:00.095Z,1717429800.095 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-06-03T15:50:00.095Z,1717429800.095 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-06-03T15:50:00.095Z,1717429800.095 [IBIT](IMPORTANT): IBIT PASSED
2024-06-03T15:50:29.549Z,1717429829.549 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T15:52:45.708Z,1717429965.708 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T15:52:45.708Z,1717429965.708 [Default:CheckIn:C.Wait] Stopped
2024-06-03T15:52:45.708Z,1717429965.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T15:52:45.708Z,1717429965.708 [Default:CheckIn:D] Running Loop=1
2024-06-03T15:52:46.110Z,1717429966.110 [Default:CheckIn:D] Stopped
2024-06-03T15:52:46.110Z,1717429966.110 [Default:CheckIn:E] Running Loop=1
2024-06-03T15:52:46.523Z,1717429966.523 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.748258 min
2024-06-03T15:52:46.524Z,1717429966.524 [Default:CheckIn:E] Stopped
2024-06-03T15:52:46.524Z,1717429966.524 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T15:52:46.524Z,1717429966.524 [Default:CheckIn] Stopped
2024-06-03T15:52:46.524Z,1717429966.524 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T15:52:46.524Z,1717429966.524 [Default:CheckIn](INFO): Running loop #6
2024-06-03T15:52:46.524Z,1717429966.524 [Default:CheckIn] Running Loop=6
2024-06-03T15:52:46.524Z,1717429966.524 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T15:52:46.524Z,1717429966.524 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T15:52:48.537Z,1717429968.537 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155247.00,A,3648.14217,N,12147.22775,W,0.058,72.61,030624,,,A*4C
2024-06-03T15:52:48.539Z,1717429968.539 [NAL9602](INFO): GPS fix at 20240603T155247: (36.802369, -121.787129)
2024-06-03T15:52:48.550Z,1717429968.550 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T15:52:48.551Z,1717429968.551 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T15:52:59.064Z,1717429979.064 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240603T151131/Courier0019.lzma
2024-06-03T15:53:00.066Z,1717429980.066 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0019.lzma.bak
2024-06-03T15:53:00.067Z,1717429980.067 [DataOverHttps](INFO): SBD MOMSN=21112039
2024-06-03T15:53:03.137Z,1717429983.137 [NAL9602](INFO): SBD MO Status=0, MOMSN=51619, MT Status=0, MTMSN=0
2024-06-03T15:53:03.137Z,1717429983.137 [NAL9602](INFO): No messages in MT queue
2024-06-03T15:53:15.934Z,1717429995.934 [DataOverHttps](INFO): Sending 865 bytes from file Logs/20240603T151131/Express0020.lzma
2024-06-03T15:53:16.935Z,1717429996.935 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0020.lzma.bak
2024-06-03T15:53:16.935Z,1717429996.935 [DataOverHttps](INFO): SBD MOMSN=21112042
2024-06-03T15:53:33.000Z,1717430013.000 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240603T151131/Express0023.lzma
2024-06-03T15:53:33.833Z,1717430013.833 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T15:53:34.002Z,1717430014.002 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0023.lzma.bak
2024-06-03T15:53:34.002Z,1717430014.002 [DataOverHttps](INFO): SBD MOMSN=21112075
2024-06-03T15:53:35.053Z,1717430015.053 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T15:53:35.053Z,1717430015.053 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T15:53:35.053Z,1717430015.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T15:58:35.645Z,1717430315.645 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T15:58:35.646Z,1717430315.646 [Default:CheckIn:C.Wait] Stopped
2024-06-03T15:58:35.646Z,1717430315.646 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T15:58:35.646Z,1717430315.646 [Default:CheckIn:D] Running Loop=1
2024-06-03T15:58:36.055Z,1717430316.055 [Default:CheckIn:D] Stopped
2024-06-03T15:58:36.055Z,1717430316.055 [Default:CheckIn:E] Running Loop=1
2024-06-03T15:58:36.443Z,1717430316.443 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.580676 min
2024-06-03T15:58:36.443Z,1717430316.443 [Default:CheckIn:E] Stopped
2024-06-03T15:58:36.443Z,1717430316.443 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T15:58:36.443Z,1717430316.443 [Default:CheckIn] Stopped
2024-06-03T15:58:36.443Z,1717430316.443 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T15:58:36.444Z,1717430316.444 [Default:CheckIn](INFO): Running loop #7
2024-06-03T15:58:36.444Z,1717430316.444 [Default:CheckIn] Running Loop=7
2024-06-03T15:58:36.444Z,1717430316.444 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T15:58:36.444Z,1717430316.444 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T15:58:38.460Z,1717430318.460 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155837.00,A,3648.14282,N,12147.22584,W,0.000,72.61,030624,,,A*4C
2024-06-03T15:58:38.462Z,1717430318.462 [NAL9602](INFO): GPS fix at 20240603T155837: (36.802380, -121.787097)
2024-06-03T15:58:38.473Z,1717430318.473 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T15:58:38.473Z,1717430318.473 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T15:58:45.432Z,1717430325.432 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0025.lzma
2024-06-03T15:58:46.430Z,1717430326.430 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0025.lzma.bak
2024-06-03T15:58:46.430Z,1717430326.430 [DataOverHttps](INFO): SBD MOMSN=21112083
2024-06-03T15:58:52.243Z,1717430332.243 [NAL9602](INFO): SBD MO Status=0, MOMSN=51620, MT Status=0, MTMSN=0
2024-06-03T15:58:52.244Z,1717430332.244 [NAL9602](INFO): No messages in MT queue
2024-06-03T15:59:04.776Z,1717430344.776 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0026.lzma
2024-06-03T15:59:05.778Z,1717430345.778 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0026.lzma.bak
2024-06-03T15:59:05.778Z,1717430345.778 [DataOverHttps](INFO): SBD MOMSN=21112086
2024-06-03T15:59:06.819Z,1717430346.819 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T15:59:06.819Z,1717430346.819 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T15:59:06.819Z,1717430346.819 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T15:59:22.954Z,1717430362.954 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:04:07.383Z,1717430647.383 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:04:07.383Z,1717430647.383 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:04:07.383Z,1717430647.383 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:04:07.383Z,1717430647.383 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:04:07.784Z,1717430647.784 [Default:CheckIn:D] Stopped
2024-06-03T16:04:07.784Z,1717430647.784 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:04:08.189Z,1717430648.189 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.109501 min
2024-06-03T16:04:08.189Z,1717430648.189 [Default:CheckIn:E] Stopped
2024-06-03T16:04:08.189Z,1717430648.189 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:04:08.189Z,1717430648.189 [Default:CheckIn] Stopped
2024-06-03T16:04:08.189Z,1717430648.189 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:04:08.189Z,1717430648.189 [Default:CheckIn](INFO): Running loop #8
2024-06-03T16:04:08.189Z,1717430648.189 [Default:CheckIn] Running Loop=8
2024-06-03T16:04:08.189Z,1717430648.189 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:04:08.190Z,1717430648.190 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:04:10.199Z,1717430650.199 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160409.00,A,3648.14122,N,12147.22832,W,0.058,46.26,030624,,,A*4B
2024-06-03T16:04:10.202Z,1717430650.202 [NAL9602](INFO): GPS fix at 20240603T160409: (36.802354, -121.787139)
2024-06-03T16:04:10.233Z,1717430650.233 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:04:10.233Z,1717430650.233 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:04:17.092Z,1717430657.092 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0028.lzma
2024-06-03T16:04:18.094Z,1717430658.094 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0028.lzma.bak
2024-06-03T16:04:18.095Z,1717430658.095 [DataOverHttps](INFO): SBD MOMSN=21112135
2024-06-03T16:04:28.832Z,1717430668.832 [NAL9602](INFO): SBD MO Status=0, MOMSN=51621, MT Status=0, MTMSN=0
2024-06-03T16:04:28.833Z,1717430668.833 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:04:36.456Z,1717430676.456 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240603T151131/Express0029.lzma
2024-06-03T16:04:37.458Z,1717430677.458 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0029.lzma.bak
2024-06-03T16:04:37.459Z,1717430677.459 [DataOverHttps](INFO): SBD MOMSN=21112138
2024-06-03T16:04:38.537Z,1717430678.537 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:04:38.537Z,1717430678.537 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:04:38.537Z,1717430678.537 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:04:59.529Z,1717430699.529 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:09:39.111Z,1717430979.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:09:39.111Z,1717430979.111 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:09:39.111Z,1717430979.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:09:39.111Z,1717430979.111 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:09:39.525Z,1717430979.525 [Default:CheckIn:D] Stopped
2024-06-03T16:09:39.525Z,1717430979.525 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:09:39.919Z,1717430979.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.638513 min
2024-06-03T16:09:39.919Z,1717430979.919 [Default:CheckIn:E] Stopped
2024-06-03T16:09:39.919Z,1717430979.919 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:09:39.919Z,1717430979.919 [Default:CheckIn] Stopped
2024-06-03T16:09:39.919Z,1717430979.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:09:39.919Z,1717430979.919 [Default:CheckIn](INFO): Running loop #9
2024-06-03T16:09:39.919Z,1717430979.919 [Default:CheckIn] Running Loop=9
2024-06-03T16:09:39.920Z,1717430979.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:09:39.920Z,1717430979.920 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:09:41.930Z,1717430981.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160941.00,A,3648.14212,N,12147.22804,W,0.078,46.26,030624,,,A*4D
2024-06-03T16:09:41.932Z,1717430981.932 [NAL9602](INFO): GPS fix at 20240603T160941: (36.802369, -121.787134)
2024-06-03T16:09:41.943Z,1717430981.943 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:09:41.943Z,1717430981.943 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:09:49.892Z,1717430989.892 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0031.lzma
2024-06-03T16:09:50.894Z,1717430990.894 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0031.lzma.bak
2024-06-03T16:09:50.894Z,1717430990.894 [DataOverHttps](INFO): SBD MOMSN=21112154
2024-06-03T16:09:59.312Z,1717430999.312 [NAL9602](INFO): SBD MO Status=0, MOMSN=51622, MT Status=0, MTMSN=0
2024-06-03T16:09:59.312Z,1717430999.312 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:10:06.688Z,1717431006.688 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240603T151131/Express0032.lzma
2024-06-03T16:10:07.692Z,1717431007.692 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0032.lzma.bak
2024-06-03T16:10:07.694Z,1717431007.694 [DataOverHttps](INFO): SBD MOMSN=21112157
2024-06-03T16:10:09.020Z,1717431009.020 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:10:09.021Z,1717431009.021 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:10:09.021Z,1717431009.021 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:10:30.008Z,1717431030.008 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:15:09.696Z,1717431309.696 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:15:09.696Z,1717431309.696 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:15:09.697Z,1717431309.697 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:15:09.697Z,1717431309.697 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:15:10.104Z,1717431310.104 [Default:CheckIn:D] Stopped
2024-06-03T16:15:10.104Z,1717431310.104 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:15:10.500Z,1717431310.500 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.148161 min
2024-06-03T16:15:10.500Z,1717431310.500 [Default:CheckIn:E] Stopped
2024-06-03T16:15:10.500Z,1717431310.500 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:15:10.500Z,1717431310.500 [Default:CheckIn] Stopped
2024-06-03T16:15:10.500Z,1717431310.500 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:15:10.500Z,1717431310.500 [Default:CheckIn](INFO): Running loop #10
2024-06-03T16:15:10.500Z,1717431310.500 [Default:CheckIn] Running Loop=10
2024-06-03T16:15:10.500Z,1717431310.500 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:15:10.500Z,1717431310.500 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:15:12.511Z,1717431312.511 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161511.00,A,3648.14272,N,12147.23068,W,0.058,46.26,030624,,,A*42
2024-06-03T16:15:12.514Z,1717431312.514 [NAL9602](INFO): GPS fix at 20240603T161511: (36.802379, -121.787178)
2024-06-03T16:15:12.525Z,1717431312.525 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:15:12.525Z,1717431312.525 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:15:19.604Z,1717431319.604 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0034.lzma
2024-06-03T16:15:20.614Z,1717431320.614 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0034.lzma.bak
2024-06-03T16:15:20.615Z,1717431320.615 [DataOverHttps](INFO): SBD MOMSN=21112167
2024-06-03T16:15:33.515Z,1717431333.515 [NAL9602](INFO): SBD MO Status=0, MOMSN=51623, MT Status=0, MTMSN=0
2024-06-03T16:15:33.515Z,1717431333.515 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:15:36.364Z,1717431336.364 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240603T151131/Express0035.lzma
2024-06-03T16:15:37.366Z,1717431337.366 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0035.lzma.bak
2024-06-03T16:15:37.367Z,1717431337.367 [DataOverHttps](INFO): SBD MOMSN=21112170
2024-06-03T16:15:38.391Z,1717431338.391 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:15:38.391Z,1717431338.391 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:15:38.391Z,1717431338.391 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:16:04.217Z,1717431364.217 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:18:33.695Z,1717431513.695 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T16:18:46.220Z,1717431526.220 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T16:18:59.148Z,1717431539.148 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T16:19:11.267Z,1717431551.267 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T16:19:23.792Z,1717431563.792 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T16:19:36.315Z,1717431576.315 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T16:19:49.243Z,1717431589.243 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T16:20:38.979Z,1717431638.979 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:20:38.979Z,1717431638.979 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:20:38.979Z,1717431638.979 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:20:38.980Z,1717431638.980 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:20:39.381Z,1717431639.381 [Default:CheckIn:D] Stopped
2024-06-03T16:20:39.381Z,1717431639.381 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:20:39.793Z,1717431639.793 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.636104 min
2024-06-03T16:20:39.793Z,1717431639.793 [Default:CheckIn:E] Stopped
2024-06-03T16:20:39.793Z,1717431639.793 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:20:39.793Z,1717431639.793 [Default:CheckIn] Stopped
2024-06-03T16:20:39.793Z,1717431639.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:20:39.793Z,1717431639.793 [Default:CheckIn](INFO): Running loop #11
2024-06-03T16:20:39.794Z,1717431639.794 [Default:CheckIn] Running Loop=11
2024-06-03T16:20:39.794Z,1717431639.794 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:20:39.794Z,1717431639.794 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:20:41.799Z,1717431641.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162041.00,A,3648.14201,N,12147.22746,W,0.078,46.26,030624,,,A*4D
2024-06-03T16:20:41.803Z,1717431641.803 [NAL9602](INFO): GPS fix at 20240603T162041: (36.802367, -121.787124)
2024-06-03T16:20:41.832Z,1717431641.832 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:20:41.832Z,1717431641.832 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:20:51.104Z,1717431651.104 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0037.lzma
2024-06-03T16:20:52.107Z,1717431652.107 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0037.lzma.bak
2024-06-03T16:20:52.107Z,1717431652.107 [DataOverHttps](INFO): SBD MOMSN=21112186
2024-06-03T16:21:02.040Z,1717431662.040 [NAL9602](INFO): SBD MO Status=0, MOMSN=51624, MT Status=0, MTMSN=0
2024-06-03T16:21:02.041Z,1717431662.041 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:21:10.032Z,1717431670.032 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0038.lzma
2024-06-03T16:21:11.034Z,1717431671.034 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0038.lzma.bak
2024-06-03T16:21:11.035Z,1717431671.035 [DataOverHttps](INFO): SBD MOMSN=21112189
2024-06-03T16:21:12.140Z,1717431672.140 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:21:12.140Z,1717431672.140 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:21:12.140Z,1717431672.140 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:21:32.742Z,1717431692.742 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:22:30.139Z,1717431750.139 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-06-03T16:22:30.141Z,1717431750.141 [BPC1](INFO): Received data from all battery sticks.
2024-06-03T16:26:12.897Z,1717431972.897 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:26:12.897Z,1717431972.897 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:26:12.897Z,1717431972.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:26:12.897Z,1717431972.897 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:26:13.300Z,1717431973.300 [Default:CheckIn:D] Stopped
2024-06-03T16:26:13.300Z,1717431973.300 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:26:13.705Z,1717431973.705 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.201432 min
2024-06-03T16:26:13.706Z,1717431973.706 [Default:CheckIn:E] Stopped
2024-06-03T16:26:13.706Z,1717431973.706 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:26:13.706Z,1717431973.706 [Default:CheckIn] Stopped
2024-06-03T16:26:13.706Z,1717431973.706 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:26:13.706Z,1717431973.706 [Default:CheckIn](INFO): Running loop #12
2024-06-03T16:26:13.706Z,1717431973.706 [Default:CheckIn] Running Loop=12
2024-06-03T16:26:13.706Z,1717431973.706 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:26:13.706Z,1717431973.706 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:26:15.712Z,1717431975.712 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162614.00,A,3648.14180,N,12147.22677,W,0.019,46.26,030624,,,A*45
2024-06-03T16:26:15.714Z,1717431975.714 [NAL9602](INFO): GPS fix at 20240603T162614: (36.802363, -121.787113)
2024-06-03T16:26:15.725Z,1717431975.725 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:26:15.725Z,1717431975.725 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:26:26.397Z,1717431986.397 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20240603T151131/Courier0040.lzma
2024-06-03T16:26:27.399Z,1717431987.399 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0040.lzma.bak
2024-06-03T16:26:27.399Z,1717431987.399 [DataOverHttps](INFO): SBD MOMSN=21112199
2024-06-03T16:26:40.419Z,1717432000.419 [NAL9602](INFO): SBD MO Status=0, MOMSN=51625, MT Status=0, MTMSN=0
2024-06-03T16:26:40.419Z,1717432000.419 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:26:43.304Z,1717432003.304 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0041.lzma
2024-06-03T16:26:44.294Z,1717432004.294 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0041.lzma.bak
2024-06-03T16:26:44.295Z,1717432004.295 [DataOverHttps](INFO): SBD MOMSN=21112202
2024-06-03T16:26:45.683Z,1717432005.683 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:26:45.683Z,1717432005.683 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:26:45.683Z,1717432005.683 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:27:11.166Z,1717432031.166 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:31:46.491Z,1717432306.491 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:31:46.491Z,1717432306.491 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:31:46.491Z,1717432306.491 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:31:46.491Z,1717432306.491 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:31:46.921Z,1717432306.921 [Default:CheckIn:D] Stopped
2024-06-03T16:31:46.921Z,1717432306.921 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:31:47.303Z,1717432307.303 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.761784 min
2024-06-03T16:31:47.303Z,1717432307.303 [Default:CheckIn:E] Stopped
2024-06-03T16:31:47.303Z,1717432307.303 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:31:47.303Z,1717432307.303 [Default:CheckIn] Stopped
2024-06-03T16:31:47.303Z,1717432307.303 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:31:47.303Z,1717432307.303 [Default:CheckIn](INFO): Running loop #13
2024-06-03T16:31:47.303Z,1717432307.303 [Default:CheckIn] Running Loop=13
2024-06-03T16:31:47.303Z,1717432307.303 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:31:47.303Z,1717432307.303 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:31:49.306Z,1717432309.306 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163148.00,A,3648.14182,N,12147.22712,W,0.000,46.26,030624,,,A*42
2024-06-03T16:31:49.308Z,1717432309.308 [NAL9602](INFO): GPS fix at 20240603T163148: (36.802364, -121.787119)
2024-06-03T16:31:49.320Z,1717432309.320 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:31:49.320Z,1717432309.320 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:31:56.560Z,1717432316.560 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240603T151131/Courier0043.lzma
2024-06-03T16:31:57.563Z,1717432317.563 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0043.lzma.bak
2024-06-03T16:31:57.563Z,1717432317.563 [DataOverHttps](INFO): SBD MOMSN=21112218
2024-06-03T16:31:58.187Z,1717432318.187 [NAL9602](INFO): SBD MO Status=0, MOMSN=51626, MT Status=0, MTMSN=0
2024-06-03T16:31:58.187Z,1717432318.187 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:32:13.522Z,1717432333.522 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240603T151131/Express0044.lzma
2024-06-03T16:32:14.518Z,1717432334.518 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0044.lzma.bak
2024-06-03T16:32:14.519Z,1717432334.519 [DataOverHttps](INFO): SBD MOMSN=21112221
2024-06-03T16:32:15.564Z,1717432335.564 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:32:15.564Z,1717432335.564 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:32:15.565Z,1717432335.565 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:32:28.962Z,1717432348.962 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:37:16.089Z,1717432636.089 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:37:16.089Z,1717432636.089 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:37:16.089Z,1717432636.089 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:37:16.090Z,1717432636.089 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:37:16.488Z,1717432636.488 [Default:CheckIn:D] Stopped
2024-06-03T16:37:16.488Z,1717432636.488 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:37:16.887Z,1717432636.887 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.254565 min
2024-06-03T16:37:16.887Z,1717432636.887 [Default:CheckIn:E] Stopped
2024-06-03T16:37:16.887Z,1717432636.887 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:37:16.887Z,1717432636.887 [Default:CheckIn] Stopped
2024-06-03T16:37:16.888Z,1717432636.888 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:37:16.888Z,1717432636.888 [Default:CheckIn](INFO): Running loop #14
2024-06-03T16:37:16.888Z,1717432636.888 [Default:CheckIn] Running Loop=14
2024-06-03T16:37:16.888Z,1717432636.888 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:37:16.888Z,1717432636.888 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:37:18.899Z,1717432638.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163718.00,A,3648.14158,N,12147.22736,W,0.000,0.00,030624,,,A*76
2024-06-03T16:37:18.902Z,1717432638.902 [NAL9602](INFO): GPS fix at 20240603T163718: (36.802360, -121.787123)
2024-06-03T16:37:18.913Z,1717432638.913 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:37:18.913Z,1717432638.913 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:37:25.812Z,1717432645.812 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0046.lzma
2024-06-03T16:37:26.814Z,1717432646.814 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0046.lzma.bak
2024-06-03T16:37:26.815Z,1717432646.815 [DataOverHttps](INFO): SBD MOMSN=21112232
2024-06-03T16:37:42.656Z,1717432662.656 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240603T151131/Express0047.lzma
2024-06-03T16:37:43.658Z,1717432663.658 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0047.lzma.bak
2024-06-03T16:37:43.659Z,1717432663.659 [DataOverHttps](INFO): SBD MOMSN=21112235
2024-06-03T16:37:44.772Z,1717432664.772 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:37:44.772Z,1717432664.772 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:37:44.773Z,1717432664.773 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:37:53.239Z,1717432673.239 [NAL9602](INFO): SBD MO Status=2, MOMSN=51627, MT Status=2, MTMSN=0
2024-06-03T16:37:53.239Z,1717432673.239 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-03T16:38:02.126Z,1717432682.126 [NAL9602](INFO): SBD MO Status=0, MOMSN=51627, MT Status=0, MTMSN=0
2024-06-03T16:38:02.126Z,1717432682.126 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:38:32.869Z,1717432712.869 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:42:45.565Z,1717432965.565 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:42:45.565Z,1717432965.565 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:42:45.565Z,1717432965.565 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:42:45.565Z,1717432965.565 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:42:45.976Z,1717432965.976 [Default:CheckIn:D] Stopped
2024-06-03T16:42:45.976Z,1717432965.976 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:42:46.376Z,1717432966.376 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.746029 min
2024-06-03T16:42:46.376Z,1717432966.376 [Default:CheckIn:E] Stopped
2024-06-03T16:42:46.376Z,1717432966.376 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:42:46.376Z,1717432966.376 [Default:CheckIn] Stopped
2024-06-03T16:42:46.392Z,1717432966.392 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:42:46.393Z,1717432966.393 [Default:CheckIn](INFO): Running loop #15
2024-06-03T16:42:46.393Z,1717432966.393 [Default:CheckIn] Running Loop=15
2024-06-03T16:42:46.393Z,1717432966.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:42:46.393Z,1717432966.393 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:42:48.387Z,1717432968.387 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164247.00,A,3648.14302,N,12147.22634,W,0.039,0.00,030624,,,A*7A
2024-06-03T16:42:48.389Z,1717432968.389 [NAL9602](INFO): GPS fix at 20240603T164247: (36.802384, -121.787106)
2024-06-03T16:42:48.432Z,1717432968.432 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:42:48.432Z,1717432968.432 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:42:59.260Z,1717432979.260 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0049.lzma
2024-06-03T16:43:00.263Z,1717432980.263 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0049.lzma.bak
2024-06-03T16:43:00.263Z,1717432980.263 [DataOverHttps](INFO): SBD MOMSN=21112251
2024-06-03T16:43:16.324Z,1717432996.324 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240603T151131/Express0050.lzma
2024-06-03T16:43:17.131Z,1717432997.131 [NAL9602](INFO): SBD MO Status=2, MOMSN=51628, MT Status=2, MTMSN=0
2024-06-03T16:43:17.131Z,1717432997.131 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-03T16:43:17.326Z,1717432997.326 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0050.lzma.bak
2024-06-03T16:43:17.327Z,1717432997.327 [DataOverHttps](INFO): SBD MOMSN=21112254
2024-06-03T16:43:18.359Z,1717432998.359 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:43:18.359Z,1717432998.359 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:43:18.359Z,1717432998.359 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:43:45.810Z,1717433025.810 [NAL9602](INFO): SBD MO Status=2, MOMSN=51628, MT Status=2, MTMSN=0
2024-06-03T16:43:45.810Z,1717433025.810 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-03T16:44:16.927Z,1717433056.927 [NAL9602](INFO): SBD MO Status=0, MOMSN=51628, MT Status=0, MTMSN=0
2024-06-03T16:44:16.927Z,1717433056.927 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:44:47.694Z,1717433087.694 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:48:19.132Z,1717433299.132 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:48:19.133Z,1717433299.133 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:48:19.133Z,1717433299.133 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:48:19.133Z,1717433299.133 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:48:19.532Z,1717433299.532 [Default:CheckIn:D] Stopped
2024-06-03T16:48:19.532Z,1717433299.532 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:48:19.936Z,1717433299.936 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.305298 min
2024-06-03T16:48:19.936Z,1717433299.936 [Default:CheckIn:E] Stopped
2024-06-03T16:48:19.936Z,1717433299.936 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:48:19.936Z,1717433299.936 [Default:CheckIn] Stopped
2024-06-03T16:48:19.936Z,1717433299.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:48:19.937Z,1717433299.937 [Default:CheckIn](INFO): Running loop #16
2024-06-03T16:48:19.937Z,1717433299.937 [Default:CheckIn] Running Loop=16
2024-06-03T16:48:19.937Z,1717433299.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:48:19.937Z,1717433299.937 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:48:21.942Z,1717433301.942 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164821.00,A,3648.14128,N,12147.22782,W,0.019,0.00,030624,,,A*74
2024-06-03T16:48:21.944Z,1717433301.944 [NAL9602](INFO): GPS fix at 20240603T164821: (36.802355, -121.787130)
2024-06-03T16:48:21.963Z,1717433301.963 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:48:21.963Z,1717433301.963 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:48:30.564Z,1717433310.564 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0052.lzma
2024-06-03T16:48:31.567Z,1717433311.567 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0052.lzma.bak
2024-06-03T16:48:31.567Z,1717433311.567 [DataOverHttps](INFO): SBD MOMSN=21112264
2024-06-03T16:48:42.138Z,1717433322.138 [NAL9602](INFO): SBD MO Status=0, MOMSN=51629, MT Status=0, MTMSN=0
2024-06-03T16:48:42.138Z,1717433322.138 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:48:47.565Z,1717433327.565 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0053.lzma
2024-06-03T16:48:48.567Z,1717433328.567 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0053.lzma.bak
2024-06-03T16:48:48.567Z,1717433328.567 [DataOverHttps](INFO): SBD MOMSN=21112267
2024-06-03T16:48:49.832Z,1717433329.832 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:48:49.832Z,1717433329.832 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:48:49.832Z,1717433329.832 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:49:12.906Z,1717433352.906 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:53:50.548Z,1717433630.548 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:53:50.548Z,1717433630.548 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:53:50.548Z,1717433630.548 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:53:50.548Z,1717433630.548 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:53:50.947Z,1717433630.947 [Default:CheckIn:D] Stopped
2024-06-03T16:53:50.947Z,1717433630.947 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:53:51.352Z,1717433631.352 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.828882 min
2024-06-03T16:53:51.352Z,1717433631.352 [Default:CheckIn:E] Stopped
2024-06-03T16:53:51.353Z,1717433631.353 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:53:51.353Z,1717433631.353 [Default:CheckIn] Stopped
2024-06-03T16:53:51.353Z,1717433631.353 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:53:51.353Z,1717433631.353 [Default:CheckIn](INFO): Running loop #17
2024-06-03T16:53:51.353Z,1717433631.353 [Default:CheckIn] Running Loop=17
2024-06-03T16:53:51.353Z,1717433631.353 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:53:51.353Z,1717433631.353 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:53:53.395Z,1717433633.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165352.00,A,3648.14206,N,12147.22860,W,0.019,0.00,030624,,,D*73
2024-06-03T16:53:53.398Z,1717433633.398 [NAL9602](INFO): GPS fix at 20240603T165352: (36.802368, -121.787143)
2024-06-03T16:53:53.408Z,1717433633.408 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:53:53.408Z,1717433633.408 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:54:00.349Z,1717433640.349 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240603T151131/Courier0055.lzma
2024-06-03T16:54:01.350Z,1717433641.350 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0055.lzma.bak
2024-06-03T16:54:01.351Z,1717433641.351 [DataOverHttps](INFO): SBD MOMSN=21112283
2024-06-03T16:54:06.699Z,1717433646.699 [NAL9602](INFO): SBD MO Status=0, MOMSN=51630, MT Status=0, MTMSN=0
2024-06-03T16:54:06.699Z,1717433646.699 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:54:17.485Z,1717433657.485 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240603T151131/Express0056.lzma
2024-06-03T16:54:18.494Z,1717433658.494 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0056.lzma.bak
2024-06-03T16:54:18.495Z,1717433658.495 [DataOverHttps](INFO): SBD MOMSN=21112286
2024-06-03T16:54:19.650Z,1717433659.650 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:54:19.650Z,1717433659.650 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:54:19.650Z,1717433659.650 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T16:54:37.456Z,1717433677.456 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T16:59:20.420Z,1717433960.420 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T16:59:20.420Z,1717433960.420 [Default:CheckIn:C.Wait] Stopped
2024-06-03T16:59:20.420Z,1717433960.420 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T16:59:20.420Z,1717433960.420 [Default:CheckIn:D] Running Loop=1
2024-06-03T16:59:20.819Z,1717433960.819 [Default:CheckIn:D] Stopped
2024-06-03T16:59:20.819Z,1717433960.819 [Default:CheckIn:E] Running Loop=1
2024-06-03T16:59:21.234Z,1717433961.234 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.326742 min
2024-06-03T16:59:21.234Z,1717433961.234 [Default:CheckIn:E] Stopped
2024-06-03T16:59:21.234Z,1717433961.234 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T16:59:21.234Z,1717433961.234 [Default:CheckIn] Stopped
2024-06-03T16:59:21.234Z,1717433961.234 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T16:59:21.234Z,1717433961.234 [Default:CheckIn](INFO): Running loop #18
2024-06-03T16:59:21.234Z,1717433961.234 [Default:CheckIn] Running Loop=18
2024-06-03T16:59:21.234Z,1717433961.234 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T16:59:21.235Z,1717433961.235 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T16:59:23.234Z,1717433963.234 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165922.00,A,3648.14216,N,12147.22918,W,0.000,0.00,030624,,,D*79
2024-06-03T16:59:23.236Z,1717433963.236 [NAL9602](INFO): GPS fix at 20240603T165922: (36.802369, -121.787153)
2024-06-03T16:59:23.260Z,1717433963.260 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T16:59:23.261Z,1717433963.261 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T16:59:30.528Z,1717433970.528 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0058.lzma
2024-06-03T16:59:31.530Z,1717433971.530 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0058.lzma.bak
2024-06-03T16:59:31.531Z,1717433971.531 [DataOverHttps](INFO): SBD MOMSN=21112296
2024-06-03T16:59:43.851Z,1717433983.851 [NAL9602](INFO): SBD MO Status=0, MOMSN=51631, MT Status=0, MTMSN=0
2024-06-03T16:59:43.851Z,1717433983.851 [NAL9602](INFO): No messages in MT queue
2024-06-03T16:59:47.404Z,1717433987.404 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0059.lzma
2024-06-03T16:59:48.406Z,1717433988.406 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0059.lzma.bak
2024-06-03T16:59:48.407Z,1717433988.407 [DataOverHttps](INFO): SBD MOMSN=21112299
2024-06-03T16:59:49.517Z,1717433989.517 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T16:59:49.517Z,1717433989.517 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T16:59:49.517Z,1717433989.517 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:00:14.558Z,1717434014.558 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:04:50.188Z,1717434290.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:04:50.188Z,1717434290.188 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:04:50.188Z,1717434290.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:04:50.188Z,1717434290.188 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:04:50.588Z,1717434290.588 [Default:CheckIn:D] Stopped
2024-06-03T17:04:50.588Z,1717434290.588 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:04:50.993Z,1717434290.993 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.822900 min
2024-06-03T17:04:50.994Z,1717434290.994 [Default:CheckIn:E] Stopped
2024-06-03T17:04:50.994Z,1717434290.994 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:04:50.994Z,1717434290.994 [Default:CheckIn] Stopped
2024-06-03T17:04:50.994Z,1717434290.994 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:04:50.994Z,1717434290.994 [Default:CheckIn](INFO): Running loop #19
2024-06-03T17:04:50.994Z,1717434290.994 [Default:CheckIn] Running Loop=19
2024-06-03T17:04:50.994Z,1717434290.994 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:04:50.994Z,1717434290.994 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:04:52.990Z,1717434292.990 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170452.00,A,3648.14165,N,12147.22961,W,0.000,0.00,030624,,,D*7E
2024-06-03T17:04:52.993Z,1717434292.993 [NAL9602](INFO): GPS fix at 20240603T170452: (36.802361, -121.787160)
2024-06-03T17:04:53.041Z,1717434293.041 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:04:53.041Z,1717434293.041 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:05:00.552Z,1717434300.552 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240603T151131/Courier0061.lzma
2024-06-03T17:05:01.555Z,1717434301.555 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0061.lzma.bak
2024-06-03T17:05:01.555Z,1717434301.555 [DataOverHttps](INFO): SBD MOMSN=21112317
2024-06-03T17:05:15.615Z,1717434315.615 [NAL9602](INFO): SBD MO Status=0, MOMSN=51632, MT Status=0, MTMSN=0
2024-06-03T17:05:15.615Z,1717434315.615 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:05:17.584Z,1717434317.584 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0062.lzma
2024-06-03T17:05:18.586Z,1717434318.586 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0062.lzma.bak
2024-06-03T17:05:18.587Z,1717434318.587 [DataOverHttps](INFO): SBD MOMSN=21112320
2024-06-03T17:05:19.673Z,1717434319.673 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:05:19.673Z,1717434319.673 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:05:19.673Z,1717434319.673 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:05:46.317Z,1717434346.317 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:10:20.367Z,1717434620.367 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:10:20.368Z,1717434620.368 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:10:20.368Z,1717434620.368 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:10:20.368Z,1717434620.368 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:10:20.768Z,1717434620.768 [Default:CheckIn:D] Stopped
2024-06-03T17:10:20.768Z,1717434620.768 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:10:21.161Z,1717434621.161 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.325903 min
2024-06-03T17:10:21.161Z,1717434621.161 [Default:CheckIn:E] Stopped
2024-06-03T17:10:21.161Z,1717434621.161 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:10:21.162Z,1717434621.162 [Default:CheckIn] Stopped
2024-06-03T17:10:21.162Z,1717434621.162 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:10:21.162Z,1717434621.162 [Default:CheckIn](INFO): Running loop #20
2024-06-03T17:10:21.162Z,1717434621.162 [Default:CheckIn] Running Loop=20
2024-06-03T17:10:21.162Z,1717434621.162 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:10:21.162Z,1717434621.162 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:10:23.171Z,1717434623.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171022.00,A,3648.14168,N,12147.22841,W,0.019,0.00,030624,,,D*7A
2024-06-03T17:10:23.173Z,1717434623.173 [NAL9602](INFO): GPS fix at 20240603T171022: (36.802361, -121.787140)
2024-06-03T17:10:23.241Z,1717434623.241 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:10:23.241Z,1717434623.241 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:10:30.904Z,1717434630.904 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0064.lzma
2024-06-03T17:10:31.906Z,1717434631.906 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0064.lzma.bak
2024-06-03T17:10:31.907Z,1717434631.907 [DataOverHttps](INFO): SBD MOMSN=21112330
2024-06-03T17:10:46.207Z,1717434646.207 [NAL9602](INFO): SBD MO Status=2, MOMSN=51633, MT Status=2, MTMSN=0
2024-06-03T17:10:46.207Z,1717434646.207 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-03T17:10:47.816Z,1717434647.816 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240603T151131/Express0065.lzma
2024-06-03T17:10:48.819Z,1717434648.819 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0065.lzma.bak
2024-06-03T17:10:48.819Z,1717434648.819 [DataOverHttps](INFO): SBD MOMSN=21112333
2024-06-03T17:10:50.272Z,1717434650.272 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:10:50.272Z,1717434650.272 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:10:50.272Z,1717434650.272 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:11:07.670Z,1717434667.670 [NAL9602](INFO): SBD MO Status=2, MOMSN=51633, MT Status=2, MTMSN=0
2024-06-03T17:11:07.670Z,1717434667.670 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-03T17:11:36.763Z,1717434696.763 [NAL9602](INFO): SBD MO Status=0, MOMSN=51633, MT Status=0, MTMSN=0
2024-06-03T17:11:36.763Z,1717434696.763 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:12:07.462Z,1717434727.462 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:15:50.921Z,1717434950.921 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:15:50.921Z,1717434950.921 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:15:50.921Z,1717434950.921 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:15:50.921Z,1717434950.921 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:15:51.320Z,1717434951.320 [Default:CheckIn:D] Stopped
2024-06-03T17:15:51.320Z,1717434951.320 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:15:51.720Z,1717434951.720 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.835091 min
2024-06-03T17:15:51.720Z,1717434951.720 [Default:CheckIn:E] Stopped
2024-06-03T17:15:51.720Z,1717434951.720 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:15:51.720Z,1717434951.720 [Default:CheckIn] Stopped
2024-06-03T17:15:51.720Z,1717434951.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:15:51.720Z,1717434951.720 [Default:CheckIn](INFO): Running loop #21
2024-06-03T17:15:51.720Z,1717434951.720 [Default:CheckIn] Running Loop=21
2024-06-03T17:15:51.721Z,1717434951.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:15:51.721Z,1717434951.721 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:15:53.732Z,1717434953.732 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171552.00,A,3648.14116,N,12147.22758,W,0.058,200.21,030624,,,D*72
2024-06-03T17:15:53.734Z,1717434953.734 [NAL9602](INFO): GPS fix at 20240603T171552: (36.802353, -121.787126)
2024-06-03T17:15:53.745Z,1717434953.745 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:15:53.745Z,1717434953.745 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:16:01.325Z,1717434961.325 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240603T151131/Courier0067.lzma
2024-06-03T17:16:02.327Z,1717434962.327 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0067.lzma.bak
2024-06-03T17:16:02.327Z,1717434962.327 [DataOverHttps](INFO): SBD MOMSN=21112344
2024-06-03T17:16:18.305Z,1717434978.305 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0068.lzma
2024-06-03T17:16:19.307Z,1717434979.307 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0068.lzma.bak
2024-06-03T17:16:19.307Z,1717434979.307 [DataOverHttps](INFO): SBD MOMSN=21112347
2024-06-03T17:16:19.612Z,1717434979.612 [NAL9602](INFO): SBD MO Status=0, MOMSN=51634, MT Status=0, MTMSN=0
2024-06-03T17:16:19.612Z,1717434979.612 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:16:20.413Z,1717434980.413 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:16:20.413Z,1717434980.413 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:16:20.413Z,1717434980.413 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:16:50.355Z,1717435010.355 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:21:20.828Z,1717435280.828 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:21:20.828Z,1717435280.828 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:21:20.828Z,1717435280.828 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:21:20.828Z,1717435280.828 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:21:21.240Z,1717435281.240 [Default:CheckIn:D] Stopped
2024-06-03T17:21:21.240Z,1717435281.240 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:21:21.627Z,1717435281.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.333765 min
2024-06-03T17:21:21.628Z,1717435281.628 [Default:CheckIn:E] Stopped
2024-06-03T17:21:21.628Z,1717435281.628 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:21:21.628Z,1717435281.628 [Default:CheckIn] Stopped
2024-06-03T17:21:21.628Z,1717435281.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:21:21.628Z,1717435281.628 [Default:CheckIn](INFO): Running loop #22
2024-06-03T17:21:21.628Z,1717435281.628 [Default:CheckIn] Running Loop=22
2024-06-03T17:21:21.628Z,1717435281.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:21:21.628Z,1717435281.628 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:21:23.644Z,1717435283.644 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172122.00,A,3648.14165,N,12147.22980,W,0.019,200.21,030624,,,A*7D
2024-06-03T17:21:23.646Z,1717435283.646 [NAL9602](INFO): GPS fix at 20240603T172122: (36.802361, -121.787163)
2024-06-03T17:21:23.657Z,1717435283.657 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:21:23.657Z,1717435283.657 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:21:30.576Z,1717435290.576 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0070.lzma
2024-06-03T17:21:31.579Z,1717435291.579 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0070.lzma.bak
2024-06-03T17:21:31.579Z,1717435291.579 [DataOverHttps](INFO): SBD MOMSN=21112357
2024-06-03T17:21:38.995Z,1717435298.995 [NAL9602](INFO): SBD MO Status=0, MOMSN=51635, MT Status=0, MTMSN=0
2024-06-03T17:21:38.995Z,1717435298.995 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:21:47.404Z,1717435307.404 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240603T151131/Express0071.lzma
2024-06-03T17:21:48.407Z,1717435308.407 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0071.lzma.bak
2024-06-03T17:21:48.407Z,1717435308.407 [DataOverHttps](INFO): SBD MOMSN=21112360
2024-06-03T17:21:49.513Z,1717435309.513 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:21:49.513Z,1717435309.513 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:21:49.513Z,1717435309.513 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:22:09.697Z,1717435329.697 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:23:37.451Z,1717435417.451 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T17:23:50.004Z,1717435430.004 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T17:24:02.501Z,1717435442.501 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T17:24:15.831Z,1717435455.831 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T17:24:28.355Z,1717435468.355 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T17:24:41.687Z,1717435481.687 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T17:24:54.632Z,1717435494.632 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-03T17:26:50.193Z,1717435610.193 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:26:50.193Z,1717435610.193 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:26:50.193Z,1717435610.193 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:26:50.193Z,1717435610.193 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:26:50.600Z,1717435610.600 [Default:CheckIn:D] Stopped
2024-06-03T17:26:50.600Z,1717435610.600 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:26:51.004Z,1717435611.004 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.823096 min
2024-06-03T17:26:51.004Z,1717435611.004 [Default:CheckIn:E] Stopped
2024-06-03T17:26:51.005Z,1717435611.005 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:26:51.005Z,1717435611.005 [Default:CheckIn] Stopped
2024-06-03T17:26:51.005Z,1717435611.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:26:51.005Z,1717435611.005 [Default:CheckIn](INFO): Running loop #23
2024-06-03T17:26:51.005Z,1717435611.005 [Default:CheckIn] Running Loop=23
2024-06-03T17:26:51.005Z,1717435611.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:26:51.005Z,1717435611.005 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:26:53.005Z,1717435613.005 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172652.00,A,3648.14240,N,12147.22695,W,0.019,200.21,030624,,,A*72
2024-06-03T17:26:53.007Z,1717435613.007 [NAL9602](INFO): GPS fix at 20240603T172652: (36.802373, -121.787116)
2024-06-03T17:26:53.026Z,1717435613.026 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:26:53.026Z,1717435613.026 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:26:53.825Z,1717435613.825 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:26:53.825Z,1717435613.825 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:26:53.825Z,1717435613.825 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:27:20.096Z,1717435640.096 [NAL9602](INFO): SBD MO Status=0, MOMSN=51636, MT Status=0, MTMSN=0
2024-06-03T17:27:20.096Z,1717435640.096 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:27:35.060Z,1717435655.060 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-06-03T17:27:35.067Z,1717435655.067 [BPC1](INFO): Received data from all battery sticks.
2024-06-03T17:27:50.797Z,1717435670.797 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:31:54.424Z,1717435914.424 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:31:54.425Z,1717435914.425 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:31:54.425Z,1717435914.425 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:31:54.425Z,1717435914.425 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:31:54.834Z,1717435914.834 [Default:CheckIn:D] Stopped
2024-06-03T17:31:54.834Z,1717435914.834 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:31:55.243Z,1717435915.243 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.893652 min
2024-06-03T17:31:55.243Z,1717435915.243 [Default:CheckIn:E] Stopped
2024-06-03T17:31:55.244Z,1717435915.244 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:31:55.244Z,1717435915.244 [Default:CheckIn] Stopped
2024-06-03T17:31:55.244Z,1717435915.244 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:31:55.244Z,1717435915.244 [Default:CheckIn](INFO): Running loop #24
2024-06-03T17:31:55.244Z,1717435915.244 [Default:CheckIn] Running Loop=24
2024-06-03T17:31:55.244Z,1717435915.244 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:31:55.244Z,1717435915.244 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:31:57.243Z,1717435917.243 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173156.00,A,3648.14185,N,12147.22674,W,0.019,200.21,030624,,,A*75
2024-06-03T17:31:57.246Z,1717435917.246 [NAL9602](INFO): GPS fix at 20240603T173156: (36.802364, -121.787112)
2024-06-03T17:31:57.277Z,1717435917.277 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:31:57.277Z,1717435917.277 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:32:04.232Z,1717435924.232 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240603T151131/Courier0073.lzma
2024-06-03T17:32:05.235Z,1717435925.235 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0073.lzma.bak
2024-06-03T17:32:05.235Z,1717435925.235 [DataOverHttps](INFO): SBD MOMSN=21112378
2024-06-03T17:32:18.671Z,1717435938.671 [NAL9602](INFO): SBD MO Status=2, MOMSN=51637, MT Status=2, MTMSN=0
2024-06-03T17:32:18.671Z,1717435938.671 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-03T17:32:23.708Z,1717435943.708 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240603T151131/Courier0076.lzma
2024-06-03T17:32:24.711Z,1717435944.711 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0076.lzma.bak
2024-06-03T17:32:24.711Z,1717435944.711 [DataOverHttps](INFO): SBD MOMSN=21112381
2024-06-03T17:32:41.240Z,1717435961.240 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240603T151131/Express0074.lzma
2024-06-03T17:32:42.120Z,1717435962.120 [NAL9602](INFO): SBD MO Status=0, MOMSN=51637, MT Status=0, MTMSN=0
2024-06-03T17:32:42.120Z,1717435962.120 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:32:42.243Z,1717435962.243 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0074.lzma.bak
2024-06-03T17:32:42.243Z,1717435962.243 [DataOverHttps](INFO): SBD MOMSN=21112384
2024-06-03T17:33:01.204Z,1717435981.204 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0077.lzma
2024-06-03T17:33:02.207Z,1717435982.207 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0077.lzma.bak
2024-06-03T17:33:02.207Z,1717435982.207 [DataOverHttps](INFO): SBD MOMSN=21112394
2024-06-03T17:33:03.565Z,1717435983.565 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:33:03.565Z,1717435983.565 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:33:03.565Z,1717435983.565 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:33:12.854Z,1717435992.854 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:38:04.154Z,1717436284.154 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:38:04.154Z,1717436284.154 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:38:04.154Z,1717436284.154 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:38:04.154Z,1717436284.154 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:38:04.548Z,1717436284.548 [Default:CheckIn:D] Stopped
2024-06-03T17:38:04.548Z,1717436284.548 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:38:04.952Z,1717436284.952 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.055566 min
2024-06-03T17:38:04.952Z,1717436284.952 [Default:CheckIn:E] Stopped
2024-06-03T17:38:04.952Z,1717436284.952 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:38:04.952Z,1717436284.952 [Default:CheckIn] Stopped
2024-06-03T17:38:04.953Z,1717436284.953 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:38:04.953Z,1717436284.953 [Default:CheckIn](INFO): Running loop #25
2024-06-03T17:38:04.953Z,1717436284.953 [Default:CheckIn] Running Loop=25
2024-06-03T17:38:04.953Z,1717436284.953 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:38:04.953Z,1717436284.953 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:38:06.970Z,1717436286.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173806.00,A,3648.14153,N,12147.22729,W,0.136,200.21,030624,,,D*72
2024-06-03T17:38:06.972Z,1717436286.972 [NAL9602](INFO): GPS fix at 20240603T173806: (36.802359, -121.787121)
2024-06-03T17:38:06.990Z,1717436286.990 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:38:06.990Z,1717436286.990 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:38:13.876Z,1717436293.876 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0079.lzma
2024-06-03T17:38:14.878Z,1717436294.878 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0079.lzma.bak
2024-06-03T17:38:14.879Z,1717436294.879 [DataOverHttps](INFO): SBD MOMSN=21112402
2024-06-03T17:38:21.561Z,1717436301.561 [NAL9602](INFO): SBD MO Status=0, MOMSN=51638, MT Status=0, MTMSN=0
2024-06-03T17:38:21.561Z,1717436301.561 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:38:33.608Z,1717436313.608 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240603T151131/Express0080.lzma
2024-06-03T17:38:34.611Z,1717436314.611 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0080.lzma.bak
2024-06-03T17:38:34.611Z,1717436314.611 [DataOverHttps](INFO): SBD MOMSN=21112408
2024-06-03T17:38:35.737Z,1717436315.737 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:38:35.737Z,1717436315.737 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:38:35.737Z,1717436315.737 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:38:52.253Z,1717436332.253 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:43:36.285Z,1717436616.285 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:43:36.285Z,1717436616.285 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:43:36.285Z,1717436616.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:43:36.285Z,1717436616.285 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:43:36.702Z,1717436616.702 [Default:CheckIn:D] Stopped
2024-06-03T17:43:36.702Z,1717436616.702 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:43:37.096Z,1717436617.096 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.591455 min
2024-06-03T17:43:37.096Z,1717436617.096 [Default:CheckIn:E] Stopped
2024-06-03T17:43:37.096Z,1717436617.096 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:43:37.096Z,1717436617.096 [Default:CheckIn] Stopped
2024-06-03T17:43:37.096Z,1717436617.096 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:43:37.097Z,1717436617.097 [Default:CheckIn](INFO): Running loop #26
2024-06-03T17:43:37.097Z,1717436617.097 [Default:CheckIn] Running Loop=26
2024-06-03T17:43:37.097Z,1717436617.097 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:43:37.097Z,1717436617.097 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:43:39.111Z,1717436619.111 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174338.00,A,3648.14122,N,12147.22828,W,0.019,200.21,030624,,,D*77
2024-06-03T17:43:39.113Z,1717436619.113 [NAL9602](INFO): GPS fix at 20240603T174338: (36.802354, -121.787138)
2024-06-03T17:43:39.124Z,1717436619.124 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:43:39.125Z,1717436619.125 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:43:46.100Z,1717436626.100 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240603T151131/Courier0082.lzma
2024-06-03T17:43:47.102Z,1717436627.102 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0082.lzma.bak
2024-06-03T17:43:47.103Z,1717436627.103 [DataOverHttps](INFO): SBD MOMSN=21112419
2024-06-03T17:43:50.903Z,1717436630.903 [NAL9602](INFO): SBD MO Status=0, MOMSN=51639, MT Status=0, MTMSN=0
2024-06-03T17:43:50.903Z,1717436630.903 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:44:02.948Z,1717436642.948 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240603T151131/Express0083.lzma
2024-06-03T17:44:03.951Z,1717436643.951 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0083.lzma.bak
2024-06-03T17:44:03.951Z,1717436643.951 [DataOverHttps](INFO): SBD MOMSN=21112422
2024-06-03T17:44:05.056Z,1717436645.056 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:44:05.056Z,1717436645.056 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:44:05.056Z,1717436645.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:44:21.606Z,1717436661.606 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:49:05.632Z,1717436945.632 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:49:05.632Z,1717436945.632 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:49:05.632Z,1717436945.632 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:49:05.632Z,1717436945.632 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:49:06.041Z,1717436946.041 [Default:CheckIn:D] Stopped
2024-06-03T17:49:06.041Z,1717436946.041 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:49:06.449Z,1717436946.449 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.080452 min
2024-06-03T17:49:06.449Z,1717436946.449 [Default:CheckIn:E] Stopped
2024-06-03T17:49:06.449Z,1717436946.449 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:49:06.449Z,1717436946.449 [Default:CheckIn] Stopped
2024-06-03T17:49:06.449Z,1717436946.449 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:49:06.449Z,1717436946.449 [Default:CheckIn](INFO): Running loop #27
2024-06-03T17:49:06.449Z,1717436946.449 [Default:CheckIn] Running Loop=27
2024-06-03T17:49:06.449Z,1717436946.449 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:49:06.449Z,1717436946.449 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:49:08.450Z,1717436948.450 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174907.00,A,3648.14156,N,12147.22900,W,0.058,200.21,030624,,,A*79
2024-06-03T17:49:08.453Z,1717436948.453 [NAL9602](INFO): GPS fix at 20240603T174907: (36.802359, -121.787150)
2024-06-03T17:49:08.464Z,1717436948.464 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:49:08.464Z,1717436948.464 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:49:15.713Z,1717436955.713 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0085.lzma
2024-06-03T17:49:16.714Z,1717436956.714 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0085.lzma.bak
2024-06-03T17:49:16.715Z,1717436956.715 [DataOverHttps](INFO): SBD MOMSN=21112432
2024-06-03T17:49:26.223Z,1717436966.223 [NAL9602](INFO): SBD MO Status=0, MOMSN=51640, MT Status=0, MTMSN=0
2024-06-03T17:49:26.223Z,1717436966.223 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:49:32.656Z,1717436972.656 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0086.lzma
2024-06-03T17:49:33.659Z,1717436973.659 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0086.lzma.bak
2024-06-03T17:49:33.659Z,1717436973.659 [DataOverHttps](INFO): SBD MOMSN=21112435
2024-06-03T17:49:34.720Z,1717436974.720 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:49:34.720Z,1717436974.720 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:49:34.720Z,1717436974.720 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:49:56.929Z,1717436996.929 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T17:54:35.303Z,1717437275.303 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T17:54:35.303Z,1717437275.303 [Default:CheckIn:C.Wait] Stopped
2024-06-03T17:54:35.304Z,1717437275.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T17:54:35.305Z,1717437275.305 [Default:CheckIn:D] Running Loop=1
2024-06-03T17:54:35.718Z,1717437275.718 [Default:CheckIn:D] Stopped
2024-06-03T17:54:35.718Z,1717437275.718 [Default:CheckIn:E] Running Loop=1
2024-06-03T17:54:36.133Z,1717437276.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.575065 min
2024-06-03T17:54:36.133Z,1717437276.133 [Default:CheckIn:E] Stopped
2024-06-03T17:54:36.133Z,1717437276.133 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T17:54:36.133Z,1717437276.133 [Default:CheckIn] Stopped
2024-06-03T17:54:36.133Z,1717437276.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T17:54:36.133Z,1717437276.133 [Default:CheckIn](INFO): Running loop #28
2024-06-03T17:54:36.133Z,1717437276.133 [Default:CheckIn] Running Loop=28
2024-06-03T17:54:36.133Z,1717437276.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T17:54:36.133Z,1717437276.133 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T17:54:38.121Z,1717437278.121 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175437.00,A,3648.14026,N,12147.23014,W,0.117,132.59,030624,,,A*7A
2024-06-03T17:54:38.123Z,1717437278.123 [NAL9602](INFO): GPS fix at 20240603T175437: (36.802338, -121.787169)
2024-06-03T17:54:38.135Z,1717437278.135 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T17:54:38.135Z,1717437278.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T17:54:45.400Z,1717437285.400 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0088.lzma
2024-06-03T17:54:46.402Z,1717437286.402 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0088.lzma.bak
2024-06-03T17:54:46.403Z,1717437286.403 [DataOverHttps](INFO): SBD MOMSN=21112445
2024-06-03T17:54:53.543Z,1717437293.543 [NAL9602](INFO): SBD MO Status=0, MOMSN=51641, MT Status=0, MTMSN=0
2024-06-03T17:54:53.543Z,1717437293.543 [NAL9602](INFO): No messages in MT queue
2024-06-03T17:55:04.865Z,1717437304.865 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240603T151131/Express0089.lzma
2024-06-03T17:55:05.867Z,1717437305.867 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0089.lzma.bak
2024-06-03T17:55:05.867Z,1717437305.867 [DataOverHttps](INFO): SBD MOMSN=21112449
2024-06-03T17:55:06.901Z,1717437306.901 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T17:55:06.902Z,1717437306.902 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T17:55:06.902Z,1717437306.902 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T17:55:24.245Z,1717437324.245 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T18:00:07.456Z,1717437607.456 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T18:00:07.456Z,1717437607.456 [Default:CheckIn:C.Wait] Stopped
2024-06-03T18:00:07.456Z,1717437607.456 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T18:00:07.456Z,1717437607.456 [Default:CheckIn:D] Running Loop=1
2024-06-03T18:00:07.864Z,1717437607.864 [Default:CheckIn:D] Stopped
2024-06-03T18:00:07.865Z,1717437607.865 [Default:CheckIn:E] Running Loop=1
2024-06-03T18:00:08.266Z,1717437608.266 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.110840 min
2024-06-03T18:00:08.266Z,1717437608.266 [Default:CheckIn:E] Stopped
2024-06-03T18:00:08.266Z,1717437608.266 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T18:00:08.266Z,1717437608.266 [Default:CheckIn] Stopped
2024-06-03T18:00:08.266Z,1717437608.266 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T18:00:08.266Z,1717437608.266 [Default:CheckIn](INFO): Running loop #29
2024-06-03T18:00:08.266Z,1717437608.266 [Default:CheckIn] Running Loop=29
2024-06-03T18:00:08.267Z,1717437608.267 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T18:00:08.267Z,1717437608.267 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T18:00:10.276Z,1717437610.276 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180009.00,A,3648.14179,N,12147.22635,W,0.058,132.59,030624,,,A*7C
2024-06-03T18:00:10.278Z,1717437610.278 [NAL9602](INFO): GPS fix at 20240603T180009: (36.802363, -121.787106)
2024-06-03T18:00:10.309Z,1717437610.309 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T18:00:10.309Z,1717437610.309 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T18:00:17.564Z,1717437617.564 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0091.lzma
2024-06-03T18:00:18.567Z,1717437618.567 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0091.lzma.bak
2024-06-03T18:00:18.567Z,1717437618.567 [DataOverHttps](INFO): SBD MOMSN=21112458
2024-06-03T18:00:23.631Z,1717437623.631 [NAL9602](INFO): SBD MO Status=0, MOMSN=51642, MT Status=0, MTMSN=0
2024-06-03T18:00:23.632Z,1717437623.632 [NAL9602](INFO): No messages in MT queue
2024-06-03T18:00:37.152Z,1717437637.152 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240603T151131/Express0092.lzma
2024-06-03T18:00:38.156Z,1717437638.156 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0092.lzma.bak
2024-06-03T18:00:38.156Z,1717437638.156 [DataOverHttps](INFO): SBD MOMSN=21112461
2024-06-03T18:00:39.411Z,1717437639.411 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T18:00:39.411Z,1717437639.411 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T18:00:39.411Z,1717437639.411 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T18:00:54.349Z,1717437654.349 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T18:05:40.057Z,1717437940.057 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T18:05:40.057Z,1717437940.057 [Default:CheckIn:C.Wait] Stopped
2024-06-03T18:05:40.057Z,1717437940.057 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T18:05:40.057Z,1717437940.057 [Default:CheckIn:D] Running Loop=1
2024-06-03T18:05:40.468Z,1717437940.468 [Default:CheckIn:D] Stopped
2024-06-03T18:05:40.468Z,1717437940.468 [Default:CheckIn:E] Running Loop=1
2024-06-03T18:05:40.864Z,1717437940.864 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 170.654232 min
2024-06-03T18:05:40.865Z,1717437940.865 [Default:CheckIn:E] Stopped
2024-06-03T18:05:40.865Z,1717437940.865 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T18:05:40.865Z,1717437940.865 [Default:CheckIn] Stopped
2024-06-03T18:05:40.865Z,1717437940.865 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T18:05:40.865Z,1717437940.865 [Default:CheckIn](INFO): Running loop #30
2024-06-03T18:05:40.865Z,1717437940.865 [Default:CheckIn] Running Loop=30
2024-06-03T18:05:40.865Z,1717437940.865 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T18:05:40.865Z,1717437940.865 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T18:05:42.875Z,1717437942.875 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180542.00,A,3648.14210,N,12147.22796,W,0.097,132.59,030624,,,D*74
2024-06-03T18:05:42.878Z,1717437942.878 [NAL9602](INFO): GPS fix at 20240603T180542: (36.802368, -121.787133)
2024-06-03T18:05:42.889Z,1717437942.889 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T18:05:42.889Z,1717437942.889 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T18:05:50.576Z,1717437950.576 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0094.lzma
2024-06-03T18:05:51.579Z,1717437951.579 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0094.lzma.bak
2024-06-03T18:05:51.579Z,1717437951.579 [DataOverHttps](INFO): SBD MOMSN=21112471
2024-06-03T18:06:07.577Z,1717437967.577 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240603T151131/Express0095.lzma
2024-06-03T18:06:08.579Z,1717437968.579 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0095.lzma.bak
2024-06-03T18:06:08.579Z,1717437968.579 [DataOverHttps](INFO): SBD MOMSN=21112474
2024-06-03T18:06:09.963Z,1717437969.963 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T18:06:09.963Z,1717437969.963 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T18:06:09.963Z,1717437969.963 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T18:06:27.723Z,1717437987.723 [NAL9602](INFO): SBD MO Status=2, MOMSN=51643, MT Status=2, MTMSN=0
2024-06-03T18:06:27.723Z,1717437987.723 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-03T18:06:35.003Z,1717437995.003 [NAL9602](INFO): SBD MO Status=0, MOMSN=51643, MT Status=0, MTMSN=0
2024-06-03T18:06:35.003Z,1717437995.003 [NAL9602](INFO): No messages in MT queue
2024-06-03T18:07:05.694Z,1717438025.694 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T18:11:10.587Z,1717438270.587 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T18:11:10.588Z,1717438270.588 [Default:CheckIn:C.Wait] Stopped
2024-06-03T18:11:10.588Z,1717438270.588 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T18:11:10.588Z,1717438270.588 [Default:CheckIn:D] Running Loop=1
2024-06-03T18:11:10.998Z,1717438270.998 [Default:CheckIn:D] Stopped
2024-06-03T18:11:10.998Z,1717438270.998 [Default:CheckIn:E] Running Loop=1
2024-06-03T18:11:11.392Z,1717438271.392 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.163053 min
2024-06-03T18:11:11.392Z,1717438271.392 [Default:CheckIn:E] Stopped
2024-06-03T18:11:11.393Z,1717438271.393 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T18:11:11.393Z,1717438271.393 [Default:CheckIn] Stopped
2024-06-03T18:11:11.393Z,1717438271.393 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T18:11:11.393Z,1717438271.393 [Default:CheckIn](INFO): Running loop #31
2024-06-03T18:11:11.393Z,1717438271.393 [Default:CheckIn] Running Loop=31
2024-06-03T18:11:11.393Z,1717438271.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T18:11:11.393Z,1717438271.393 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T18:11:13.404Z,1717438273.404 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181112.00,A,3648.14215,N,12147.22935,W,0.058,132.59,030624,,,D*75
2024-06-03T18:11:13.406Z,1717438273.406 [NAL9602](INFO): GPS fix at 20240603T181112: (36.802369, -121.787156)
2024-06-03T18:11:13.417Z,1717438273.417 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T18:11:13.417Z,1717438273.417 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T18:11:20.672Z,1717438280.672 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240603T151131/Courier0097.lzma
2024-06-03T18:11:21.675Z,1717438281.675 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0097.lzma.bak
2024-06-03T18:11:21.675Z,1717438281.675 [DataOverHttps](INFO): SBD MOMSN=21112485
2024-06-03T18:11:32.422Z,1717438292.422 [NAL9602](INFO): SBD MO Status=0, MOMSN=51644, MT Status=0, MTMSN=0
2024-06-03T18:11:32.422Z,1717438292.422 [NAL9602](INFO): No messages in MT queue
2024-06-03T18:11:37.657Z,1717438297.657 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0098.lzma
2024-06-03T18:11:38.659Z,1717438298.659 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0098.lzma.bak
2024-06-03T18:11:38.659Z,1717438298.659 [DataOverHttps](INFO): SBD MOMSN=21112488
2024-06-03T18:11:39.691Z,1717438299.691 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T18:11:39.691Z,1717438299.691 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T18:11:39.691Z,1717438299.691 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T18:12:03.088Z,1717438323.088 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T18:16:40.325Z,1717438600.325 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-03T18:16:40.325Z,1717438600.325 [Default:CheckIn:C.Wait] Stopped
2024-06-03T18:16:40.325Z,1717438600.325 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T18:16:40.325Z,1717438600.325 [Default:CheckIn:D] Running Loop=1
2024-06-03T18:16:40.739Z,1717438600.739 [Default:CheckIn:D] Stopped
2024-06-03T18:16:40.739Z,1717438600.739 [Default:CheckIn:E] Running Loop=1
2024-06-03T18:16:41.136Z,1717438601.136 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 181.658757 min
2024-06-03T18:16:41.136Z,1717438601.136 [Default:CheckIn:E] Stopped
2024-06-03T18:16:41.137Z,1717438601.137 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-03T18:16:41.137Z,1717438601.137 [Default:CheckIn] Stopped
2024-06-03T18:16:41.137Z,1717438601.137 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T18:16:41.137Z,1717438601.137 [Default:CheckIn](INFO): Running loop #32
2024-06-03T18:16:41.137Z,1717438601.137 [Default:CheckIn] Running Loop=32
2024-06-03T18:16:41.137Z,1717438601.137 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-03T18:16:41.137Z,1717438601.137 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-03T18:16:43.148Z,1717438603.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181642.00,A,3648.14195,N,12147.22788,W,0.058,132.59,030624,,,D*74
2024-06-03T18:16:43.150Z,1717438603.150 [NAL9602](INFO): GPS fix at 20240603T181642: (36.802366, -121.787131)
2024-06-03T18:16:43.161Z,1717438603.161 [Default:CheckIn:Read_GPS] Stopped
2024-06-03T18:16:43.161Z,1717438603.161 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-03T18:16:52.885Z,1717438612.885 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T151131/Courier0100.lzma
2024-06-03T18:16:53.887Z,1717438613.887 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Courier0100.lzma.bak
2024-06-03T18:16:53.887Z,1717438613.887 [DataOverHttps](INFO): SBD MOMSN=21112498
2024-06-03T18:17:06.207Z,1717438626.207 [NAL9602](INFO): SBD MO Status=0, MOMSN=51645, MT Status=0, MTMSN=0
2024-06-03T18:17:06.207Z,1717438626.207 [NAL9602](INFO): No messages in MT queue
2024-06-03T18:17:10.949Z,1717438630.949 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T151131/Express0101.lzma
2024-06-03T18:17:11.951Z,1717438631.951 [DataOverHttps](INFO): Moved sent file to Logs/20240603T151131/Express0101.lzma.bak
2024-06-03T18:17:11.951Z,1717438631.951 [DataOverHttps](INFO): SBD MOMSN=21112501
2024-06-03T18:17:13.085Z,1717438633.085 [Default:CheckIn:Read_Iridium] Stopped
2024-06-03T18:17:13.085Z,1717438633.085 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-03T18:17:13.085Z,1717438633.085 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-03T18:17:36.901Z,1717438656.901 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-03T18:18:23.230Z,1717438703.230 [DataOverHttps](IMPORTANT): SBD MTMSN=20240603T181822
2024-06-03T18:18:32.168Z,1717438712.168 [DataOverHttps](INFO): Received command: restart sys
2024-06-03T18:18:32.218Z,1717438712.218 [CommandExec](IMPORTANT): got command restart system
2024-06-03T18:18:34.276Z,1717438714.276 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-06-03T18:18:34.277Z,1717438714.277 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:34.278Z,1717438714.278 [CommandExec](INFO): Uninitializing the command executive.
2024-06-03T18:18:34.278Z,1717438714.278 [CommandExec](INFO): Uninitializing the command scheduler.
2024-06-03T18:18:34.278Z,1717438714.278 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:34.456Z,1717438714.456 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-06-03T18:18:34.457Z,1717438714.457 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-06-03T18:18:34.457Z,1717438714.457 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:34.457Z,1717438714.457 [NavChartDb](INFO): Join timeout helper Thread ID is 1871
2024-06-03T18:18:34.685Z,1717438714.685 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:34.686Z,1717438714.686 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:34.705Z,1717438714.705 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-06-03T18:18:34.705Z,1717438714.705 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:34.705Z,1717438714.705 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1872
2024-06-03T18:18:34.853Z,1717438714.853 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:34.853Z,1717438714.853 [WetLabsBB2FL](INFO): Powering down
2024-06-03T18:18:34.854Z,1717438714.854 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:34.868Z,1717438714.868 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-06-03T18:18:34.869Z,1717438714.869 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:34.869Z,1717438714.869 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1873
2024-06-03T18:18:35.689Z,1717438715.689 [CTD_Seabird](INFO): Powering down
2024-06-03T18:18:35.700Z,1717438715.700 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:35.701Z,1717438715.701 [CTD_Seabird](INFO): Powering down
2024-06-03T18:18:35.713Z,1717438715.713 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:35.713Z,1717438715.713 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-06-03T18:18:35.713Z,1717438715.713 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:35.714Z,1717438715.714 [DAT](INFO): Join timeout helper Thread ID is 1874
2024-06-03T18:18:35.789Z,1717438715.789 [DAT](INFO): Powering down
2024-06-03T18:18:35.862Z,1717438715.862 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:35.862Z,1717438715.862 [DAT](INFO): Powering down
2024-06-03T18:18:35.863Z,1717438715.863 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:35.864Z,1717438715.864 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-06-03T18:18:35.865Z,1717438715.865 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:35.865Z,1717438715.865 [Radio_Surface](INFO): Join timeout helper Thread ID is 1875
2024-06-03T18:18:36.013Z,1717438716.013 [Radio_Surface](INFO): Powering down
2024-06-03T18:18:36.014Z,1717438716.014 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:36.014Z,1717438716.014 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:36.026Z,1717438716.026 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-06-03T18:18:36.026Z,1717438716.026 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:36.026Z,1717438716.026 [Onboard](INFO): Join timeout helper Thread ID is 1876
2024-06-03T18:18:36.181Z,1717438716.181 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-06-03T18:18:37.725Z,1717438717.725 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:37.725Z,1717438717.725 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:37.740Z,1717438717.740 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-06-03T18:18:37.740Z,1717438717.740 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:37.740Z,1717438717.740 [DataOverHttps](INFO): Join timeout helper Thread ID is 1877
2024-06-03T18:18:38.189Z,1717438718.189 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:38.189Z,1717438718.189 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.197Z,1717438718.197 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-06-03T18:18:38.197Z,1717438718.197 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.198Z,1717438718.198 [logger](INFO): Join timeout helper Thread ID is 1878
2024-06-03T18:18:38.205Z,1717438718.205 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:38.205Z,1717438718.205 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.217Z,1717438718.217 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-06-03T18:18:38.217Z,1717438718.217 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.218Z,1717438718.218 [CommandLine](INFO): Join timeout helper Thread ID is 1879
2024-06-03T18:18:38.297Z,1717438718.297 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:38.297Z,1717438718.297 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.317Z,1717438718.317 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-06-03T18:18:38.317Z,1717438718.317 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.318Z,1717438718.318 [CommandExec](INFO): Join timeout helper Thread ID is 1880
2024-06-03T18:18:38.319Z,1717438718.319 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-06-03T18:18:38.319Z,1717438718.319 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.319Z,1717438718.319 [controlThread](INFO): Join timeout helper Thread ID is 1881
2024-06-03T18:18:38.325Z,1717438718.325 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-03T18:18:38.325Z,1717438718.325 [controlThread](DEBUG): Uninitializing ControlThread
2024-06-03T18:18:38.325Z,1717438718.325 [AHRS_M2](INFO): Powering down
2024-06-03T18:18:38.397Z,1717438718.397 [NAL9602](INFO): Powering down
2024-06-03T18:18:38.400Z,1717438718.400 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-06-03T18:18:38.401Z,1717438718.401 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-06-03T18:18:38.402Z,1717438718.402 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-06-03T18:18:38.402Z,1717438718.402 [MissionManager](INFO): Uninitializing Mission Default
2024-06-03T18:18:38.402Z,1717438718.402 [Default] Stopped
2024-06-03T18:18:38.402Z,1717438718.402 [Default](DEBUG): Aggregate::uninitialize Default
2024-06-03T18:18:38.402Z,1717438718.402 [Default:B.GoToSurface] Stopped
2024-06-03T18:18:38.403Z,1717438718.403 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-03T18:18:38.403Z,1717438718.403 [Default:CheckIn] Stopped
2024-06-03T18:18:38.403Z,1717438718.403 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-03T18:18:38.403Z,1717438718.403 [Default:CheckIn:C.Wait] Stopped
2024-06-03T18:18:38.403Z,1717438718.403 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-03T18:18:38.406Z,1717438718.406 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-06-03T18:18:38.406Z,1717438718.406 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-06-03T18:18:38.407Z,1717438718.407 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-06-03T18:18:38.407Z,1717438718.407 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-06-03T18:18:38.407Z,1717438718.407 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-06-03T18:18:38.407Z,1717438718.407 [BuoyancyServo](INFO): Powering down
2024-06-03T18:18:38.421Z,1717438718.421 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-03T18:18:38.421Z,1717438718.421 [ElevatorServo](INFO): Powering down
2024-06-03T18:18:38.422Z,1717438718.422 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-06-03T18:18:38.422Z,1717438718.422 [MassServo](INFO): Powering down
2024-06-03T18:18:38.423Z,1717438718.423 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-06-03T18:18:38.423Z,1717438718.423 [RudderServo](INFO): Powering down
2024-06-03T18:18:38.424Z,1717438718.424 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-06-03T18:18:38.424Z,1717438718.424 [ThrusterHE](INFO): Powering down
2024-06-03T18:18:38.425Z,1717438718.425 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-06-03T18:18:38.425Z,1717438718.425 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-06-03T18:18:38.425Z,1717438718.425 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-06-03T18:18:38.425Z,1717438718.425 [CBIT](DEBUG): Powering off loads.
2024-06-03T18:18:38.436Z,1717438718.436 [CBIT](DEBUG): Disabling WDT.
2024-06-03T18:18:38.448Z,1717438718.448 [CBIT](DEBUG): Opening all GF detection circuits.
2024-06-03T18:18:38.449Z,1717438718.449 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.508Z,1717438718.508 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.513Z,1717438718.513 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.516Z,1717438718.516 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.523Z,1717438718.523 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.606Z,1717438718.606 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.611Z,1717438718.611 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.665Z,1717438718.665 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-03T18:18:38.744Z,1717438718.744 [logger ThreadHandler](INFO): Thread cancelled.