2025-05-19T15:41:43.945Z,1747669303.945 [Supervisor](DEBUG): Initializing supervisor.
2025-05-19T15:41:43.950Z,1747669303.950 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-05-19T15:41:43.950Z,1747669303.950 [SyncHandler](INFO): Protected caller Thread ID is 8921
2025-05-19T15:41:43.951Z,1747669303.951 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-05-19T15:41:43.952Z,1747669303.952 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-05-19T15:41:43.952Z,1747669303.952 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8922
2025-05-19T15:41:43.957Z,1747669303.957 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-05-19T15:41:43.977Z,1747669303.977 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-05-19T15:41:43.978Z,1747669303.978 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-05-19T15:41:43.979Z,1747669303.979 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8923
2025-05-19T15:41:43.983Z,1747669303.983 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-05-19T15:41:43.984Z,1747669303.984 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-05-19T15:41:43.984Z,1747669303.984 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8924
2025-05-19T15:41:43.987Z,1747669303.987 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-05-19T15:41:43.987Z,1747669303.987 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-05-19T15:41:43.988Z,1747669303.988 [logger ThreadHandler](INFO): Protected caller Thread ID is 8925
2025-05-19T15:41:43.992Z,1747669303.992 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-05-19T15:41:43.992Z,1747669303.992 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-05-19T15:41:43.994Z,1747669303.994 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-05-19T15:41:44.349Z,1747669304.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-05-19T15:41:44.350Z,1747669304.350 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-05-19T15:41:44.442Z,1747669304.442 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-05-19T15:41:44.561Z,1747669304.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-05-19T15:41:44.562Z,1747669304.562 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-05-19T15:41:44.705Z,1747669304.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-05-19T15:41:44.706Z,1747669304.706 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-05-19T15:41:45.072Z,1747669305.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-05-19T15:41:45.072Z,1747669305.072 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-05-19T15:41:45.181Z,1747669305.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-05-19T15:41:45.182Z,1747669305.182 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-05-19T15:41:45.603Z,1747669305.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-05-19T15:41:45.603Z,1747669305.603 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-05-19T15:41:45.842Z,1747669305.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-05-19T15:41:45.843Z,1747669305.843 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-05-19T15:41:45.935Z,1747669305.935 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-05-19T15:41:46.195Z,1747669306.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-05-19T15:41:46.195Z,1747669306.195 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-05-19T15:41:46.430Z,1747669306.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-05-19T15:41:46.431Z,1747669306.431 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-05-19T15:41:46.982Z,1747669306.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-05-19T15:41:46.982Z,1747669306.982 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-05-19T15:41:47.189Z,1747669307.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-05-19T15:41:47.190Z,1747669307.190 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-05-19T15:41:47.510Z,1747669307.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-05-19T15:41:47.510Z,1747669307.510 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-05-19T15:41:48.408Z,1747669308.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-05-19T15:41:48.408Z,1747669308.408 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-05-19T15:41:48.994Z,1747669308.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-05-19T15:41:48.996Z,1747669308.996 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2025-05-19T15:41:48.997Z,1747669308.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2025-05-19T15:41:49.106Z,1747669309.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2025-05-19T15:41:49.251Z,1747669309.251 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2025-05-19T15:41:49.346Z,1747669309.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2025-05-19T15:41:49.466Z,1747669309.466 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2025-05-19T15:41:49.565Z,1747669309.565 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2025-05-19T15:41:49.837Z,1747669309.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-05-19T15:41:49.837Z,1747669309.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2025-05-19T15:41:49.959Z,1747669309.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2025-05-19T15:41:50.070Z,1747669310.070 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2025-05-19T15:41:50.223Z,1747669310.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2025-05-19T15:41:50.321Z,1747669310.321 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2025-05-19T15:41:50.321Z,1747669310.321 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-05-19T15:41:50.329Z,1747669310.329 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-05-19T15:41:50.462Z,1747669310.462 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-05-19T15:41:50.462Z,1747669310.462 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-05-19T15:41:50.516Z,1747669310.516 [VerticalControl](DEBUG): Construct VerticalControl.
2025-05-19T15:41:50.576Z,1747669310.576 [VerticalControl] Loaded
2025-05-19T15:41:50.576Z,1747669310.576 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-05-19T15:41:50.579Z,1747669310.579 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-05-19T15:41:50.626Z,1747669310.626 [HorizontalControl] Loaded
2025-05-19T15:41:50.626Z,1747669310.626 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-05-19T15:41:50.629Z,1747669310.629 [SpeedControl](DEBUG): Construct SpeedControl.
2025-05-19T15:41:50.632Z,1747669310.632 [SpeedControl] Loaded
2025-05-19T15:41:50.632Z,1747669310.632 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-05-19T15:41:50.635Z,1747669310.635 [LoopControl](DEBUG): Construct LoopControl.
2025-05-19T15:41:50.635Z,1747669310.635 [LoopControl] Loaded
2025-05-19T15:41:50.635Z,1747669310.635 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-05-19T15:41:50.636Z,1747669310.636 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-05-19T15:41:50.636Z,1747669310.636 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-05-19T15:41:50.695Z,1747669310.695 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-05-19T15:41:50.696Z,1747669310.696 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-05-19T15:41:51.190Z,1747669311.190 [AHRS_M2] Loaded
2025-05-19T15:41:51.191Z,1747669311.191 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-05-19T15:41:51.229Z,1747669311.229 [BackseatComponent] Loaded
2025-05-19T15:41:51.229Z,1747669311.229 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-05-19T15:41:51.230Z,1747669311.230 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0
2025-05-19T15:41:51.230Z,1747669311.230 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9004
2025-05-19T15:41:51.233Z,1747669311.233 [LcmUniversalReporter] Loaded
2025-05-19T15:41:51.233Z,1747669311.233 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-05-19T15:41:52.029Z,1747669312.029 [BPC1] Loaded
2025-05-19T15:41:52.030Z,1747669312.030 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-05-19T15:41:52.126Z,1747669312.126 [DAT] Loaded
2025-05-19T15:41:52.126Z,1747669312.126 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-05-19T15:41:52.127Z,1747669312.127 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0
2025-05-19T15:41:52.128Z,1747669312.128 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9005
2025-05-19T15:41:52.201Z,1747669312.201 [DataOverHttps] Loaded
2025-05-19T15:41:52.201Z,1747669312.201 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-05-19T15:41:52.202Z,1747669312.202 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0
2025-05-19T15:41:52.202Z,1747669312.202 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9006
2025-05-19T15:41:52.225Z,1747669312.225 [Depth_Keller] Loaded
2025-05-19T15:41:52.226Z,1747669312.226 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-05-19T15:41:52.231Z,1747669312.231 [DropWeight] Loaded
2025-05-19T15:41:52.231Z,1747669312.231 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-05-19T15:41:52.295Z,1747669312.295 [NAL9602] Loaded
2025-05-19T15:41:52.295Z,1747669312.295 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-05-19T15:41:52.325Z,1747669312.325 [Onboard] Loaded
2025-05-19T15:41:52.325Z,1747669312.325 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-05-19T15:41:52.326Z,1747669312.326 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0
2025-05-19T15:41:52.327Z,1747669312.327 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9007
2025-05-19T15:41:52.340Z,1747669312.340 [Power24vConverter] Loaded
2025-05-19T15:41:52.341Z,1747669312.341 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-05-19T15:41:52.357Z,1747669312.357 [Radio_Surface] Loaded
2025-05-19T15:41:52.357Z,1747669312.357 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-05-19T15:41:52.358Z,1747669312.358 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0
2025-05-19T15:41:52.358Z,1747669312.358 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9008
2025-05-19T15:41:52.374Z,1747669312.374 [Sonardyne_Nano] Loaded
2025-05-19T15:41:52.374Z,1747669312.374 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-05-19T15:41:52.398Z,1747669312.398 [Waterlinked] Loaded
2025-05-19T15:41:52.398Z,1747669312.398 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-05-19T15:41:52.399Z,1747669312.399 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-05-19T15:41:52.399Z,1747669312.399 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-05-19T15:41:52.409Z,1747669312.409 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-05-19T15:41:52.410Z,1747669312.410 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-05-19T15:41:52.439Z,1747669312.439 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-05-19T15:41:52.440Z,1747669312.440 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-05-19T15:41:52.599Z,1747669312.599 [SBIT](DEBUG): Construct Startup Built In Test.
2025-05-19T15:41:52.608Z,1747669312.608 [SBIT] Loaded
2025-05-19T15:41:52.609Z,1747669312.609 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-05-19T15:41:52.611Z,1747669312.611 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-05-19T15:41:52.624Z,1747669312.624 [IBIT] Loaded
2025-05-19T15:41:52.624Z,1747669312.624 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-05-19T15:41:52.630Z,1747669312.630 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-05-19T15:41:52.709Z,1747669312.709 [CBIT] Loaded
2025-05-19T15:41:52.709Z,1747669312.709 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-05-19T15:41:52.742Z,1747669312.742 [GFScanner] Loaded
2025-05-19T15:41:52.742Z,1747669312.742 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-05-19T15:41:52.743Z,1747669312.743 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-05-19T15:41:52.743Z,1747669312.743 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-05-19T15:41:53.088Z,1747669313.088 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-05-19T15:41:53.088Z,1747669313.088 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-05-19T15:41:53.140Z,1747669313.140 [DepthRateCalculator] Loaded
2025-05-19T15:41:53.140Z,1747669313.140 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-05-19T15:41:53.145Z,1747669313.145 [PitchRateCalculator] Loaded
2025-05-19T15:41:53.145Z,1747669313.145 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-05-19T15:41:53.156Z,1747669313.156 [SpeedCalculator] Loaded
2025-05-19T15:41:53.156Z,1747669313.156 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-05-19T15:41:53.160Z,1747669313.160 [YawRateCalculator] Loaded
2025-05-19T15:41:53.160Z,1747669313.160 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-05-19T15:41:53.179Z,1747669313.179 [ElevatorOffsetCalculator] Loaded
2025-05-19T15:41:53.180Z,1747669313.180 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-05-19T15:41:53.180Z,1747669313.180 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-05-19T15:41:53.181Z,1747669313.181 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-05-19T15:41:53.357Z,1747669313.357 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-05-19T15:41:53.358Z,1747669313.358 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-05-19T15:41:53.471Z,1747669313.471 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-05-19T15:41:53.471Z,1747669313.471 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-05-19T15:41:53.484Z,1747669313.484 [NavChart] Loaded
2025-05-19T15:41:53.485Z,1747669313.485 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-05-19T15:41:53.490Z,1747669313.490 [UniversalFixResidualReporter] Loaded
2025-05-19T15:41:53.491Z,1747669313.491 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-05-19T15:41:53.491Z,1747669313.491 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-05-19T15:41:53.492Z,1747669313.492 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-05-19T15:41:53.644Z,1747669313.644 [BuoyancyServo] Loaded
2025-05-19T15:41:53.645Z,1747669313.645 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-05-19T15:41:53.668Z,1747669313.668 [ElevatorServo] Loaded
2025-05-19T15:41:53.668Z,1747669313.668 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-05-19T15:41:53.690Z,1747669313.690 [MassServo] Loaded
2025-05-19T15:41:53.690Z,1747669313.690 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-05-19T15:41:53.711Z,1747669313.711 [RudderServo] Loaded
2025-05-19T15:41:53.711Z,1747669313.711 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-05-19T15:41:53.728Z,1747669313.728 [ThrusterHE] Loaded
2025-05-19T15:41:53.728Z,1747669313.728 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-05-19T15:41:53.728Z,1747669313.728 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-05-19T15:41:53.729Z,1747669313.729 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-05-19T15:41:53.897Z,1747669313.897 [CTD_Seabird] Loaded
2025-05-19T15:41:53.897Z,1747669313.897 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-05-19T15:41:53.898Z,1747669313.898 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0
2025-05-19T15:41:53.898Z,1747669313.898 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9009
2025-05-19T15:41:53.935Z,1747669313.935 [ESPComponent] Loaded
2025-05-19T15:41:53.935Z,1747669313.935 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-05-19T15:41:53.958Z,1747669313.958 [PAR_Licor] Loaded
2025-05-19T15:41:53.959Z,1747669313.959 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-05-19T15:41:54.004Z,1747669314.004 [WetLabsBB2FL] Loaded
2025-05-19T15:41:54.004Z,1747669314.004 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-05-19T15:41:54.005Z,1747669314.005 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0
2025-05-19T15:41:54.006Z,1747669314.006 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9010
2025-05-19T15:41:54.006Z,1747669314.006 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-05-19T15:41:54.013Z,1747669314.013 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-05-19T15:41:54.016Z,1747669314.016 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-05-19T15:41:54.027Z,1747669314.027 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-05-19T15:41:54.028Z,1747669314.028 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-05-19T15:41:54.028Z,1747669314.028 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9011
2025-05-19T15:41:54.032Z,1747669314.032 [Supervisor](INFO): Main Thread ID is 8609
2025-05-19T15:41:54.033Z,1747669314.033 [Supervisor](DEBUG): Running supervisor.
2025-05-19T15:41:54.033Z,1747669314.033 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9012
2025-05-19T15:41:54.034Z,1747669314.034 [CommandExec](INFO): Initializing the command executive.
2025-05-19T15:41:54.035Z,1747669314.035 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9013
2025-05-19T15:41:54.038Z,1747669314.038 [controlThread ThreadHandler](INFO): Handler Thread ID is 9014
2025-05-19T15:41:54.038Z,1747669314.038 [controlThread](DEBUG): Initializing ControlThread
2025-05-19T15:41:54.039Z,1747669314.039 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-05-19T15:41:54.040Z,1747669314.040 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-05-19T15:41:54.041Z,1747669314.041 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-05-19T15:41:54.042Z,1747669314.042 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-05-19T15:41:54.045Z,1747669314.045 [SBIT](INFO): Initialize SBIT Component.
2025-05-19T15:41:54.045Z,1747669314.045 [SBIT](IMPORTANT): git: 2025-05-16
2025-05-19T15:41:54.045Z,1747669314.045 [SBIT](INFO): git hash: 7cd925afbf924468d60d7bdc303f0aabfe74e3fc
2025-05-19T15:41:54.046Z,1747669314.046 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-05-19T15:41:54.047Z,1747669314.047 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2025-05-19T15:41:54.048Z,1747669314.048 [SBIT](INFO): Beginning SBIT in 102.000000 seconds.
2025-05-19T15:41:54.049Z,1747669314.049 [IBIT](INFO): Initialize IBIT Component.
2025-05-19T15:41:54.049Z,1747669314.049 [CBIT](DEBUG): Initialize CBIT Component.
2025-05-19T15:41:54.051Z,1747669314.051 [logger ThreadHandler](INFO): Handler Thread ID is 9015
2025-05-19T15:41:54.061Z,1747669314.061 [CBIT](DEBUG): Initialized mux pins.
2025-05-19T15:41:54.061Z,1747669314.061 [CBIT](DEBUG): Initializing the watchdog timer.
2025-05-19T15:41:54.069Z,1747669314.069 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9016
2025-05-19T15:41:54.085Z,1747669314.085 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-05-19T15:41:54.085Z,1747669314.085 [CBIT](DEBUG): Initializing heartbeat.
2025-05-19T15:41:54.086Z,1747669314.086 [DAT ThreadHandler](INFO): Handler Thread ID is 9017
2025-05-19T15:41:54.087Z,1747669314.087 [DAT](INFO): Powering up
2025-05-19T15:41:54.087Z,1747669314.087 [DAT](DEBUG): Initializing DAT.
2025-05-19T15:41:54.090Z,1747669314.090 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9019
2025-05-19T15:41:54.092Z,1747669314.092 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-05-19T15:41:54.109Z,1747669314.109 [Onboard ThreadHandler](INFO): Handler Thread ID is 9020
2025-05-19T15:41:54.127Z,1747669314.127 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9021
2025-05-19T15:41:54.153Z,1747669314.153 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9022
2025-05-19T15:41:54.157Z,1747669314.157 [CBIT](DEBUG): Deactivating emergency mode.
2025-05-19T15:41:54.158Z,1747669314.158 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-05-19T15:41:54.161Z,1747669314.161 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9024
2025-05-19T15:41:54.165Z,1747669314.165 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9026
2025-05-19T15:41:54.168Z,1747669314.168 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-05-19T15:41:54.168Z,1747669314.168 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-05-19T15:41:54.168Z,1747669314.168 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-05-19T15:41:54.168Z,1747669314.168 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-05-19T15:41:54.169Z,1747669314.169 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-05-19T15:41:54.169Z,1747669314.169 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-05-19T15:41:54.169Z,1747669314.169 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-05-19T15:41:54.169Z,1747669314.169 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-05-19T15:41:54.189Z,1747669314.189 [WetLabsBB2FL](INFO): Powering up
2025-05-19T15:41:54.197Z,1747669314.197 [CBIT](DEBUG): Backplane powered.
2025-05-19T15:41:54.197Z,1747669314.197 [GFScanner](DEBUG): Initializing GFScanner
2025-05-19T15:41:54.197Z,1747669314.197 [GFScanner](DEBUG): Deactivating GF circuits.
2025-05-19T15:41:54.202Z,1747669314.202 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-05-19T15:41:54.202Z,1747669314.202 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-05-19T15:41:54.202Z,1747669314.202 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-05-19T15:41:54.203Z,1747669314.203 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-05-19T15:41:54.203Z,1747669314.203 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-05-19T15:41:54.204Z,1747669314.204 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-05-19T15:41:54.205Z,1747669314.205 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-05-19T15:41:54.210Z,1747669314.210 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-05-19T15:41:54.210Z,1747669314.210 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-05-19T15:41:54.211Z,1747669314.211 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-05-19T15:41:54.212Z,1747669314.212 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-05-19T15:41:54.298Z,1747669314.298 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-05-19T15:41:54.321Z,1747669314.321 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-19T15:41:54.362Z,1747669314.362 [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
    }
  }
}


2025-05-19T15:41:54.362Z,1747669314.362 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-05-19T15:41:54.363Z,1747669314.363 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-05-19T15:41:54.364Z,1747669314.364 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-05-19T15:41:54.533Z,1747669314.533 [Radio_Surface](INFO): Powering up
2025-05-19T15:41:54.695Z,1747669314.695 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-05-19T15:41:54.717Z,1747669314.717 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-05-19T15:41:54.718Z,1747669314.718 [Default:A.Wait](DEBUG): Construct Wait.
2025-05-19T15:41:54.720Z,1747669314.720 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-19T15:41:54.766Z,1747669314.766 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-05-19T15:41:54.768Z,1747669314.768 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-05-19T15:41:54.790Z,1747669314.790 [Default:E.Execute](DEBUG): Construct Execute.
2025-05-19T15:41:54.810Z,1747669314.810 [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"
  }
}


2025-05-19T15:41:54.815Z,1747669314.815 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,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,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-05-19T15:41:54.838Z,1747669314.838 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-05-19T15:41:54.898Z,1747669314.898 [Depth_Keller](INFO): Initializing.
2025-05-19T15:41:54.899Z,1747669314.899 [Power24vConverter](INFO): Powering up.
2025-05-19T15:41:54.899Z,1747669314.899 [Sonardyne_Nano](INFO): Initializing.
2025-05-19T15:41:54.942Z,1747669314.942 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-19T15:41:54.968Z,1747669314.968 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-05-19T15:41:54.973Z,1747669314.973 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-05-19T15:41:54.974Z,1747669314.974 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-05-19T15:41:54.981Z,1747669314.981 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-05-19T15:41:54.982Z,1747669314.982 [MassServo](DEBUG): Initializing EZServoServo.
2025-05-19T15:41:54.989Z,1747669314.989 [MassServo](DEBUG): Initializing MassServo.
2025-05-19T15:41:54.990Z,1747669314.990 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-19T15:41:54.997Z,1747669314.997 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-19T15:41:54.998Z,1747669314.998 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-05-19T15:41:55.005Z,1747669315.005 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-05-19T15:41:56.273Z,1747669316.273 [WetLabsBB2FL](INFO): Powering down
2025-05-19T15:41:56.844Z,1747669316.844 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-05-19T15:41:58.146Z,1747669318.146 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2025-05-19T15:42:01.189Z,1747669321.189 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-05-19T15:42:06.434Z,1747669326.434 [DAT](INFO): DAT read: 

2025-05-19T15:42:06.435Z,1747669326.435 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-05-19T15:42:07.948Z,1747669327.948 [DAT](INFO): DAT read: MF Frequency Band

2025-05-19T15:42:08.198Z,1747669328.198 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-05-19T15:42:08.199Z,1747669328.199 [DAT](INFO): DAT read: May 19 2025 15:42:02 

2025-05-19T15:42:09.206Z,1747669329.206 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-05-19T15:42:09.207Z,1747669329.207 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-05-19T15:42:09.208Z,1747669329.208 [DAT](INFO): commRate: 800
2025-05-19T15:42:11.274Z,1747669331.274 [DAT](INFO): entering command mode
2025-05-19T15:42:11.474Z,1747669331.474 [DAT](INFO): DAT read: 

2025-05-19T15:42:11.474Z,1747669331.474 [DAT](INFO): DAT read: use:1>

2025-05-19T15:42:11.475Z,1747669331.475 [DAT](INFO): unknown deviceResponse_: use:1>

2025-05-19T15:42:11.475Z,1747669331.475 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:11.726Z,1747669331.726 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:11.978Z,1747669331.978 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:12.230Z,1747669332.230 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:12.482Z,1747669332.482 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:12.733Z,1747669332.733 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:12.985Z,1747669332.985 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:13.238Z,1747669333.238 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:13.490Z,1747669333.490 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:13.742Z,1747669333.742 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:13.994Z,1747669333.994 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:14.246Z,1747669334.246 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:14.497Z,1747669334.497 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:14.750Z,1747669334.750 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:15.001Z,1747669335.001 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:15.253Z,1747669335.253 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:15.505Z,1747669335.505 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:15.757Z,1747669335.757 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:16.009Z,1747669336.009 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:16.262Z,1747669336.262 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:16.514Z,1747669336.514 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:16.766Z,1747669336.766 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:17.017Z,1747669337.017 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:17.270Z,1747669337.270 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:17.521Z,1747669337.521 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:17.774Z,1747669337.774 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:18.026Z,1747669338.026 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:18.279Z,1747669338.279 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:18.530Z,1747669338.530 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:18.782Z,1747669338.782 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:19.033Z,1747669339.033 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:19.285Z,1747669339.285 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:19.538Z,1747669339.538 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:19.789Z,1747669339.789 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:20.042Z,1747669340.042 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:20.296Z,1747669340.296 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:20.545Z,1747669340.545 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:20.798Z,1747669340.798 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:21.050Z,1747669341.050 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:21.302Z,1747669341.302 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:21.554Z,1747669341.554 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:21.806Z,1747669341.806 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:22.058Z,1747669342.058 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:22.309Z,1747669342.309 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:22.327Z,1747669342.327 [NAL9602](INFO): Powering up NAL9602
2025-05-19T15:42:22.563Z,1747669342.563 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:22.815Z,1747669342.815 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:23.067Z,1747669343.067 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:23.318Z,1747669343.318 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:23.569Z,1747669343.569 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:23.823Z,1747669343.823 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:24.074Z,1747669344.074 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:24.327Z,1747669344.327 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:24.578Z,1747669344.578 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:24.830Z,1747669344.830 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:25.081Z,1747669345.081 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:25.334Z,1747669345.334 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:25.586Z,1747669345.586 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:25.837Z,1747669345.837 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:26.090Z,1747669346.089 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:26.343Z,1747669346.343 [DAT](DEBUG): checking for command mode acknowledgment
2025-05-19T15:42:26.343Z,1747669346.343 [DAT](FAULT): failed to enter command mode
2025-05-19T15:42:26.649Z,1747669346.649 [DAT](INFO): entering command mode
2025-05-19T15:42:26.848Z,1747669346.848 [DAT](INFO): DAT read: user:1>

2025-05-19T15:42:26.850Z,1747669346.850 [DAT](INFO): DAT read: Command '+++' not found

2025-05-19T15:42:26.852Z,1747669346.852 [DAT](INFO): DAT read: Error

2025-05-19T15:42:26.854Z,1747669346.854 [DAT](INFO): setting verbose to 3
2025-05-19T15:42:27.100Z,1747669347.100 [DAT](INFO): DAT read: user:2>

2025-05-19T15:42:27.103Z,1747669347.103 [DAT](INFO): DAT read: Verbose         | 3           

2025-05-19T15:42:27.104Z,1747669347.104 [DAT](INFO): set verbose to 3
2025-05-19T15:42:27.105Z,1747669347.105 [DAT](INFO): setting DatVerbose to 27440
2025-05-19T15:42:27.350Z,1747669347.350 [DAT](INFO): DAT read: user:3>

2025-05-19T15:42:27.351Z,1747669347.351 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-05-19T15:42:27.351Z,1747669347.351 [DAT](INFO): set DatVerbose to 27440
2025-05-19T15:42:27.351Z,1747669347.351 [DAT](INFO): setting transmit power to 8
2025-05-19T15:42:27.604Z,1747669347.604 [DAT](INFO): DAT read: user:4>

2025-05-19T15:42:27.607Z,1747669347.607 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-05-19T15:42:27.608Z,1747669347.608 [DAT](INFO): set transmit power to 8
2025-05-19T15:42:27.611Z,1747669347.611 [DAT](INFO): setting local address to 1
2025-05-19T15:42:27.856Z,1747669347.856 [DAT](INFO): DAT read: user:5>

2025-05-19T15:42:27.858Z,1747669347.858 [DAT](INFO): DAT read: LocalAddr       | 1           

2025-05-19T15:42:27.860Z,1747669347.860 [DAT](INFO): set local address to 1
2025-05-19T15:42:27.864Z,1747669347.864 [DAT](INFO): Setting time to: 15:42:27 And date to:5/19/2025
2025-05-19T15:42:28.106Z,1747669348.106 [DAT](INFO): DAT read: user:6>

2025-05-19T15:42:28.107Z,1747669348.107 [DAT](INFO): DAT read: Mon May 19, 2025  15:42:27  

2025-05-19T15:42:28.107Z,1747669348.107 [DAT](INFO): Local DAT time set to Mon May 19, 2025  15:42:27  

2025-05-19T15:42:31.673Z,1747669351.673 [Waterlinked](INFO): Powering down
2025-05-19T15:42:32.418Z,1747669352.418 [CommandExec](IMPORTANT): got command strobe off
2025-05-19T15:42:32.419Z,1747669352.419 [CommandExec](IMPORTANT): Deactivating strobe
2025-05-19T15:42:33.195Z,1747669353.195 [NAL9602](INFO): NAL9602 initialized
2025-05-19T15:43:09.154Z,1747669389.154 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:43:24.498Z,1747669404.498 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:43:36.636Z,1747669416.636 [SBIT](IMPORTANT): Beginning Startup BIT
2025-05-19T15:43:36.664Z,1747669416.664 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-19T15:43:39.864Z,1747669419.864 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:43:41.174Z,1747669421.174 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002944
CHAN A1 (24V): 0.001489
CHAN A2 (12V): -0.000167
CHAN A3 (5V): -0.001601
CHAN B0 (3.3V): 0.000755
CHAN B1 (3.15aV): -0.000385
CHAN B2 (3.15bV): -0.000273
CHAN B3 (GND): -0.000498
OPEN: 0.007018
Full Scale: +/- 1 mA
2025-05-19T15:43:52.951Z,1747669432.951 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-05-19T15:43:52.952Z,1747669432.952 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 87.000000 %
2025-05-19T15:43:55.686Z,1747669435.686 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:44:10.971Z,1747669450.971 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:44:26.327Z,1747669466.327 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:44:30.394Z,1747669470.394 [SBIT](IMPORTANT): SBIT PASSED
2025-05-19T15:44:30.395Z,1747669470.395 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-05-19T15:44:30.395Z,1747669470.395 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;

2025-05-19T15:44:30.396Z,1747669470.396 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;

2025-05-19T15:44:30.396Z,1747669470.396 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 0.200000 count_per_second;

2025-05-19T15:44:30.396Z,1747669470.396 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 0.200000 count_per_second;

2025-05-19T15:44:30.396Z,1747669470.396 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=284.393591 cubic_centimeter;

2025-05-19T15:44:30.396Z,1747669470.396 [SBIT](IMPORTANT): VerticalControl.massDefault=20.564323 millimeter;

2025-05-19T15:44:30.791Z,1747669470.791 [MissionManager](IMPORTANT): Started mission Startup
2025-05-19T15:44:30.791Z,1747669470.791 [Startup] Running Loop=1
2025-05-19T15:44:30.791Z,1747669470.791 [Startup](DEBUG): Aggregate::initialize Startup
2025-05-19T15:44:30.792Z,1747669470.792 [Startup:A.GoToSurface] Running Loop=1
2025-05-19T15:44:30.792Z,1747669470.792 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-19T15:44:30.792Z,1747669470.792 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-19T15:44:30.793Z,1747669470.793 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-19T15:44:30.818Z,1747669470.818 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-19T15:44:30.818Z,1747669470.818 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-19T15:44:30.818Z,1747669470.818 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-19T15:44:30.819Z,1747669470.819 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-19T15:44:30.820Z,1747669470.820 [Startup:StartupSatComms] Running Loop=1
2025-05-19T15:44:30.820Z,1747669470.820 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-05-19T15:44:30.821Z,1747669470.821 [Startup:StartupSatComms:A] Running Loop=1
2025-05-19T15:44:31.195Z,1747669471.195 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-05-19T15:44:37.072Z,1747669477.072 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005176
2025-05-19T15:44:42.487Z,1747669482.487 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:44:54.218Z,1747669494.218 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-05-19T15:44:54.218Z,1747669494.218 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-19T15:44:54.229Z,1747669494.229 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-19T15:44:54.633Z,1747669494.633 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-19T15:44:54.633Z,1747669494.633 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-05-19T15:44:58.649Z,1747669498.649 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:44:59.868Z,1747669499.868 [NAL9602](INFO): SBD MO Status=2, MOMSN=24335, MT Status=2, MTMSN=0
2025-05-19T15:44:59.869Z,1747669499.869 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T15:45:13.595Z,1747669513.595 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:45:20.876Z,1747669520.876 [NAL9602](INFO): SBD MO Status=0, MOMSN=24335, MT Status=0, MTMSN=0
2025-05-19T15:45:20.876Z,1747669520.876 [NAL9602](INFO): No messages in MT queue
2025-05-19T15:45:31.007Z,1747669531.007 [Startup:StartupSatComms:A](INFO): Timed out from 2025-05-19T15:44:30.8Z
2025-05-19T15:45:31.007Z,1747669531.007 [Startup:StartupSatComms:A] Stopped
2025-05-19T15:45:31.007Z,1747669531.007 [Startup:StartupSatComms:B] Running Loop=1
2025-05-19T15:45:31.388Z,1747669531.388 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-19T15:45:36.933Z,1747669536.933 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250519T153425/Courier0004.lzma
2025-05-19T15:45:37.935Z,1747669537.935 [DataOverHttps](INFO): Moved sent file to Logs/20250519T153425/Courier0004.lzma.bak
2025-05-19T15:45:37.935Z,1747669537.935 [DataOverHttps](INFO): SBD MOMSN=24920407
2025-05-19T15:45:53.626Z,1747669553.626 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250519T154143/Courier0000.lzma
2025-05-19T15:45:54.627Z,1747669554.627 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0000.lzma.bak
2025-05-19T15:45:54.627Z,1747669554.627 [DataOverHttps](INFO): SBD MOMSN=24920415
2025-05-19T15:46:10.275Z,1747669570.275 [DataOverHttps](INFO): Sending 283 bytes from file Logs/20250519T153425/Express0005.lzma
2025-05-19T15:46:11.275Z,1747669571.275 [DataOverHttps](INFO): Moved sent file to Logs/20250519T153425/Express0005.lzma.bak
2025-05-19T15:46:11.275Z,1747669571.275 [DataOverHttps](INFO): SBD MOMSN=24920462
2025-05-19T15:46:27.011Z,1747669587.011 [DataOverHttps](INFO): Sending 992 bytes from file Logs/20250519T154143/Express0001.lzma
2025-05-19T15:46:28.011Z,1747669588.011 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0001.lzma.bak
2025-05-19T15:46:28.011Z,1747669588.011 [DataOverHttps](INFO): SBD MOMSN=24920471
2025-05-19T15:46:29.231Z,1747669589.231 [Startup:StartupSatComms:B] Stopped
2025-05-19T15:46:29.231Z,1747669589.231 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-05-19T15:46:29.231Z,1747669589.231 [Startup:StartupSatComms] Stopped
2025-05-19T15:46:29.231Z,1747669589.231 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-05-19T15:46:29.232Z,1747669589.232 [Startup](INFO): Completed Startup
2025-05-19T15:46:29.233Z,1747669589.233 [MissionManager](INFO): Startup is completed.
2025-05-19T15:46:29.233Z,1747669589.233 [MissionManager](INFO): Uninitializing Mission Startup
2025-05-19T15:46:29.233Z,1747669589.233 [Startup] Stopped
2025-05-19T15:46:29.233Z,1747669589.233 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-05-19T15:46:29.233Z,1747669589.233 [Startup:A.GoToSurface] Stopped
2025-05-19T15:46:29.233Z,1747669589.233 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-19T15:46:29.607Z,1747669589.607 [MissionManager](IMPORTANT): Started mission Default
2025-05-19T15:46:29.607Z,1747669589.607 [Default] Running Loop=1
2025-05-19T15:46:29.607Z,1747669589.607 [Default](DEBUG): Aggregate::initialize Default
2025-05-19T15:46:29.607Z,1747669589.607 [Default:B.GoToSurface] Running Loop=1
2025-05-19T15:46:29.608Z,1747669589.608 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-19T15:46:29.608Z,1747669589.608 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-19T15:46:29.608Z,1747669589.608 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-19T15:46:29.608Z,1747669589.608 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-19T15:46:29.609Z,1747669589.609 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-19T15:46:29.609Z,1747669589.609 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-19T15:46:29.610Z,1747669589.610 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-19T15:46:29.610Z,1747669589.610 [Default:A.Wait] Running Loop=1
2025-05-19T15:46:29.610Z,1747669589.610 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-05-19T15:46:42.896Z,1747669602.896 [Default:A.Wait](INFO): Done Waiting.
2025-05-19T15:46:42.896Z,1747669602.896 [Default:A.Wait] Stopped
2025-05-19T15:46:42.897Z,1747669602.897 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T15:46:43.300Z,1747669603.300 [Default:CheckIn] Running Loop=1
2025-05-19T15:46:43.300Z,1747669603.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T15:46:43.301Z,1747669603.301 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T15:46:43.701Z,1747669603.701 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-19T15:47:49.540Z,1747669669.540 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:47:55.227Z,1747669675.227 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-05-19T15:47:55.227Z,1747669675.227 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-19T15:47:55.238Z,1747669675.238 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-19T15:47:55.646Z,1747669675.646 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-19T15:47:55.647Z,1747669675.647 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-05-19T15:48:03.679Z,1747669683.679 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:48:17.819Z,1747669697.819 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:48:31.961Z,1747669711.961 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:48:46.099Z,1747669726.099 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:49:00.241Z,1747669740.241 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:49:14.380Z,1747669754.380 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:49:28.176Z,1747669768.176 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:49:41.854Z,1747669781.854 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:49:55.587Z,1747669795.587 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T15:50:10.547Z,1747669810.547 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-05-19T15:50:10.548Z,1747669810.548 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-05-19T15:50:10.549Z,1747669810.549 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-05-19T15:50:10.550Z,1747669810.550 [BPC1](INFO): Calculating totals. Valid battery stick count: 43. Valid reserve battery stick count: 5.
2025-05-19T15:50:10.553Z,1747669810.553 [BPC1](INFO): Received data from all battery sticks.
2025-05-19T15:50:56.203Z,1747669856.203 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-05-19T15:50:56.203Z,1747669856.203 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-19T15:50:56.215Z,1747669856.215 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-19T15:50:56.627Z,1747669856.627 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-19T15:50:56.627Z,1747669856.627 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-05-19T15:51:43.509Z,1747669903.509 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-19T15:46:43.3Z
2025-05-19T15:51:43.510Z,1747669903.510 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T15:51:43.510Z,1747669903.510 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T15:51:43.905Z,1747669903.905 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-19T15:51:50.893Z,1747669910.893 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250519T154143/Courier0004.lzma
2025-05-19T15:51:51.895Z,1747669911.895 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0004.lzma.bak
2025-05-19T15:51:51.895Z,1747669911.895 [DataOverHttps](INFO): SBD MOMSN=24920507
2025-05-19T15:52:07.473Z,1747669927.473 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20250519T154143/Express0005.lzma
2025-05-19T15:52:08.475Z,1747669928.475 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0005.lzma.bak
2025-05-19T15:52:08.475Z,1747669928.475 [DataOverHttps](INFO): SBD MOMSN=24920509
2025-05-19T15:52:09.775Z,1747669929.775 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T15:52:09.775Z,1747669929.775 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T15:52:09.775Z,1747669929.775 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T15:52:35.178Z,1747669955.178 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-19T15:52:35.178Z,1747669955.178 [NAL9602] Data Fault, FailCount= 1
2025-05-19T15:52:35.178Z,1747669955.178 [NAL9602](ERROR): Data Fault
2025-05-19T15:52:35.195Z,1747669955.195 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-19T15:52:35.584Z,1747669955.584 [NAL9602](INFO): Powering down
2025-05-19T15:52:36.406Z,1747669956.406 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-19T15:52:36.406Z,1747669956.406 [NAL9602] No Fault, FailCount= 1
2025-05-19T15:53:05.918Z,1747669985.918 [NAL9602](INFO): Powering up NAL9602
2025-05-19T15:53:16.827Z,1747669996.827 [NAL9602](INFO): NAL9602 initialized
2025-05-19T15:53:57.235Z,1747670037.235 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-05-19T15:53:57.235Z,1747670037.235 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-19T15:53:57.268Z,1747670037.268 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-19T15:53:57.647Z,1747670037.647 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-19T15:53:57.647Z,1747670037.647 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-05-19T15:56:58.274Z,1747670218.274 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-05-19T15:56:58.275Z,1747670218.275 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-19T15:56:58.309Z,1747670218.309 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-19T15:56:58.687Z,1747670218.687 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-19T15:56:58.688Z,1747670218.688 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-05-19T15:57:10.395Z,1747670230.395 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T15:57:10.395Z,1747670230.395 [Default:CheckIn:C.Wait] Stopped
2025-05-19T15:57:10.395Z,1747670230.395 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T15:57:10.395Z,1747670230.395 [Default:CheckIn:D] Running Loop=1
2025-05-19T15:57:10.805Z,1747670230.805 [Default:CheckIn:D] Stopped
2025-05-19T15:57:10.805Z,1747670230.805 [Default:CheckIn:E] Running Loop=1
2025-05-19T15:57:11.208Z,1747670231.208 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  10.686622 min 
2025-05-19T15:57:11.208Z,1747670231.208 [Default:CheckIn:E] Stopped
2025-05-19T15:57:11.208Z,1747670231.208 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T15:57:11.208Z,1747670231.208 [Default:CheckIn] Stopped
2025-05-19T15:57:11.208Z,1747670231.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T15:57:11.208Z,1747670231.208 [Default:CheckIn](INFO): Running loop #2
2025-05-19T15:57:11.208Z,1747670231.208 [Default:CheckIn] Running Loop=2
2025-05-19T15:57:11.209Z,1747670231.209 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T15:57:11.209Z,1747670231.209 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T15:59:59.330Z,1747670399.330 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-05-19T15:59:59.330Z,1747670399.330 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-19T15:59:59.340Z,1747670399.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-19T15:59:59.756Z,1747670399.756 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-19T15:59:59.756Z,1747670399.756 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-05-19T16:00:03.761Z,1747670403.761 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160002.00,A,3648.17005,N,12147.28725,W,0.330,0.00,190525,,,A*72
2025-05-19T16:00:03.764Z,1747670403.764 [NAL9602](INFO): GPS fix at 20250519T160002: (36.802834, -121.788121)
2025-05-19T16:00:03.776Z,1747670403.776 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:00:03.776Z,1747670403.776 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:00:11.421Z,1747670411.421 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250519T154143/Courier0007.lzma
2025-05-19T16:00:12.423Z,1747670412.423 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0007.lzma.bak
2025-05-19T16:00:12.423Z,1747670412.423 [DataOverHttps](INFO): SBD MOMSN=24920584
2025-05-19T16:00:29.262Z,1747670429.262 [DataOverHttps](INFO): Sending 407 bytes from file Logs/20250519T154143/Express0008.lzma
2025-05-19T16:00:36.499Z,1747670436.499 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:00:37.810Z,1747670437.810 [DataOverHttps](INFO): Sending 407 bytes from file Logs/20250519T154143/Express0008.lzma
2025-05-19T16:00:38.811Z,1747670438.811 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0008.lzma.bak
2025-05-19T16:00:38.811Z,1747670438.811 [DataOverHttps](INFO): SBD MOMSN=24920587
2025-05-19T16:00:40.134Z,1747670440.134 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:00:40.134Z,1747670440.134 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:00:40.134Z,1747670440.134 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:05:40.898Z,1747670740.898 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:05:40.898Z,1747670740.898 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:05:40.898Z,1747670740.898 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:05:40.898Z,1747670740.898 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:05:41.293Z,1747670741.293 [Default:CheckIn:D] Stopped
2025-05-19T16:05:41.293Z,1747670741.293 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:05:41.693Z,1747670741.693 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  19.194759 min 
2025-05-19T16:05:41.693Z,1747670741.693 [Default:CheckIn:E] Stopped
2025-05-19T16:05:41.694Z,1747670741.694 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:05:41.694Z,1747670741.694 [Default:CheckIn] Stopped
2025-05-19T16:05:41.694Z,1747670741.694 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:05:41.694Z,1747670741.694 [Default:CheckIn](INFO): Running loop #3
2025-05-19T16:05:41.694Z,1747670741.694 [Default:CheckIn] Running Loop=3
2025-05-19T16:05:41.694Z,1747670741.694 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:05:41.694Z,1747670741.694 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:05:43.707Z,1747670743.707 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160542.00,A,3648.17231,N,12147.27102,W,2.955,102.45,190525,,,D*76
2025-05-19T16:05:43.709Z,1747670743.709 [NAL9602](INFO): GPS fix at 20250519T160542: (36.802872, -121.787850)
2025-05-19T16:05:43.721Z,1747670743.721 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:05:43.721Z,1747670743.721 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:05:52.673Z,1747670752.673 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0010.lzma
2025-05-19T16:05:53.675Z,1747670753.675 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0010.lzma.bak
2025-05-19T16:05:53.675Z,1747670753.675 [DataOverHttps](INFO): SBD MOMSN=24920603
2025-05-19T16:06:09.557Z,1747670769.557 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250519T154143/Express0011.lzma
2025-05-19T16:06:10.559Z,1747670770.559 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0011.lzma.bak
2025-05-19T16:06:10.559Z,1747670770.559 [DataOverHttps](INFO): SBD MOMSN=24920606
2025-05-19T16:06:11.599Z,1747670771.599 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:06:11.599Z,1747670771.599 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:06:11.599Z,1747670771.599 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:06:14.412Z,1747670774.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=24336, MT Status=2, MTMSN=0
2025-05-19T16:06:14.412Z,1747670774.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T16:06:22.088Z,1747670782.088 [NAL9602](INFO): SBD MO Status=0, MOMSN=24336, MT Status=0, MTMSN=0
2025-05-19T16:06:22.088Z,1747670782.088 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:06:52.838Z,1747670812.838 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:07:20.106Z,1747670840.106 [DataOverHttps](IMPORTANT): SBD MTMSN=20250519T160719
2025-05-19T16:07:27.733Z,1747670847.733 [DataOverHttps](INFO): Received command: ibit
2025-05-19T16:07:27.814Z,1747670847.814 [CommandExec](IMPORTANT): got command ibit
2025-05-19T16:07:28.004Z,1747670848.004 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-05-19T16:07:28.004Z,1747670848.004 [IBIT](IMPORTANT): Beginning control surface checks.
2025-05-19T16:07:28.024Z,1747670848.024 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-19T16:07:28.735Z,1747670848.735 [DataOverHttps](IMPORTANT): SBD MTMSN=20250519T160727
2025-05-19T16:07:29.604Z,1747670849.605 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160727.00,A,3648.14010,N,12147.23881,W,1.730,120.32,190525,,,D*7D
2025-05-19T16:07:29.607Z,1747670849.607 [NAL9602](INFO): GPS fix at 20250519T160727: (36.802335, -121.787313)
2025-05-19T16:07:32.629Z,1747670852.629 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000202
CHAN A1 (24V): 0.001319
CHAN A2 (12V): 0.000216
CHAN A3 (5V): -0.001525
CHAN B0 (3.3V): -0.000040
CHAN B1 (3.15aV): -0.000251
CHAN B2 (3.15bV): 0.000175
CHAN B3 (GND): -0.000806
OPEN: 0.006892
Full Scale: +/- 1 mA
2025-05-19T16:07:36.545Z,1747670856.545 [DataOverHttps](INFO): Received command: failc
2025-05-19T16:07:36.574Z,1747670856.574 [CommandExec](IMPORTANT): got command failComponent
2025-05-19T16:07:36.574Z,1747670856.574 [CommandExec](IMPORTANT): Failed components:
2025-05-19T16:07:36.574Z,1747670856.574 [CommandExec](IMPORTANT): No failed Components.
2025-05-19T16:07:39.300Z,1747670859.300 [NAL9602](INFO): SBD MO Status=0, MOMSN=24337, MT Status=0, MTMSN=0
2025-05-19T16:07:39.300Z,1747670859.300 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:07:40.530Z,1747670860.530 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160738.00,A,3648.13735,N,12147.23400,W,2.294,100.13,190525,,,D*78
2025-05-19T16:07:40.532Z,1747670860.532 [NAL9602](INFO): GPS fix at 20250519T160738: (36.802289, -121.787233)
2025-05-19T16:07:42.938Z,1747670862.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160741.00,A,3648.13818,N,12147.23164,W,2.274,61.98,190525,,,D*4A
2025-05-19T16:07:42.940Z,1747670862.940 [NAL9602](INFO): GPS fix at 20250519T160741: (36.802303, -121.787194)
2025-05-19T16:07:45.764Z,1747670865.764 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160744.00,A,3648.13923,N,12147.23039,W,1.458,46.53,190525,,,D*46
2025-05-19T16:07:45.767Z,1747670865.767 [NAL9602](INFO): GPS fix at 20250519T160744: (36.802321, -121.787173)
2025-05-19T16:07:48.997Z,1747670868.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160747.00,A,3648.13951,N,12147.22998,W,0.058,43.52,190525,,,D*42
2025-05-19T16:07:48.999Z,1747670868.999 [NAL9602](INFO): GPS fix at 20250519T160747: (36.802325, -121.787166)
2025-05-19T16:07:51.824Z,1747670871.824 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160750.00,A,3648.13951,N,12147.23006,W,0.039,43.52,190525,,,D*4C
2025-05-19T16:07:51.834Z,1747670871.834 [NAL9602](INFO): GPS fix at 20250519T160750: (36.802325, -121.787168)
2025-05-19T16:07:55.056Z,1747670875.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160753.00,A,3648.13952,N,12147.23010,W,0.097,43.52,190525,,,D*4F
2025-05-19T16:07:55.066Z,1747670875.066 [NAL9602](INFO): GPS fix at 20250519T160753: (36.802325, -121.787168)
2025-05-19T16:07:57.886Z,1747670877.886 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160756.00,A,3648.13952,N,12147.23012,W,0.019,43.52,190525,,,D*4E
2025-05-19T16:07:57.888Z,1747670877.888 [NAL9602](INFO): GPS fix at 20250519T160756: (36.802325, -121.787169)
2025-05-19T16:08:01.119Z,1747670881.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160759.00,A,3648.13963,N,12147.23016,W,0.019,43.52,190525,,,D*47
2025-05-19T16:08:01.121Z,1747670881.121 [NAL9602](INFO): GPS fix at 20250519T160759: (36.802327, -121.787169)
2025-05-19T16:08:03.948Z,1747670883.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160802.00,A,3648.13974,N,12147.23023,W,0.039,43.52,190525,,,D*44
2025-05-19T16:08:03.950Z,1747670883.950 [NAL9602](INFO): GPS fix at 20250519T160802: (36.802329, -121.787171)
2025-05-19T16:08:06.772Z,1747670886.772 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160805.00,A,3648.13984,N,12147.23030,W,0.039,43.52,190525,,,D*4E
2025-05-19T16:08:06.775Z,1747670886.775 [NAL9602](INFO): GPS fix at 20250519T160805: (36.802331, -121.787172)
2025-05-19T16:08:10.004Z,1747670890.004 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160808.00,A,3648.13994,N,12147.23037,W,0.058,43.52,190525,,,D*42
2025-05-19T16:08:10.007Z,1747670890.007 [NAL9602](INFO): GPS fix at 20250519T160808: (36.802332, -121.787173)
2025-05-19T16:08:12.841Z,1747670892.841 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160811.00,A,3648.14006,N,12147.23042,W,0.019,43.52,190525,,,D*48
2025-05-19T16:08:12.844Z,1747670892.844 [NAL9602](INFO): GPS fix at 20250519T160811: (36.802334, -121.787174)
2025-05-19T16:08:14.064Z,1747670894.064 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802334 Longitude: -121.787170
2025-05-19T16:08:14.470Z,1747670894.470 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.452999
2025-05-19T16:08:14.470Z,1747670894.470 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-05-19T16:08:14.470Z,1747670894.470 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-05-19T16:08:14.889Z,1747670894.889 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-05-19T16:08:14.889Z,1747670894.889 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-05-19T16:08:14.890Z,1747670894.890 [IBIT](IMPORTANT): Pressure:10.118196 PSI
2025-05-19T16:08:14.890Z,1747670894.890 [IBIT](IMPORTANT): Humidity:23.287754 %
2025-05-19T16:08:15.272Z,1747670895.272 [IBIT](IMPORTANT): Vehicle Pitch:-1.545943 degrees
2025-05-19T16:08:15.272Z,1747670895.272 [IBIT](IMPORTANT): Vehicle  Roll:-12.913850 degrees
2025-05-19T16:08:15.272Z,1747670895.272 [IBIT](IMPORTANT): Vehicle  Heading:15.678905 degrees
2025-05-19T16:08:15.676Z,1747670895.676 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-05-19T16:08:15.676Z,1747670895.676 [IBIT](IMPORTANT): buoyancyNeutral: 284.393585 cc
2025-05-19T16:08:15.677Z,1747670895.677 [IBIT](IMPORTANT): massDefault: 2.056432 cm
2025-05-19T16:08:15.677Z,1747670895.677 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-05-19T16:08:15.677Z,1747670895.677 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-05-19T16:08:15.678Z,1747670895.678 [IBIT](IMPORTANT): IBIT PASSED
2025-05-19T16:08:45.584Z,1747670925.584 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:11:12.230Z,1747671072.230 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:11:12.230Z,1747671072.230 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:11:12.230Z,1747671072.230 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:11:12.230Z,1747671072.230 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:11:12.637Z,1747671072.637 [Default:CheckIn:D] Stopped
2025-05-19T16:11:12.638Z,1747671072.638 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:11:13.039Z,1747671073.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  24.717169 min 
2025-05-19T16:11:13.039Z,1747671073.039 [Default:CheckIn:E] Stopped
2025-05-19T16:11:13.040Z,1747671073.040 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:11:13.040Z,1747671073.040 [Default:CheckIn] Stopped
2025-05-19T16:11:13.040Z,1747671073.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:11:13.040Z,1747671073.040 [Default:CheckIn](INFO): Running loop #4
2025-05-19T16:11:13.040Z,1747671073.040 [Default:CheckIn] Running Loop=4
2025-05-19T16:11:13.040Z,1747671073.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:11:13.040Z,1747671073.040 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:11:15.048Z,1747671075.048 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161114.00,A,3648.15027,N,12147.22799,W,0.019,23.20,190525,,,D*44
2025-05-19T16:11:15.059Z,1747671075.059 [NAL9602](INFO): GPS fix at 20250519T161114: (36.802504, -121.787133)
2025-05-19T16:11:15.070Z,1747671075.070 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:11:15.070Z,1747671075.070 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:11:22.217Z,1747671082.217 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0013.lzma
2025-05-19T16:11:23.219Z,1747671083.219 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0013.lzma.bak
2025-05-19T16:11:23.219Z,1747671083.219 [DataOverHttps](INFO): SBD MOMSN=24920671
2025-05-19T16:11:34.444Z,1747671094.444 [NAL9602](INFO): SBD MO Status=0, MOMSN=24338, MT Status=0, MTMSN=0
2025-05-19T16:11:34.444Z,1747671094.444 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:11:39.219Z,1747671099.219 [DataOverHttps](INFO): Sending 1125 bytes from file Logs/20250519T154143/Express0014.lzma
2025-05-19T16:11:40.219Z,1747671100.219 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0014.lzma.bak
2025-05-19T16:11:40.219Z,1747671100.219 [DataOverHttps](INFO): SBD MOMSN=24920674
2025-05-19T16:11:41.318Z,1747671101.318 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:11:41.318Z,1747671101.318 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:11:41.318Z,1747671101.318 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:12:05.150Z,1747671125.150 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:16:41.998Z,1747671401.998 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:16:41.999Z,1747671401.999 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:16:41.999Z,1747671401.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:16:41.999Z,1747671401.999 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:16:42.404Z,1747671402.404 [Default:CheckIn:D] Stopped
2025-05-19T16:16:42.404Z,1747671402.404 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:16:42.814Z,1747671402.814 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  30.213275 min 
2025-05-19T16:16:42.814Z,1747671402.814 [Default:CheckIn:E] Stopped
2025-05-19T16:16:42.814Z,1747671402.814 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:16:42.814Z,1747671402.814 [Default:CheckIn] Stopped
2025-05-19T16:16:42.814Z,1747671402.814 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:16:42.814Z,1747671402.814 [Default:CheckIn](INFO): Running loop #5
2025-05-19T16:16:42.814Z,1747671402.814 [Default:CheckIn] Running Loop=5
2025-05-19T16:16:42.814Z,1747671402.814 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:16:42.814Z,1747671402.814 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:16:44.817Z,1747671404.817 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161644.00,A,3648.15313,N,12147.22038,W,0.019,80.05,190525,,,D*40
2025-05-19T16:16:44.819Z,1747671404.819 [NAL9602](INFO): GPS fix at 20250519T161644: (36.802552, -121.787006)
2025-05-19T16:16:44.847Z,1747671404.847 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:16:44.847Z,1747671404.847 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:16:53.388Z,1747671413.388 [NAL9602](INFO): SBD MO Status=0, MOMSN=24339, MT Status=0, MTMSN=0
2025-05-19T16:16:53.388Z,1747671413.388 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:16:54.957Z,1747671414.957 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0016.lzma
2025-05-19T16:16:55.959Z,1747671415.959 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0016.lzma.bak
2025-05-19T16:16:55.959Z,1747671415.959 [DataOverHttps](INFO): SBD MOMSN=24920730
2025-05-19T16:17:11.913Z,1747671431.913 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250519T154143/Express0018.lzma
2025-05-19T16:17:12.915Z,1747671432.915 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0018.lzma.bak
2025-05-19T16:17:12.915Z,1747671432.915 [DataOverHttps](INFO): SBD MOMSN=24920735
2025-05-19T16:17:14.001Z,1747671434.001 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:17:14.001Z,1747671434.001 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:17:14.001Z,1747671434.001 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:17:24.074Z,1747671444.074 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:22:14.606Z,1747671734.606 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:22:14.606Z,1747671734.606 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:22:14.606Z,1747671734.606 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:22:14.606Z,1747671734.606 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:22:15.024Z,1747671735.024 [Default:CheckIn:D] Stopped
2025-05-19T16:22:15.024Z,1747671735.024 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:22:15.413Z,1747671735.413 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  35.756946 min 
2025-05-19T16:22:15.413Z,1747671735.413 [Default:CheckIn:E] Stopped
2025-05-19T16:22:15.413Z,1747671735.413 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:22:15.413Z,1747671735.413 [Default:CheckIn] Stopped
2025-05-19T16:22:15.414Z,1747671735.414 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:22:15.414Z,1747671735.414 [Default:CheckIn](INFO): Running loop #6
2025-05-19T16:22:15.414Z,1747671735.414 [Default:CheckIn] Running Loop=6
2025-05-19T16:22:15.414Z,1747671735.414 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:22:15.414Z,1747671735.414 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:22:17.419Z,1747671737.419 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162216.00,A,3648.15246,N,12147.22018,W,0.039,92.16,190525,,,D*40
2025-05-19T16:22:17.421Z,1747671737.421 [NAL9602](INFO): GPS fix at 20250519T162216: (36.802541, -121.787003)
2025-05-19T16:22:17.432Z,1747671737.432 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:22:17.432Z,1747671737.432 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:22:24.705Z,1747671744.705 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0022.lzma
2025-05-19T16:22:25.707Z,1747671745.707 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0022.lzma.bak
2025-05-19T16:22:25.707Z,1747671745.707 [DataOverHttps](INFO): SBD MOMSN=24920800
2025-05-19T16:22:29.540Z,1747671749.540 [NAL9602](INFO): SBD MO Status=0, MOMSN=24340, MT Status=0, MTMSN=0
2025-05-19T16:22:29.540Z,1747671749.540 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:22:41.609Z,1747671761.609 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250519T154143/Express0023.lzma
2025-05-19T16:22:42.611Z,1747671762.611 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0023.lzma.bak
2025-05-19T16:22:42.611Z,1747671762.611 [DataOverHttps](INFO): SBD MOMSN=24920803
2025-05-19T16:22:43.694Z,1747671763.694 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:22:43.694Z,1747671763.694 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:22:43.694Z,1747671763.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:23:00.308Z,1747671780.308 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:27:44.397Z,1747672064.397 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:27:44.397Z,1747672064.397 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:27:44.397Z,1747672064.397 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:27:44.397Z,1747672064.397 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:27:44.802Z,1747672064.802 [Default:CheckIn:D] Stopped
2025-05-19T16:27:44.802Z,1747672064.802 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:27:45.218Z,1747672065.218 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  41.253239 min 
2025-05-19T16:27:45.218Z,1747672065.218 [Default:CheckIn:E] Stopped
2025-05-19T16:27:45.218Z,1747672065.218 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:27:45.218Z,1747672065.218 [Default:CheckIn] Stopped
2025-05-19T16:27:45.218Z,1747672065.218 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:27:45.219Z,1747672065.219 [Default:CheckIn](INFO): Running loop #7
2025-05-19T16:27:45.219Z,1747672065.219 [Default:CheckIn] Running Loop=7
2025-05-19T16:27:45.219Z,1747672065.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:27:45.219Z,1747672065.219 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:27:47.224Z,1747672067.224 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162746.00,A,3648.16186,N,12147.21577,W,0.214,12.41,190525,,,D*44
2025-05-19T16:27:47.226Z,1747672067.226 [NAL9602](INFO): GPS fix at 20250519T162746: (36.802698, -121.786929)
2025-05-19T16:27:47.257Z,1747672067.257 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:27:47.257Z,1747672067.257 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:27:55.036Z,1747672075.036 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0025.lzma
2025-05-19T16:27:56.039Z,1747672076.039 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0025.lzma.bak
2025-05-19T16:27:56.039Z,1747672076.039 [DataOverHttps](INFO): SBD MOMSN=24920825
2025-05-19T16:27:56.913Z,1747672076.913 [NAL9602](INFO): SBD MO Status=0, MOMSN=24341, MT Status=0, MTMSN=0
2025-05-19T16:27:56.913Z,1747672076.913 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:28:11.845Z,1747672091.845 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250519T154143/Express0026.lzma
2025-05-19T16:28:12.847Z,1747672092.847 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0026.lzma.bak
2025-05-19T16:28:12.848Z,1747672092.848 [DataOverHttps](INFO): SBD MOMSN=24920869
2025-05-19T16:28:13.905Z,1747672093.905 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:28:13.905Z,1747672093.905 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:28:13.905Z,1747672093.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:28:27.623Z,1747672107.623 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:33:14.569Z,1747672394.569 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:33:14.569Z,1747672394.569 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:33:14.569Z,1747672394.569 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:33:14.570Z,1747672394.570 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:33:14.982Z,1747672394.982 [Default:CheckIn:D] Stopped
2025-05-19T16:33:14.982Z,1747672394.982 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:33:15.379Z,1747672395.379 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  46.756250 min 
2025-05-19T16:33:15.379Z,1747672395.379 [Default:CheckIn:E] Stopped
2025-05-19T16:33:15.379Z,1747672395.379 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:33:15.380Z,1747672395.380 [Default:CheckIn] Stopped
2025-05-19T16:33:15.380Z,1747672395.380 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:33:15.380Z,1747672395.380 [Default:CheckIn](INFO): Running loop #8
2025-05-19T16:33:15.380Z,1747672395.380 [Default:CheckIn] Running Loop=8
2025-05-19T16:33:15.380Z,1747672395.380 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:33:15.380Z,1747672395.380 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:33:17.388Z,1747672397.388 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163316.00,A,3648.16432,N,12147.21531,W,0.019,11.78,190525,,,D*4A
2025-05-19T16:33:17.399Z,1747672397.399 [NAL9602](INFO): GPS fix at 20250519T163316: (36.802739, -121.786922)
2025-05-19T16:33:17.410Z,1747672397.410 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:33:17.410Z,1747672397.410 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:33:25.220Z,1747672405.220 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0028.lzma
2025-05-19T16:33:26.223Z,1747672406.223 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0028.lzma.bak
2025-05-19T16:33:26.223Z,1747672406.223 [DataOverHttps](INFO): SBD MOMSN=24920884
2025-05-19T16:33:34.764Z,1747672414.764 [NAL9602](INFO): SBD MO Status=0, MOMSN=24342, MT Status=0, MTMSN=0
2025-05-19T16:33:34.764Z,1747672414.764 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:33:42.017Z,1747672422.017 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250519T154143/Express0029.lzma
2025-05-19T16:33:43.019Z,1747672423.019 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0029.lzma.bak
2025-05-19T16:33:43.019Z,1747672423.019 [DataOverHttps](INFO): SBD MOMSN=24920887
2025-05-19T16:33:44.066Z,1747672424.066 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:33:44.067Z,1747672424.067 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:33:44.067Z,1747672424.067 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:34:05.467Z,1747672445.467 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:38:44.775Z,1747672724.775 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:38:44.775Z,1747672724.775 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:38:44.775Z,1747672724.775 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:38:44.775Z,1747672724.775 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:38:45.157Z,1747672725.157 [Default:CheckIn:D] Stopped
2025-05-19T16:38:45.157Z,1747672725.157 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:38:45.583Z,1747672725.583 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  52.259159 min 
2025-05-19T16:38:45.583Z,1747672725.583 [Default:CheckIn:E] Stopped
2025-05-19T16:38:45.583Z,1747672725.583 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:38:45.583Z,1747672725.583 [Default:CheckIn] Stopped
2025-05-19T16:38:45.583Z,1747672725.583 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:38:45.583Z,1747672725.583 [Default:CheckIn](INFO): Running loop #9
2025-05-19T16:38:45.584Z,1747672725.584 [Default:CheckIn] Running Loop=9
2025-05-19T16:38:45.584Z,1747672725.584 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:38:45.584Z,1747672725.584 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:38:47.570Z,1747672727.570 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163846.00,A,3648.16391,N,12147.21469,W,0.019,11.78,190525,,,D*46
2025-05-19T16:38:47.572Z,1747672727.572 [NAL9602](INFO): GPS fix at 20250519T163846: (36.802732, -121.786912)
2025-05-19T16:38:47.592Z,1747672727.592 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:38:47.592Z,1747672727.592 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:38:58.381Z,1747672738.381 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0031.lzma
2025-05-19T16:38:58.532Z,1747672738.532 [NAL9602](INFO): SBD MO Status=0, MOMSN=24343, MT Status=0, MTMSN=0
2025-05-19T16:38:58.532Z,1747672738.532 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:38:59.395Z,1747672739.395 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0031.lzma.bak
2025-05-19T16:38:59.396Z,1747672739.396 [DataOverHttps](INFO): SBD MOMSN=24920953
2025-05-19T16:39:15.405Z,1747672755.405 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250519T154143/Express0032.lzma
2025-05-19T16:39:16.407Z,1747672756.407 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0032.lzma.bak
2025-05-19T16:39:16.407Z,1747672756.407 [DataOverHttps](INFO): SBD MOMSN=24920956
2025-05-19T16:39:17.543Z,1747672757.543 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:39:17.543Z,1747672757.543 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:39:17.543Z,1747672757.543 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:39:29.242Z,1747672769.242 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:44:18.163Z,1747673058.163 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:44:18.163Z,1747673058.163 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:44:18.164Z,1747673058.164 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:44:18.164Z,1747673058.164 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:44:18.577Z,1747673058.577 [Default:CheckIn:D] Stopped
2025-05-19T16:44:18.577Z,1747673058.577 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:44:18.962Z,1747673058.962 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  57.816154 min 
2025-05-19T16:44:18.962Z,1747673058.962 [Default:CheckIn:E] Stopped
2025-05-19T16:44:18.962Z,1747673058.962 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:44:18.962Z,1747673058.962 [Default:CheckIn] Stopped
2025-05-19T16:44:18.962Z,1747673058.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:44:18.962Z,1747673058.962 [Default:CheckIn](INFO): Running loop #10
2025-05-19T16:44:18.962Z,1747673058.962 [Default:CheckIn] Running Loop=10
2025-05-19T16:44:18.962Z,1747673058.962 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:44:18.962Z,1747673058.962 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:44:20.974Z,1747673060.974 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164420.00,A,3648.16348,N,12147.21373,W,0.039,11.78,190525,,,D*47
2025-05-19T16:44:20.976Z,1747673060.976 [NAL9602](INFO): GPS fix at 20250519T164420: (36.802725, -121.786895)
2025-05-19T16:44:20.987Z,1747673060.987 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:44:20.988Z,1747673060.988 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:44:28.752Z,1747673068.752 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0034.lzma
2025-05-19T16:44:29.755Z,1747673069.755 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0034.lzma.bak
2025-05-19T16:44:29.755Z,1747673069.755 [DataOverHttps](INFO): SBD MOMSN=24920971
2025-05-19T16:44:44.816Z,1747673084.816 [NAL9602](INFO): SBD MO Status=0, MOMSN=24344, MT Status=0, MTMSN=0
2025-05-19T16:44:44.816Z,1747673084.816 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:44:45.629Z,1747673085.629 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250519T154143/Express0035.lzma
2025-05-19T16:44:46.631Z,1747673086.631 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0035.lzma.bak
2025-05-19T16:44:46.631Z,1747673086.631 [DataOverHttps](INFO): SBD MOMSN=24920974
2025-05-19T16:44:47.693Z,1747673087.693 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:44:47.693Z,1747673087.693 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:44:47.693Z,1747673087.693 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:45:15.587Z,1747673115.587 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:49:48.316Z,1747673388.316 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:49:48.316Z,1747673388.316 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:49:48.316Z,1747673388.316 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:49:48.316Z,1747673388.316 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:49:48.728Z,1747673388.728 [Default:CheckIn:D] Stopped
2025-05-19T16:49:48.728Z,1747673388.728 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:49:49.152Z,1747673389.152 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  63.318669 min 
2025-05-19T16:49:49.152Z,1747673389.152 [Default:CheckIn:E] Stopped
2025-05-19T16:49:49.152Z,1747673389.152 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:49:49.153Z,1747673389.153 [Default:CheckIn] Stopped
2025-05-19T16:49:49.153Z,1747673389.153 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:49:49.157Z,1747673389.157 [Default:CheckIn](INFO): Running loop #11
2025-05-19T16:49:49.157Z,1747673389.157 [Default:CheckIn] Running Loop=11
2025-05-19T16:49:49.157Z,1747673389.157 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:49:49.157Z,1747673389.157 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:49:51.132Z,1747673391.132 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164950.00,A,3648.16359,N,12147.21449,W,0.019,11.78,190525,,,D*41
2025-05-19T16:49:51.135Z,1747673391.135 [NAL9602](INFO): GPS fix at 20250519T164950: (36.802726, -121.786908)
2025-05-19T16:49:51.146Z,1747673391.146 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:49:51.146Z,1747673391.146 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:49:58.344Z,1747673398.344 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0037.lzma
2025-05-19T16:49:59.347Z,1747673399.347 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0037.lzma.bak
2025-05-19T16:49:59.347Z,1747673399.347 [DataOverHttps](INFO): SBD MOMSN=24921039
2025-05-19T16:50:15.325Z,1747673415.325 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250519T154143/Express0038.lzma
2025-05-19T16:50:16.327Z,1747673416.327 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0038.lzma.bak
2025-05-19T16:50:16.328Z,1747673416.328 [DataOverHttps](INFO): SBD MOMSN=24921042
2025-05-19T16:50:17.405Z,1747673417.405 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:50:17.405Z,1747673417.405 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:50:17.405Z,1747673417.405 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:50:23.459Z,1747673423.459 [NAL9602](INFO): SBD MO Status=0, MOMSN=24345, MT Status=0, MTMSN=0
2025-05-19T16:50:23.460Z,1747673423.460 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:50:54.202Z,1747673454.202 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:51:59.654Z,1747673519.654 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-05-19T16:53:26.920Z,1747673606.920 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:53:40.658Z,1747673620.658 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:53:54.392Z,1747673634.392 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:54:08.128Z,1747673648.128 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:54:21.865Z,1747673661.865 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:54:36.005Z,1747673676.005 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:54:50.146Z,1747673690.146 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:55:04.291Z,1747673704.291 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:55:18.088Z,1747673718.088 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T16:55:18.089Z,1747673718.089 [Default:CheckIn:C.Wait] Stopped
2025-05-19T16:55:18.089Z,1747673718.089 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T16:55:18.089Z,1747673718.089 [Default:CheckIn:D] Running Loop=1
2025-05-19T16:55:18.430Z,1747673718.430 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:55:18.460Z,1747673718.460 [Default:CheckIn:D] Stopped
2025-05-19T16:55:18.460Z,1747673718.460 [Default:CheckIn:E] Running Loop=1
2025-05-19T16:55:18.874Z,1747673718.874 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  68.814217 min 
2025-05-19T16:55:18.874Z,1747673718.875 [Default:CheckIn:E] Stopped
2025-05-19T16:55:18.875Z,1747673718.875 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T16:55:18.875Z,1747673718.875 [Default:CheckIn] Stopped
2025-05-19T16:55:18.875Z,1747673718.875 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T16:55:18.875Z,1747673718.875 [Default:CheckIn](INFO): Running loop #12
2025-05-19T16:55:18.875Z,1747673718.875 [Default:CheckIn] Running Loop=12
2025-05-19T16:55:18.875Z,1747673718.875 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T16:55:18.875Z,1747673718.875 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T16:55:20.860Z,1747673720.860 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165520.00,A,3648.16451,N,12147.21545,W,0.019,11.78,190525,,,D*49
2025-05-19T16:55:20.863Z,1747673720.863 [NAL9602](INFO): GPS fix at 20250519T165520: (36.802742, -121.786924)
2025-05-19T16:55:20.874Z,1747673720.874 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T16:55:20.874Z,1747673720.874 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T16:55:28.218Z,1747673728.218 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0040.lzma
2025-05-19T16:55:29.219Z,1747673729.219 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0040.lzma.bak
2025-05-19T16:55:29.219Z,1747673729.219 [DataOverHttps](INFO): SBD MOMSN=24921057
2025-05-19T16:55:31.757Z,1747673731.757 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:55:45.149Z,1747673745.149 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250519T154143/Express0041.lzma
2025-05-19T16:55:46.151Z,1747673746.151 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0041.lzma.bak
2025-05-19T16:55:46.151Z,1747673746.151 [DataOverHttps](INFO): SBD MOMSN=24921060
2025-05-19T16:55:46.313Z,1747673746.313 [NAL9602](INFO): SBD MO Status=0, MOMSN=24346, MT Status=0, MTMSN=0
2025-05-19T16:55:46.313Z,1747673746.313 [NAL9602](INFO): No messages in MT queue
2025-05-19T16:55:47.528Z,1747673747.528 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T16:55:47.529Z,1747673747.529 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T16:55:47.529Z,1747673747.529 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T16:56:17.014Z,1747673777.014 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T16:58:02.155Z,1747673882.155 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-05-19T16:58:08.165Z,1747673888.165 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T16:58:08.172Z,1747673888.172 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-05-19T16:58:08.173Z,1747673888.173 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-05-19T16:58:08.174Z,1747673888.174 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-05-19T16:58:08.174Z,1747673888.174 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5.
2025-05-19T16:58:08.176Z,1747673888.176 [BPC1](INFO): Received data from all battery sticks.
2025-05-19T17:00:48.161Z,1747674048.161 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:00:48.161Z,1747674048.161 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:00:48.161Z,1747674048.161 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:00:48.161Z,1747674048.161 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:00:48.590Z,1747674048.590 [Default:CheckIn:D] Stopped
2025-05-19T17:00:48.590Z,1747674048.590 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:00:48.979Z,1747674048.979 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  74.316374 min 
2025-05-19T17:00:48.979Z,1747674048.979 [Default:CheckIn:E] Stopped
2025-05-19T17:00:48.979Z,1747674048.979 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:00:48.979Z,1747674048.979 [Default:CheckIn] Stopped
2025-05-19T17:00:48.979Z,1747674048.979 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:00:48.979Z,1747674048.979 [Default:CheckIn](INFO): Running loop #13
2025-05-19T17:00:48.979Z,1747674048.979 [Default:CheckIn] Running Loop=13
2025-05-19T17:00:48.979Z,1747674048.979 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:00:48.980Z,1747674048.980 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:00:50.988Z,1747674050.988 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170050.00,A,3648.16415,N,12147.21561,W,0.000,11.78,190525,,,D*41
2025-05-19T17:00:50.991Z,1747674050.991 [NAL9602](INFO): GPS fix at 20250519T170050: (36.802736, -121.786927)
2025-05-19T17:00:51.023Z,1747674051.023 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:00:51.023Z,1747674051.023 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:00:58.329Z,1747674058.329 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250519T154143/Courier0043.lzma
2025-05-19T17:00:59.331Z,1747674059.331 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0043.lzma.bak
2025-05-19T17:00:59.331Z,1747674059.331 [DataOverHttps](INFO): SBD MOMSN=24921124
2025-05-19T17:01:15.229Z,1747674075.229 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250519T154143/Express0044.lzma
2025-05-19T17:01:16.231Z,1747674076.231 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0044.lzma.bak
2025-05-19T17:01:16.231Z,1747674076.231 [DataOverHttps](INFO): SBD MOMSN=24921127
2025-05-19T17:01:17.285Z,1747674077.285 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:01:17.285Z,1747674077.285 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:01:17.286Z,1747674077.286 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:01:22.925Z,1747674082.925 [NAL9602](INFO): SBD MO Status=0, MOMSN=24347, MT Status=0, MTMSN=0
2025-05-19T17:01:22.925Z,1747674082.925 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:01:53.626Z,1747674113.626 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:06:17.884Z,1747674377.884 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:06:17.885Z,1747674377.885 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:06:17.885Z,1747674377.885 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:06:17.885Z,1747674377.885 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:06:18.287Z,1747674378.287 [Default:CheckIn:D] Stopped
2025-05-19T17:06:18.287Z,1747674378.287 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:06:18.705Z,1747674378.705 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  79.811328 min 
2025-05-19T17:06:18.705Z,1747674378.705 [Default:CheckIn:E] Stopped
2025-05-19T17:06:18.705Z,1747674378.705 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:06:18.705Z,1747674378.705 [Default:CheckIn] Stopped
2025-05-19T17:06:18.705Z,1747674378.705 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:06:18.706Z,1747674378.706 [Default:CheckIn](INFO): Running loop #14
2025-05-19T17:06:18.706Z,1747674378.706 [Default:CheckIn] Running Loop=14
2025-05-19T17:06:18.706Z,1747674378.706 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:06:18.706Z,1747674378.706 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:06:20.704Z,1747674380.704 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170620.00,A,3648.16492,N,12147.21486,W,0.058,11.78,190525,,,D*4A
2025-05-19T17:06:20.706Z,1747674380.706 [NAL9602](INFO): GPS fix at 20250519T170620: (36.802749, -121.786914)
2025-05-19T17:06:20.718Z,1747674380.718 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:06:20.718Z,1747674380.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:06:28.365Z,1747674388.365 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0046.lzma
2025-05-19T17:06:29.367Z,1747674389.367 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0046.lzma.bak
2025-05-19T17:06:29.367Z,1747674389.367 [DataOverHttps](INFO): SBD MOMSN=24921142
2025-05-19T17:06:45.333Z,1747674405.333 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250519T154143/Express0047.lzma
2025-05-19T17:06:46.336Z,1747674406.336 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0047.lzma.bak
2025-05-19T17:06:46.336Z,1747674406.336 [DataOverHttps](INFO): SBD MOMSN=24921145
2025-05-19T17:06:47.401Z,1747674407.401 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:06:47.401Z,1747674407.401 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:06:47.402Z,1747674407.402 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:06:50.204Z,1747674410.204 [NAL9602](INFO): SBD MO Status=0, MOMSN=24348, MT Status=0, MTMSN=0
2025-05-19T17:06:50.204Z,1747674410.204 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:07:20.942Z,1747674440.942 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:11:48.025Z,1747674708.025 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:11:48.026Z,1747674708.026 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:11:48.026Z,1747674708.026 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:11:48.026Z,1747674708.026 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:11:48.425Z,1747674708.425 [Default:CheckIn:D] Stopped
2025-05-19T17:11:48.425Z,1747674708.425 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:11:48.829Z,1747674708.829 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  85.313623 min 
2025-05-19T17:11:48.829Z,1747674708.829 [Default:CheckIn:E] Stopped
2025-05-19T17:11:48.829Z,1747674708.829 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:11:48.829Z,1747674708.829 [Default:CheckIn] Stopped
2025-05-19T17:11:48.829Z,1747674708.829 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:11:48.830Z,1747674708.830 [Default:CheckIn](INFO): Running loop #15
2025-05-19T17:11:48.830Z,1747674708.830 [Default:CheckIn] Running Loop=15
2025-05-19T17:11:48.830Z,1747674708.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:11:48.830Z,1747674708.830 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:11:50.836Z,1747674710.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171150.00,A,3648.16268,N,12147.21541,W,0.019,0.00,190525,,,D*78
2025-05-19T17:11:50.839Z,1747674710.839 [NAL9602](INFO): GPS fix at 20250519T171150: (36.802711, -121.786924)
2025-05-19T17:11:50.850Z,1747674710.850 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:11:50.850Z,1747674710.850 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:11:58.601Z,1747674718.601 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0049.lzma
2025-05-19T17:11:59.603Z,1747674719.603 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0049.lzma.bak
2025-05-19T17:11:59.603Z,1747674719.603 [DataOverHttps](INFO): SBD MOMSN=24921208
2025-05-19T17:12:09.823Z,1747674729.823 [NAL9602](INFO): SBD MO Status=0, MOMSN=24349, MT Status=0, MTMSN=0
2025-05-19T17:12:09.823Z,1747674729.823 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:12:15.493Z,1747674735.493 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250519T154143/Express0050.lzma
2025-05-19T17:12:16.495Z,1747674736.495 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0050.lzma.bak
2025-05-19T17:12:16.495Z,1747674736.495 [DataOverHttps](INFO): SBD MOMSN=24921211
2025-05-19T17:12:17.520Z,1747674737.520 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:12:17.520Z,1747674737.520 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:12:17.520Z,1747674737.520 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:12:40.524Z,1747674760.524 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:15:03.818Z,1747674903.818 [CommandExec](IMPORTANT): got command failComponent
2025-05-19T17:15:03.818Z,1747674903.818 [CommandExec](IMPORTANT): Failed components:
2025-05-19T17:15:03.818Z,1747674903.818 [CommandExec](IMPORTANT): No failed Components.
2025-05-19T17:15:09.026Z,1747674909.026 [CommandExec](IMPORTANT): got command gfscan
2025-05-19T17:15:09.268Z,1747674909.268 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-19T17:15:13.714Z,1747674913.714 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000001
CHAN A1 (24V): 0.000989
CHAN A2 (12V): 0.000860
CHAN A3 (5V): -0.001813
CHAN B0 (3.3V): 0.000676
CHAN B1 (3.15aV): -0.000137
CHAN B2 (3.15bV): 0.000448
CHAN B3 (GND): -0.000413
OPEN: 0.006848
Full Scale: +/- 1 mA
2025-05-19T17:17:18.142Z,1747675038.142 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:17:18.142Z,1747675038.142 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:17:18.142Z,1747675038.142 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:17:18.142Z,1747675038.142 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:17:18.541Z,1747675038.541 [Default:CheckIn:D] Stopped
2025-05-19T17:17:18.558Z,1747675038.558 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:17:18.943Z,1747675038.943 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  90.815552 min 
2025-05-19T17:17:18.943Z,1747675038.943 [Default:CheckIn:E] Stopped
2025-05-19T17:17:18.943Z,1747675038.943 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:17:18.943Z,1747675038.943 [Default:CheckIn] Stopped
2025-05-19T17:17:18.943Z,1747675038.943 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:17:18.943Z,1747675038.943 [Default:CheckIn](INFO): Running loop #16
2025-05-19T17:17:18.943Z,1747675038.943 [Default:CheckIn] Running Loop=16
2025-05-19T17:17:18.943Z,1747675038.943 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:17:18.943Z,1747675038.943 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:17:20.955Z,1747675040.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171720.00,A,3648.16394,N,12147.21564,W,0.039,0.00,190525,,,D*7E
2025-05-19T17:17:20.957Z,1747675040.957 [NAL9602](INFO): GPS fix at 20250519T171720: (36.802732, -121.786927)
2025-05-19T17:17:20.969Z,1747675040.969 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:17:20.969Z,1747675040.969 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:17:28.720Z,1747675048.720 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0052.lzma
2025-05-19T17:17:29.723Z,1747675049.723 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0052.lzma.bak
2025-05-19T17:17:29.723Z,1747675049.723 [DataOverHttps](INFO): SBD MOMSN=24921226
2025-05-19T17:17:45.602Z,1747675065.602 [DataOverHttps](INFO): Sending 393 bytes from file Logs/20250519T154143/Express0053.lzma
2025-05-19T17:17:46.603Z,1747675066.603 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0053.lzma.bak
2025-05-19T17:17:46.603Z,1747675066.603 [DataOverHttps](INFO): SBD MOMSN=24921232
2025-05-19T17:17:46.812Z,1747675066.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=24350, MT Status=2, MTMSN=0
2025-05-19T17:17:46.812Z,1747675066.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T17:17:47.639Z,1747675067.639 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:17:47.639Z,1747675067.639 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:17:47.639Z,1747675067.639 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:18:04.184Z,1747675084.184 [NAL9602](INFO): SBD MO Status=2, MOMSN=24350, MT Status=2, MTMSN=0
2025-05-19T17:18:04.184Z,1747675084.184 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T17:18:21.552Z,1747675101.552 [NAL9602](INFO): SBD MO Status=2, MOMSN=24350, MT Status=2, MTMSN=0
2025-05-19T17:18:21.552Z,1747675101.552 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T17:18:38.931Z,1747675118.931 [NAL9602](INFO): SBD MO Status=2, MOMSN=24350, MT Status=2, MTMSN=0
2025-05-19T17:18:38.932Z,1747675118.932 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T17:18:48.226Z,1747675128.226 [NAL9602](INFO): SBD MO Status=0, MOMSN=24350, MT Status=0, MTMSN=0
2025-05-19T17:18:48.226Z,1747675128.226 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:19:18.934Z,1747675158.934 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:22:48.213Z,1747675368.213 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:22:48.246Z,1747675368.246 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:22:48.246Z,1747675368.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:22:48.246Z,1747675368.246 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:22:48.628Z,1747675368.628 [Default:CheckIn:D] Stopped
2025-05-19T17:22:48.628Z,1747675368.628 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:22:49.034Z,1747675369.034 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  96.317017 min 
2025-05-19T17:22:49.035Z,1747675369.035 [Default:CheckIn:E] Stopped
2025-05-19T17:22:49.035Z,1747675369.035 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:22:49.035Z,1747675369.035 [Default:CheckIn] Stopped
2025-05-19T17:22:49.035Z,1747675369.035 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:22:49.035Z,1747675369.035 [Default:CheckIn](INFO): Running loop #17
2025-05-19T17:22:49.035Z,1747675369.035 [Default:CheckIn] Running Loop=17
2025-05-19T17:22:49.035Z,1747675369.035 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:22:49.035Z,1747675369.035 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:22:51.036Z,1747675371.036 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172250.00,A,3648.16366,N,12147.21537,W,0.019,0.00,190525,,,D*76
2025-05-19T17:22:51.038Z,1747675371.038 [NAL9602](INFO): GPS fix at 20250519T172250: (36.802728, -121.786923)
2025-05-19T17:22:51.050Z,1747675371.050 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:22:51.050Z,1747675371.050 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:22:58.400Z,1747675378.400 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0055.lzma
2025-05-19T17:22:59.403Z,1747675379.403 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0055.lzma.bak
2025-05-19T17:22:59.403Z,1747675379.403 [DataOverHttps](INFO): SBD MOMSN=24921301
2025-05-19T17:23:15.257Z,1747675395.257 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250519T154143/Express0056.lzma
2025-05-19T17:23:16.259Z,1747675396.259 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0056.lzma.bak
2025-05-19T17:23:16.259Z,1747675396.259 [DataOverHttps](INFO): SBD MOMSN=24921310
2025-05-19T17:23:17.308Z,1747675397.308 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:23:17.308Z,1747675397.308 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:23:17.308Z,1747675397.308 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:24:06.588Z,1747675446.588 [NAL9602](INFO): SBD MO Status=0, MOMSN=24351, MT Status=0, MTMSN=0
2025-05-19T17:24:06.589Z,1747675446.589 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:24:37.310Z,1747675477.310 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:27:29.483Z,1747675649.483 [DataOverHttps](IMPORTANT): SBD MTMSN=20250519T172728
2025-05-19T17:27:36.977Z,1747675656.977 [DataOverHttps](INFO): Received command: ibit
2025-05-19T17:27:37.010Z,1747675657.010 [CommandExec](IMPORTANT): got command ibit
2025-05-19T17:27:37.124Z,1747675657.124 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-05-19T17:27:37.125Z,1747675657.125 [IBIT](IMPORTANT): Beginning control surface checks.
2025-05-19T17:27:37.132Z,1747675657.132 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-19T17:27:38.700Z,1747675658.700 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172738.00,A,3648.16396,N,12147.21539,W,0.039,0.00,190525,,,D*7E
2025-05-19T17:27:38.702Z,1747675658.702 [NAL9602](INFO): GPS fix at 20250519T172738: (36.802733, -121.786923)
2025-05-19T17:27:41.721Z,1747675661.721 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000202
CHAN A1 (24V): 0.001110
CHAN A2 (12V): 0.000174
CHAN A3 (5V): -0.001654
CHAN B0 (3.3V): 0.000688
CHAN B1 (3.15aV): 0.000381
CHAN B2 (3.15bV): 0.000286
CHAN B3 (GND): -0.000328
OPEN: 0.007080
Full Scale: +/- 1 mA
2025-05-19T17:27:53.648Z,1747675673.648 [NAL9602](INFO): SBD MO Status=0, MOMSN=24352, MT Status=0, MTMSN=0
2025-05-19T17:27:53.648Z,1747675673.648 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:27:54.864Z,1747675674.864 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172754.00,A,3648.16371,N,12147.21530,W,0.019,0.00,190525,,,D*76
2025-05-19T17:27:54.866Z,1747675674.866 [NAL9602](INFO): GPS fix at 20250519T172754: (36.802729, -121.786922)
2025-05-19T17:27:57.691Z,1747675677.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172757.00,A,3648.16366,N,12147.21538,W,0.019,0.00,190525,,,D*7B
2025-05-19T17:27:57.694Z,1747675677.694 [NAL9602](INFO): GPS fix at 20250519T172757: (36.802728, -121.786923)
2025-05-19T17:28:00.922Z,1747675680.922 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172800.00,A,3648.16350,N,12147.21561,W,0.019,0.00,190525,,,D*7F
2025-05-19T17:28:00.924Z,1747675680.924 [NAL9602](INFO): GPS fix at 20250519T172800: (36.802725, -121.786927)
2025-05-19T17:28:03.756Z,1747675683.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172803.00,A,3648.16342,N,12147.21576,W,0.078,0.00,190525,,,D*7E
2025-05-19T17:28:03.758Z,1747675683.758 [NAL9602](INFO): GPS fix at 20250519T172803: (36.802724, -121.786929)
2025-05-19T17:28:06.982Z,1747675686.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172806.00,A,3648.16346,N,12147.21578,W,0.039,0.00,190525,,,D*74
2025-05-19T17:28:06.984Z,1747675686.984 [NAL9602](INFO): GPS fix at 20250519T172806: (36.802724, -121.786930)
2025-05-19T17:28:09.808Z,1747675689.808 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172809.00,A,3648.16357,N,12147.21572,W,0.175,0.00,190525,,,D*78
2025-05-19T17:28:09.811Z,1747675689.811 [NAL9602](INFO): GPS fix at 20250519T172809: (36.802726, -121.786929)
2025-05-19T17:28:13.040Z,1747675693.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172812.00,A,3648.16358,N,12147.21564,W,0.156,0.00,190525,,,D*7B
2025-05-19T17:28:13.042Z,1747675693.042 [NAL9602](INFO): GPS fix at 20250519T172812: (36.802726, -121.786927)
2025-05-19T17:28:15.868Z,1747675695.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172815.00,A,3648.16360,N,12147.21550,W,0.019,0.00,190525,,,D*7A
2025-05-19T17:28:15.871Z,1747675695.871 [NAL9602](INFO): GPS fix at 20250519T172815: (36.802727, -121.786925)
2025-05-19T17:28:18.703Z,1747675698.703 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172818.00,A,3648.16357,N,12147.21546,W,0.000,0.00,190525,,,D*7C
2025-05-19T17:28:18.706Z,1747675698.706 [NAL9602](INFO): GPS fix at 20250519T172818: (36.802726, -121.786924)
2025-05-19T17:28:21.932Z,1747675701.932 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172821.00,A,3648.16357,N,12147.21552,W,0.019,0.00,190525,,,D*7B
2025-05-19T17:28:21.935Z,1747675701.935 [NAL9602](INFO): GPS fix at 20250519T172821: (36.802726, -121.786925)
2025-05-19T17:28:23.164Z,1747675703.164 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802727 Longitude: -121.786926
2025-05-19T17:28:23.581Z,1747675703.581 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.572001
2025-05-19T17:28:23.582Z,1747675703.582 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-05-19T17:28:23.582Z,1747675703.582 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-05-19T17:28:23.962Z,1747675703.962 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-05-19T17:28:23.963Z,1747675703.963 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-05-19T17:28:23.963Z,1747675703.963 [IBIT](IMPORTANT): Pressure:10.177111 PSI
2025-05-19T17:28:23.963Z,1747675703.963 [IBIT](IMPORTANT): Humidity:21.725063 %
2025-05-19T17:28:24.369Z,1747675704.369 [IBIT](IMPORTANT): Vehicle Pitch:0.389863 degrees
2025-05-19T17:28:24.369Z,1747675704.369 [IBIT](IMPORTANT): Vehicle  Roll:-8.045690 degrees
2025-05-19T17:28:24.370Z,1747675704.370 [IBIT](IMPORTANT): Vehicle  Heading:19.901428 degrees
2025-05-19T17:28:24.782Z,1747675704.782 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-05-19T17:28:24.782Z,1747675704.782 [IBIT](IMPORTANT): buoyancyNeutral: 284.393585 cc
2025-05-19T17:28:24.782Z,1747675704.782 [IBIT](IMPORTANT): massDefault: 2.056432 cm
2025-05-19T17:28:24.783Z,1747675704.783 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-05-19T17:28:24.783Z,1747675704.783 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-05-19T17:28:24.783Z,1747675704.783 [IBIT](IMPORTANT): IBIT PASSED
2025-05-19T17:28:25.176Z,1747675705.176 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:28:25.177Z,1747675705.177 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:28:25.177Z,1747675705.177 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:28:25.193Z,1747675705.193 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:28:25.574Z,1747675705.574 [Default:CheckIn:D] Stopped
2025-05-19T17:28:25.574Z,1747675705.574 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:28:25.979Z,1747675705.979 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  101.932772 min 
2025-05-19T17:28:25.979Z,1747675705.979 [Default:CheckIn:E] Stopped
2025-05-19T17:28:25.979Z,1747675705.979 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:28:25.979Z,1747675705.979 [Default:CheckIn] Stopped
2025-05-19T17:28:25.979Z,1747675705.979 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:28:25.979Z,1747675705.979 [Default:CheckIn](INFO): Running loop #18
2025-05-19T17:28:25.979Z,1747675705.979 [Default:CheckIn] Running Loop=18
2025-05-19T17:28:25.980Z,1747675705.980 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:28:25.980Z,1747675705.980 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:28:28.002Z,1747675708.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172827.00,A,3648.16354,N,12147.21559,W,0.019,0.00,190525,,,D*75
2025-05-19T17:28:28.004Z,1747675708.004 [NAL9602](INFO): GPS fix at 20250519T172827: (36.802726, -121.786927)
2025-05-19T17:28:28.016Z,1747675708.016 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:28:28.016Z,1747675708.016 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:28:35.348Z,1747675715.348 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0058.lzma
2025-05-19T17:28:36.351Z,1747675716.351 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0058.lzma.bak
2025-05-19T17:28:36.351Z,1747675716.351 [DataOverHttps](INFO): SBD MOMSN=24921354
2025-05-19T17:28:55.011Z,1747675735.011 [DataOverHttps](INFO): Sending 969 bytes from file Logs/20250519T154143/Express0059.lzma
2025-05-19T17:28:56.011Z,1747675736.011 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0059.lzma.bak
2025-05-19T17:28:56.011Z,1747675736.011 [DataOverHttps](INFO): SBD MOMSN=24921358
2025-05-19T17:29:00.354Z,1747675740.354 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:29:12.101Z,1747675752.101 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250519T154143/Express0062.lzma
2025-05-19T17:29:13.103Z,1747675753.103 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0062.lzma.bak
2025-05-19T17:29:13.103Z,1747675753.103 [DataOverHttps](INFO): SBD MOMSN=24921397
2025-05-19T17:29:14.509Z,1747675754.509 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:29:14.509Z,1747675754.509 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:29:14.509Z,1747675754.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:34:15.181Z,1747676055.181 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:34:15.181Z,1747676055.181 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:34:15.181Z,1747676055.181 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:34:15.181Z,1747676055.181 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:34:15.588Z,1747676055.588 [Default:CheckIn:D] Stopped
2025-05-19T17:34:15.588Z,1747676055.588 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:34:16.005Z,1747676056.005 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  107.766349 min 
2025-05-19T17:34:16.005Z,1747676056.005 [Default:CheckIn:E] Stopped
2025-05-19T17:34:16.006Z,1747676056.006 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:34:16.006Z,1747676056.006 [Default:CheckIn] Stopped
2025-05-19T17:34:16.006Z,1747676056.006 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:34:16.006Z,1747676056.006 [Default:CheckIn](INFO): Running loop #19
2025-05-19T17:34:16.006Z,1747676056.006 [Default:CheckIn] Running Loop=19
2025-05-19T17:34:16.006Z,1747676056.006 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:34:16.006Z,1747676056.006 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:34:18.004Z,1747676058.004 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173417.00,A,3648.16412,N,12147.21423,W,0.078,0.00,190525,,,D*75
2025-05-19T17:34:18.006Z,1747676058.006 [NAL9602](INFO): GPS fix at 20250519T173417: (36.802735, -121.786904)
2025-05-19T17:34:18.017Z,1747676058.017 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:34:18.018Z,1747676058.018 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:34:18.822Z,1747676058.822 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:34:18.822Z,1747676058.822 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:34:18.822Z,1747676058.822 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:34:46.684Z,1747676086.684 [NAL9602](INFO): SBD MO Status=2, MOMSN=24353, MT Status=2, MTMSN=0
2025-05-19T17:34:46.684Z,1747676086.684 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T17:34:55.636Z,1747676095.636 [NAL9602](INFO): SBD MO Status=0, MOMSN=24353, MT Status=0, MTMSN=0
2025-05-19T17:34:55.636Z,1747676095.636 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:35:26.342Z,1747676126.342 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:39:00.966Z,1747676340.966 [DataOverHttps](IMPORTANT): SBD MTMSN=20250519T173900
2025-05-19T17:39:08.457Z,1747676348.457 [DataOverHttps](INFO): Received command: failc
2025-05-19T17:39:08.461Z,1747676348.461 [CommandExec](IMPORTANT): got command failComponent
2025-05-19T17:39:08.461Z,1747676348.461 [CommandExec](IMPORTANT): Failed components:
2025-05-19T17:39:08.462Z,1747676348.462 [CommandExec](IMPORTANT): No failed Components.
2025-05-19T17:39:19.479Z,1747676359.479 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:39:19.479Z,1747676359.479 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:39:19.479Z,1747676359.479 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:39:19.479Z,1747676359.479 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:39:19.892Z,1747676359.892 [Default:CheckIn:D] Stopped
2025-05-19T17:39:19.892Z,1747676359.892 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:39:20.290Z,1747676360.290 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  112.838070 min 
2025-05-19T17:39:20.290Z,1747676360.290 [Default:CheckIn:E] Stopped
2025-05-19T17:39:20.290Z,1747676360.290 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:39:20.290Z,1747676360.290 [Default:CheckIn] Stopped
2025-05-19T17:39:20.290Z,1747676360.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:39:20.290Z,1747676360.290 [Default:CheckIn](INFO): Running loop #20
2025-05-19T17:39:20.290Z,1747676360.290 [Default:CheckIn] Running Loop=20
2025-05-19T17:39:20.291Z,1747676360.291 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:39:20.291Z,1747676360.291 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:39:22.300Z,1747676362.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173921.00,A,3648.16406,N,12147.21438,W,0.078,0.00,190525,,,D*72
2025-05-19T17:39:22.302Z,1747676362.302 [NAL9602](INFO): GPS fix at 20250519T173921: (36.802734, -121.786906)
2025-05-19T17:39:22.313Z,1747676362.313 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:39:22.314Z,1747676362.314 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:39:30.076Z,1747676370.076 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0064.lzma
2025-05-19T17:39:31.079Z,1747676371.079 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0064.lzma.bak
2025-05-19T17:39:31.079Z,1747676371.079 [DataOverHttps](INFO): SBD MOMSN=24921463
2025-05-19T17:39:40.887Z,1747676380.887 [NAL9602](INFO): SBD MO Status=0, MOMSN=24354, MT Status=0, MTMSN=0
2025-05-19T17:39:40.888Z,1747676380.888 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:39:46.940Z,1747676386.940 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0067.lzma
2025-05-19T17:39:47.943Z,1747676387.943 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0067.lzma.bak
2025-05-19T17:39:47.943Z,1747676387.943 [DataOverHttps](INFO): SBD MOMSN=24921469
2025-05-19T17:40:03.909Z,1747676403.909 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250519T154143/Express0065.lzma
2025-05-19T17:40:04.911Z,1747676404.911 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0065.lzma.bak
2025-05-19T17:40:04.911Z,1747676404.911 [DataOverHttps](INFO): SBD MOMSN=24921475
2025-05-19T17:40:11.599Z,1747676411.599 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:40:20.917Z,1747676420.917 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20250519T154143/Express0068.lzma
2025-05-19T17:40:21.919Z,1747676421.919 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0068.lzma.bak
2025-05-19T17:40:21.919Z,1747676421.919 [DataOverHttps](INFO): SBD MOMSN=24921478
2025-05-19T17:40:37.973Z,1747676437.973 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250519T154143/Express0071.lzma
2025-05-19T17:40:38.975Z,1747676438.975 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0071.lzma.bak
2025-05-19T17:40:38.975Z,1747676438.975 [DataOverHttps](INFO): SBD MOMSN=24921485
2025-05-19T17:40:40.326Z,1747676440.326 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:40:40.326Z,1747676440.326 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:40:40.326Z,1747676440.326 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:45:40.970Z,1747676740.970 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:45:40.970Z,1747676740.970 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:45:40.970Z,1747676740.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:45:40.970Z,1747676740.970 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:45:41.375Z,1747676741.375 [Default:CheckIn:D] Stopped
2025-05-19T17:45:41.375Z,1747676741.375 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:45:41.790Z,1747676741.790 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  119.196126 min 
2025-05-19T17:45:41.790Z,1747676741.790 [Default:CheckIn:E] Stopped
2025-05-19T17:45:41.790Z,1747676741.790 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:45:41.790Z,1747676741.790 [Default:CheckIn] Stopped
2025-05-19T17:45:41.790Z,1747676741.790 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:45:41.790Z,1747676741.790 [Default:CheckIn](INFO): Running loop #21
2025-05-19T17:45:41.790Z,1747676741.790 [Default:CheckIn] Running Loop=21
2025-05-19T17:45:41.791Z,1747676741.791 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:45:41.791Z,1747676741.791 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:45:43.788Z,1747676743.788 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174543.00,A,3648.16330,N,12147.21357,W,0.019,0.00,190525,,,D*76
2025-05-19T17:45:43.790Z,1747676743.790 [NAL9602](INFO): GPS fix at 20250519T174543: (36.802722, -121.786893)
2025-05-19T17:45:43.802Z,1747676743.802 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:45:43.802Z,1747676743.802 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:45:51.461Z,1747676751.461 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0073.lzma
2025-05-19T17:45:52.463Z,1747676752.463 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0073.lzma.bak
2025-05-19T17:45:52.463Z,1747676752.463 [DataOverHttps](INFO): SBD MOMSN=24921589
2025-05-19T17:46:08.437Z,1747676768.437 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250519T154143/Express0074.lzma
2025-05-19T17:46:09.440Z,1747676769.440 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0074.lzma.bak
2025-05-19T17:46:09.440Z,1747676769.440 [DataOverHttps](INFO): SBD MOMSN=24921608
2025-05-19T17:46:10.482Z,1747676770.482 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:46:10.482Z,1747676770.482 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:46:10.482Z,1747676770.482 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:46:10.856Z,1747676770.856 [NAL9602](INFO): SBD MO Status=2, MOMSN=24355, MT Status=2, MTMSN=0
2025-05-19T17:46:10.856Z,1747676770.856 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T17:46:20.577Z,1747676780.577 [NAL9602](INFO): SBD MO Status=0, MOMSN=24355, MT Status=0, MTMSN=0
2025-05-19T17:46:20.577Z,1747676780.577 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:46:51.308Z,1747676811.308 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:51:11.194Z,1747677071.194 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:51:11.194Z,1747677071.194 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:51:11.194Z,1747677071.194 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:51:11.194Z,1747677071.194 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:51:11.588Z,1747677071.588 [Default:CheckIn:D] Stopped
2025-05-19T17:51:11.588Z,1747677071.588 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:51:11.991Z,1747677071.991 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  124.699683 min 
2025-05-19T17:51:11.991Z,1747677071.991 [Default:CheckIn:E] Stopped
2025-05-19T17:51:11.991Z,1747677071.991 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:51:11.991Z,1747677071.991 [Default:CheckIn] Stopped
2025-05-19T17:51:11.991Z,1747677071.991 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:51:11.991Z,1747677071.991 [Default:CheckIn](INFO): Running loop #22
2025-05-19T17:51:11.991Z,1747677071.991 [Default:CheckIn] Running Loop=22
2025-05-19T17:51:11.991Z,1747677071.991 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:51:11.991Z,1747677071.991 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:51:14.003Z,1747677074.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175113.00,A,3648.16396,N,12147.21457,W,0.019,0.00,190525,,,D*7D
2025-05-19T17:51:14.006Z,1747677074.006 [NAL9602](INFO): GPS fix at 20250519T175113: (36.802733, -121.786909)
2025-05-19T17:51:14.017Z,1747677074.017 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:51:14.017Z,1747677074.017 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:51:21.281Z,1747677081.281 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0076.lzma
2025-05-19T17:51:22.283Z,1747677082.283 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0076.lzma.bak
2025-05-19T17:51:22.283Z,1747677082.283 [DataOverHttps](INFO): SBD MOMSN=24921630
2025-05-19T17:51:38.265Z,1747677098.265 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250519T154143/Express0077.lzma
2025-05-19T17:51:39.267Z,1747677099.267 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0077.lzma.bak
2025-05-19T17:51:39.267Z,1747677099.267 [DataOverHttps](INFO): SBD MOMSN=24921636
2025-05-19T17:51:40.288Z,1747677100.288 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:51:40.288Z,1747677100.288 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:51:40.288Z,1747677100.288 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:52:04.099Z,1747677124.099 [NAL9602](INFO): SBD MO Status=0, MOMSN=24356, MT Status=0, MTMSN=0
2025-05-19T17:52:04.099Z,1747677124.099 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:52:34.802Z,1747677154.802 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:56:40.998Z,1747677400.998 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T17:56:40.998Z,1747677400.998 [Default:CheckIn:C.Wait] Stopped
2025-05-19T17:56:40.998Z,1747677400.998 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T17:56:40.998Z,1747677400.998 [Default:CheckIn:D] Running Loop=1
2025-05-19T17:56:41.397Z,1747677401.397 [Default:CheckIn:D] Stopped
2025-05-19T17:56:41.397Z,1747677401.397 [Default:CheckIn:E] Running Loop=1
2025-05-19T17:56:41.798Z,1747677401.798 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  130.196484 min 
2025-05-19T17:56:41.798Z,1747677401.798 [Default:CheckIn:E] Stopped
2025-05-19T17:56:41.798Z,1747677401.798 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T17:56:41.798Z,1747677401.798 [Default:CheckIn] Stopped
2025-05-19T17:56:41.798Z,1747677401.798 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T17:56:41.798Z,1747677401.798 [Default:CheckIn](INFO): Running loop #23
2025-05-19T17:56:41.799Z,1747677401.799 [Default:CheckIn] Running Loop=23
2025-05-19T17:56:41.799Z,1747677401.799 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T17:56:41.799Z,1747677401.799 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T17:56:43.810Z,1747677403.810 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175643.00,A,3648.16515,N,12147.21405,W,0.156,0.00,190525,,,D*7F
2025-05-19T17:56:43.812Z,1747677403.812 [NAL9602](INFO): GPS fix at 20250519T175643: (36.802752, -121.786901)
2025-05-19T17:56:43.824Z,1747677403.824 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T17:56:43.824Z,1747677403.824 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T17:56:54.032Z,1747677414.032 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0079.lzma
2025-05-19T17:56:55.035Z,1747677415.035 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0079.lzma.bak
2025-05-19T17:56:55.035Z,1747677415.035 [DataOverHttps](INFO): SBD MOMSN=24921710
2025-05-19T17:56:57.204Z,1747677417.204 [NAL9602](INFO): SBD MO Status=0, MOMSN=24357, MT Status=0, MTMSN=0
2025-05-19T17:56:57.204Z,1747677417.204 [NAL9602](INFO): No messages in MT queue
2025-05-19T17:57:10.906Z,1747677430.906 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250519T154143/Express0080.lzma
2025-05-19T17:57:11.907Z,1747677431.907 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0080.lzma.bak
2025-05-19T17:57:11.907Z,1747677431.907 [DataOverHttps](INFO): SBD MOMSN=24921713
2025-05-19T17:57:12.976Z,1747677432.976 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T17:57:12.976Z,1747677432.976 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T17:57:12.976Z,1747677432.976 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T17:57:27.903Z,1747677447.903 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T17:59:14.626Z,1747677554.626 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T17:59:27.960Z,1747677567.960 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T17:59:41.289Z,1747677581.289 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T17:59:55.025Z,1747677595.025 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:00:09.168Z,1747677609.168 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:00:22.902Z,1747677622.902 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:00:37.041Z,1747677637.041 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:00:50.791Z,1747677650.791 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:01:04.557Z,1747677664.557 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:01:18.294Z,1747677678.294 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:02:13.654Z,1747677733.654 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:02:13.654Z,1747677733.654 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:02:13.654Z,1747677733.654 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:02:13.654Z,1747677733.654 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:02:14.069Z,1747677734.069 [Default:CheckIn:D] Stopped
2025-05-19T18:02:14.069Z,1747677734.069 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:02:14.476Z,1747677734.476 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  135.741024 min 
2025-05-19T18:02:14.476Z,1747677734.476 [Default:CheckIn:E] Stopped
2025-05-19T18:02:14.476Z,1747677734.476 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:02:14.476Z,1747677734.476 [Default:CheckIn] Stopped
2025-05-19T18:02:14.476Z,1747677734.476 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:02:14.476Z,1747677734.476 [Default:CheckIn](INFO): Running loop #24
2025-05-19T18:02:14.476Z,1747677734.476 [Default:CheckIn] Running Loop=24
2025-05-19T18:02:14.477Z,1747677734.477 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:02:14.477Z,1747677734.477 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:02:16.473Z,1747677736.473 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180215.00,A,3648.16397,N,12147.21561,W,0.078,0.00,190525,,,D*70
2025-05-19T18:02:16.475Z,1747677736.475 [NAL9602](INFO): GPS fix at 20250519T180215: (36.802733, -121.786927)
2025-05-19T18:02:16.486Z,1747677736.486 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:02:16.487Z,1747677736.487 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:02:24.121Z,1747677744.121 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0082.lzma
2025-05-19T18:02:25.124Z,1747677745.124 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0082.lzma.bak
2025-05-19T18:02:25.124Z,1747677745.124 [DataOverHttps](INFO): SBD MOMSN=24921746
2025-05-19T18:02:41.185Z,1747677761.185 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250519T154143/Express0083.lzma
2025-05-19T18:02:42.187Z,1747677762.187 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0083.lzma.bak
2025-05-19T18:02:42.187Z,1747677762.187 [DataOverHttps](INFO): SBD MOMSN=24921792
2025-05-19T18:02:43.557Z,1747677763.557 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:02:43.557Z,1747677763.557 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:02:43.557Z,1747677763.557 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:02:47.836Z,1747677767.836 [NAL9602](INFO): SBD MO Status=2, MOMSN=24358, MT Status=2, MTMSN=0
2025-05-19T18:02:47.836Z,1747677767.836 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:02:56.948Z,1747677776.948 [NAL9602](INFO): SBD MO Status=0, MOMSN=24358, MT Status=0, MTMSN=0
2025-05-19T18:02:56.948Z,1747677776.948 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:03:27.656Z,1747677807.656 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:03:53.097Z,1747677833.097 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:04:08.053Z,1747677848.053 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:04:23.398Z,1747677863.398 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:04:38.345Z,1747677878.345 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:04:53.367Z,1747677893.367 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:05:08.318Z,1747677908.318 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:05:23.681Z,1747677923.681 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:05:39.021Z,1747677939.021 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:05:54.373Z,1747677954.373 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-19T18:06:08.918Z,1747677968.918 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-05-19T18:06:15.389Z,1747677975.389 [BPC1](FAULT): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-05-19T18:06:15.390Z,1747677975.390 [BPC1](FAULT): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-05-19T18:06:15.390Z,1747677975.390 [BPC1](FAULT): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-05-19T18:06:15.391Z,1747677975.391 [BPC1](INFO): Calculating totals. Valid battery stick count: 43. Valid reserve battery stick count: 5.
2025-05-19T18:06:15.394Z,1747677975.394 [BPC1](INFO): Received data from all battery sticks.
2025-05-19T18:07:44.331Z,1747678064.331 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:07:44.331Z,1747678064.331 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:07:44.331Z,1747678064.331 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:07:44.331Z,1747678064.331 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:07:44.744Z,1747678064.744 [Default:CheckIn:D] Stopped
2025-05-19T18:07:44.744Z,1747678064.744 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:07:45.142Z,1747678065.142 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  141.252279 min 
2025-05-19T18:07:45.142Z,1747678065.142 [Default:CheckIn:E] Stopped
2025-05-19T18:07:45.142Z,1747678065.142 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:07:45.142Z,1747678065.142 [Default:CheckIn] Stopped
2025-05-19T18:07:45.142Z,1747678065.142 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:07:45.142Z,1747678065.142 [Default:CheckIn](INFO): Running loop #25
2025-05-19T18:07:45.142Z,1747678065.142 [Default:CheckIn] Running Loop=25
2025-05-19T18:07:45.143Z,1747678065.143 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:07:45.143Z,1747678065.143 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:07:47.148Z,1747678067.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180746.00,A,3648.35633,N,12147.12202,W,2.799,3.98,190525,,,D*73
2025-05-19T18:07:47.151Z,1747678067.151 [NAL9602](INFO): GPS fix at 20250519T180746: (36.805939, -121.785367)
2025-05-19T18:07:47.163Z,1747678067.163 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:07:47.163Z,1747678067.163 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:07:54.776Z,1747678074.776 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250519T154143/Courier0085.lzma
2025-05-19T18:07:55.779Z,1747678075.779 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0085.lzma.bak
2025-05-19T18:07:55.779Z,1747678075.779 [DataOverHttps](INFO): SBD MOMSN=24921810
2025-05-19T18:08:04.120Z,1747678084.120 [NAL9602](INFO): SBD MO Status=2, MOMSN=24359, MT Status=2, MTMSN=0
2025-05-19T18:08:04.120Z,1747678084.120 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:08:11.734Z,1747678091.734 [DataOverHttps](INFO): Sending 305 bytes from file Logs/20250519T154143/Express0086.lzma
2025-05-19T18:08:12.735Z,1747678092.735 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0086.lzma.bak
2025-05-19T18:08:12.735Z,1747678092.735 [DataOverHttps](INFO): SBD MOMSN=24921813
2025-05-19T18:08:13.825Z,1747678093.825 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:08:13.825Z,1747678093.825 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:08:13.825Z,1747678093.825 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:08:57.116Z,1747678137.116 [NAL9602](INFO): SBD MO Status=2, MOMSN=24359, MT Status=2, MTMSN=0
2025-05-19T18:08:57.116Z,1747678137.116 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:09:16.496Z,1747678156.496 [NAL9602](INFO): SBD MO Status=0, MOMSN=24359, MT Status=0, MTMSN=0
2025-05-19T18:09:16.496Z,1747678156.496 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:09:47.194Z,1747678187.194 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:13:14.539Z,1747678394.539 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:13:14.539Z,1747678394.539 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:13:14.539Z,1747678394.539 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:13:14.539Z,1747678394.539 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:13:14.953Z,1747678394.953 [Default:CheckIn:D] Stopped
2025-05-19T18:13:14.953Z,1747678394.953 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:13:15.349Z,1747678395.349 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  146.755762 min 
2025-05-19T18:13:15.350Z,1747678395.350 [Default:CheckIn:E] Stopped
2025-05-19T18:13:15.350Z,1747678395.350 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:13:15.350Z,1747678395.350 [Default:CheckIn] Stopped
2025-05-19T18:13:15.350Z,1747678395.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:13:15.350Z,1747678395.350 [Default:CheckIn](INFO): Running loop #26
2025-05-19T18:13:15.350Z,1747678395.350 [Default:CheckIn] Running Loop=26
2025-05-19T18:13:15.350Z,1747678395.350 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:13:15.350Z,1747678395.350 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:13:17.358Z,1747678397.358 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181316.00,A,3648.45644,N,12147.31820,W,4.724,236.22,190525,,,D*7A
2025-05-19T18:13:17.360Z,1747678397.360 [NAL9602](INFO): GPS fix at 20250519T181316: (36.807607, -121.788637)
2025-05-19T18:13:17.371Z,1747678397.371 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:13:17.371Z,1747678397.371 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:13:24.528Z,1747678404.528 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250519T154143/Courier0088.lzma
2025-05-19T18:13:25.531Z,1747678405.531 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0088.lzma.bak
2025-05-19T18:13:25.531Z,1747678405.531 [DataOverHttps](INFO): SBD MOMSN=24921885
2025-05-19T18:13:34.730Z,1747678414.730 [NAL9602](INFO): SBD MO Status=0, MOMSN=24360, MT Status=0, MTMSN=0
2025-05-19T18:13:34.730Z,1747678414.730 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:13:41.357Z,1747678421.357 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250519T154143/Express0089.lzma
2025-05-19T18:13:42.359Z,1747678422.359 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0089.lzma.bak
2025-05-19T18:13:42.360Z,1747678422.360 [DataOverHttps](INFO): SBD MOMSN=24921889
2025-05-19T18:13:43.626Z,1747678423.626 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:13:43.626Z,1747678423.626 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:13:43.626Z,1747678423.626 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:14:05.431Z,1747678445.431 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:18:44.331Z,1747678724.331 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:18:44.332Z,1747678724.332 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:18:44.332Z,1747678724.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:18:44.332Z,1747678724.332 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:18:44.750Z,1747678724.750 [Default:CheckIn:D] Stopped
2025-05-19T18:18:44.750Z,1747678724.750 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:18:45.130Z,1747678725.130 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  152.252376 min 
2025-05-19T18:18:45.130Z,1747678725.130 [Default:CheckIn:E] Stopped
2025-05-19T18:18:45.130Z,1747678725.130 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:18:45.130Z,1747678725.130 [Default:CheckIn] Stopped
2025-05-19T18:18:45.130Z,1747678725.130 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:18:45.130Z,1747678725.130 [Default:CheckIn](INFO): Running loop #27
2025-05-19T18:18:45.131Z,1747678725.131 [Default:CheckIn] Running Loop=27
2025-05-19T18:18:45.131Z,1747678725.131 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:18:45.131Z,1747678725.131 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:18:47.141Z,1747678727.141 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181846.00,A,3648.31208,N,12147.68823,W,3.616,297.71,190525,,,A*7B
2025-05-19T18:18:47.143Z,1747678727.143 [NAL9602](INFO): GPS fix at 20250519T181846: (36.805201, -121.794804)
2025-05-19T18:18:47.182Z,1747678727.182 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:18:47.183Z,1747678727.183 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:18:58.045Z,1747678738.045 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0091.lzma
2025-05-19T18:18:59.047Z,1747678739.047 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0091.lzma.bak
2025-05-19T18:18:59.047Z,1747678739.047 [DataOverHttps](INFO): SBD MOMSN=24921905
2025-05-19T18:19:15.073Z,1747678755.073 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250519T154143/Express0092.lzma
2025-05-19T18:19:15.484Z,1747678755.484 [NAL9602](INFO): SBD MO Status=2, MOMSN=24361, MT Status=2, MTMSN=0
2025-05-19T18:19:15.484Z,1747678755.484 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:19:16.076Z,1747678756.076 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0092.lzma.bak
2025-05-19T18:19:16.077Z,1747678756.077 [DataOverHttps](INFO): SBD MOMSN=24921908
2025-05-19T18:19:17.132Z,1747678757.132 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:19:17.132Z,1747678757.132 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:19:17.132Z,1747678757.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:19:33.667Z,1747678773.667 [NAL9602](INFO): SBD MO Status=2, MOMSN=24361, MT Status=2, MTMSN=0
2025-05-19T18:19:33.668Z,1747678773.668 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:20:00.330Z,1747678800.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=24361, MT Status=0, MTMSN=0
2025-05-19T18:20:00.330Z,1747678800.330 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:20:31.032Z,1747678831.032 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:24:17.712Z,1747679057.712 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:24:17.712Z,1747679057.712 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:24:17.712Z,1747679057.712 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:24:17.713Z,1747679057.713 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:24:18.112Z,1747679058.112 [Default:CheckIn:D] Stopped
2025-05-19T18:24:18.112Z,1747679058.112 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:24:18.525Z,1747679058.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  157.808415 min 
2025-05-19T18:24:18.525Z,1747679058.525 [Default:CheckIn:E] Stopped
2025-05-19T18:24:18.525Z,1747679058.525 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:24:18.525Z,1747679058.525 [Default:CheckIn] Stopped
2025-05-19T18:24:18.525Z,1747679058.525 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:24:18.526Z,1747679058.526 [Default:CheckIn](INFO): Running loop #28
2025-05-19T18:24:18.526Z,1747679058.526 [Default:CheckIn] Running Loop=28
2025-05-19T18:24:18.526Z,1747679058.526 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:24:18.526Z,1747679058.526 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:24:20.525Z,1747679060.525 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182419.00,A,3648.25486,N,12148.15937,W,4.432,267.50,190525,,,A*75
2025-05-19T18:24:20.527Z,1747679060.527 [NAL9602](INFO): GPS fix at 20250519T182419: (36.804248, -121.802656)
2025-05-19T18:24:20.539Z,1747679060.539 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:24:20.539Z,1747679060.539 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:24:28.505Z,1747679068.505 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0094.lzma
2025-05-19T18:24:29.508Z,1747679069.508 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0094.lzma.bak
2025-05-19T18:24:29.508Z,1747679069.508 [DataOverHttps](INFO): SBD MOMSN=24921970
2025-05-19T18:24:45.505Z,1747679085.505 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20250519T154143/Express0095.lzma
2025-05-19T18:24:46.508Z,1747679086.508 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0095.lzma.bak
2025-05-19T18:24:46.508Z,1747679086.508 [DataOverHttps](INFO): SBD MOMSN=24921973
2025-05-19T18:24:47.666Z,1747679087.666 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:24:47.666Z,1747679087.666 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:24:47.666Z,1747679087.666 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:24:51.672Z,1747679091.672 [NAL9602](INFO): SBD MO Status=2, MOMSN=24362, MT Status=2, MTMSN=0
2025-05-19T18:24:51.672Z,1747679091.672 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:25:17.554Z,1747679117.554 [NAL9602](INFO): SBD MO Status=2, MOMSN=24362, MT Status=2, MTMSN=0
2025-05-19T18:25:17.555Z,1747679117.555 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:25:35.332Z,1747679135.332 [NAL9602](INFO): SBD MO Status=2, MOMSN=24362, MT Status=2, MTMSN=0
2025-05-19T18:25:35.332Z,1747679135.332 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:25:48.261Z,1747679148.261 [NAL9602](INFO): SBD MO Status=0, MOMSN=24362, MT Status=0, MTMSN=0
2025-05-19T18:25:48.261Z,1747679148.261 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:26:18.974Z,1747679178.974 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:29:48.276Z,1747679388.276 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:29:48.276Z,1747679388.276 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:29:48.276Z,1747679388.276 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:29:48.276Z,1747679388.276 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:29:48.676Z,1747679388.676 [Default:CheckIn:D] Stopped
2025-05-19T18:29:48.677Z,1747679388.677 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:29:49.079Z,1747679389.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  163.317822 min 
2025-05-19T18:29:49.079Z,1747679389.079 [Default:CheckIn:E] Stopped
2025-05-19T18:29:49.079Z,1747679389.079 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:29:49.079Z,1747679389.079 [Default:CheckIn] Stopped
2025-05-19T18:29:49.079Z,1747679389.079 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:29:49.079Z,1747679389.079 [Default:CheckIn](INFO): Running loop #29
2025-05-19T18:29:49.080Z,1747679389.080 [Default:CheckIn] Running Loop=29
2025-05-19T18:29:49.080Z,1747679389.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:29:49.080Z,1747679389.080 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:29:51.091Z,1747679391.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182950.00,A,3648.19611,N,12148.62740,W,3.654,246.85,190525,,,D*73
2025-05-19T18:29:51.093Z,1747679391.093 [NAL9602](INFO): GPS fix at 20250519T182950: (36.803269, -121.810457)
2025-05-19T18:29:51.104Z,1747679391.104 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:29:51.104Z,1747679391.104 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:29:53.321Z,1747679393.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-19T18:30:06.045Z,1747679406.045 [NAL9602](INFO): SBD MO Status=0, MOMSN=24363, MT Status=0, MTMSN=0
2025-05-19T18:30:06.046Z,1747679406.046 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:30:24.451Z,1747679424.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-19T18:30:36.738Z,1747679436.738 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:30:55.573Z,1747679455.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-19T18:31:03.100Z,1747679463.100 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0097.lzma
2025-05-19T18:31:04.103Z,1747679464.103 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0097.lzma.bak
2025-05-19T18:31:04.103Z,1747679464.103 [DataOverHttps](INFO): SBD MOMSN=24921994
2025-05-19T18:31:19.961Z,1747679479.961 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250519T154143/Express0098.lzma
2025-05-19T18:31:20.963Z,1747679480.963 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0098.lzma.bak
2025-05-19T18:31:20.963Z,1747679480.963 [DataOverHttps](INFO): SBD MOMSN=24921997
2025-05-19T18:31:22.016Z,1747679482.016 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:31:22.016Z,1747679482.016 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:31:22.016Z,1747679482.016 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:36:22.633Z,1747679782.633 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:36:22.633Z,1747679782.633 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:36:22.633Z,1747679782.633 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:36:22.634Z,1747679782.634 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:36:23.040Z,1747679783.040 [Default:CheckIn:D] Stopped
2025-05-19T18:36:23.040Z,1747679783.040 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:36:23.449Z,1747679783.449 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  169.890544 min 
2025-05-19T18:36:23.449Z,1747679783.449 [Default:CheckIn:E] Stopped
2025-05-19T18:36:23.449Z,1747679783.449 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:36:23.449Z,1747679783.449 [Default:CheckIn] Stopped
2025-05-19T18:36:23.450Z,1747679783.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:36:23.450Z,1747679783.450 [Default:CheckIn](INFO): Running loop #30
2025-05-19T18:36:23.450Z,1747679783.450 [Default:CheckIn] Running Loop=30
2025-05-19T18:36:23.450Z,1747679783.450 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:36:23.450Z,1747679783.450 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:36:25.448Z,1747679785.448 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183624.00,A,3648.16066,N,12149.05299,W,4.102,224.46,190525,,,A*7B
2025-05-19T18:36:25.451Z,1747679785.451 [NAL9602](INFO): GPS fix at 20250519T183624: (36.802678, -121.817550)
2025-05-19T18:36:25.479Z,1747679785.479 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:36:25.479Z,1747679785.479 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:36:32.820Z,1747679792.820 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0100.lzma
2025-05-19T18:36:33.823Z,1747679793.823 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0100.lzma.bak
2025-05-19T18:36:33.823Z,1747679793.823 [DataOverHttps](INFO): SBD MOMSN=24922057
2025-05-19T18:36:42.836Z,1747679802.836 [NAL9602](INFO): SBD MO Status=0, MOMSN=24364, MT Status=0, MTMSN=0
2025-05-19T18:36:42.836Z,1747679802.836 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:36:51.317Z,1747679811.317 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250519T154143/Express0101.lzma
2025-05-19T18:36:52.315Z,1747679812.315 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0101.lzma.bak
2025-05-19T18:36:52.315Z,1747679812.315 [DataOverHttps](INFO): SBD MOMSN=24922061
2025-05-19T18:36:53.406Z,1747679813.406 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:36:53.407Z,1747679813.407 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:36:53.407Z,1747679813.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:37:13.567Z,1747679833.567 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:41:53.956Z,1747680113.956 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:41:53.956Z,1747680113.956 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:41:53.956Z,1747680113.956 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:41:53.956Z,1747680113.956 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:41:54.367Z,1747680114.367 [Default:CheckIn:D] Stopped
2025-05-19T18:41:54.367Z,1747680114.367 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:41:54.780Z,1747680114.780 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  175.412646 min 
2025-05-19T18:41:54.780Z,1747680114.780 [Default:CheckIn:E] Stopped
2025-05-19T18:41:54.780Z,1747680114.780 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:41:54.780Z,1747680114.780 [Default:CheckIn] Stopped
2025-05-19T18:41:54.780Z,1747680114.780 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:41:54.781Z,1747680114.781 [Default:CheckIn](INFO): Running loop #31
2025-05-19T18:41:54.781Z,1747680114.781 [Default:CheckIn] Running Loop=31
2025-05-19T18:41:54.781Z,1747680114.781 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:41:54.781Z,1747680114.781 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:41:56.776Z,1747680116.776 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184156.00,A,3648.32161,N,12149.45808,W,4.043,302.30,190525,,,D*7D
2025-05-19T18:41:56.778Z,1747680116.778 [NAL9602](INFO): GPS fix at 20250519T184156: (36.805360, -121.824301)
2025-05-19T18:41:56.790Z,1747680116.790 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:41:56.790Z,1747680116.790 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:42:05.801Z,1747680125.801 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0103.lzma
2025-05-19T18:42:06.803Z,1747680126.803 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0103.lzma.bak
2025-05-19T18:42:06.803Z,1747680126.803 [DataOverHttps](INFO): SBD MOMSN=24922084
2025-05-19T18:42:18.592Z,1747680138.592 [NAL9602](INFO): SBD MO Status=2, MOMSN=24365, MT Status=2, MTMSN=0
2025-05-19T18:42:18.592Z,1747680138.592 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:42:25.869Z,1747680145.869 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250519T154143/Express0104.lzma
2025-05-19T18:42:26.871Z,1747680146.871 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0104.lzma.bak
2025-05-19T18:42:26.871Z,1747680146.871 [DataOverHttps](INFO): SBD MOMSN=24922090
2025-05-19T18:42:27.916Z,1747680147.916 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:42:27.916Z,1747680147.916 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:42:27.916Z,1747680147.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:42:36.391Z,1747680156.391 [NAL9602](INFO): SBD MO Status=0, MOMSN=24365, MT Status=0, MTMSN=0
2025-05-19T18:42:36.391Z,1747680156.391 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:43:07.098Z,1747680187.098 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:47:28.514Z,1747680448.514 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:47:28.514Z,1747680448.514 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:47:28.514Z,1747680448.514 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:47:28.514Z,1747680448.514 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:47:28.919Z,1747680448.919 [Default:CheckIn:D] Stopped
2025-05-19T18:47:28.919Z,1747680448.919 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:47:29.323Z,1747680449.323 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  180.988542 min 
2025-05-19T18:47:29.324Z,1747680449.324 [Default:CheckIn:E] Stopped
2025-05-19T18:47:29.324Z,1747680449.324 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:47:29.324Z,1747680449.324 [Default:CheckIn] Stopped
2025-05-19T18:47:29.324Z,1747680449.324 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:47:29.324Z,1747680449.324 [Default:CheckIn](INFO): Running loop #32
2025-05-19T18:47:29.324Z,1747680449.324 [Default:CheckIn] Running Loop=32
2025-05-19T18:47:29.324Z,1747680449.324 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:47:29.324Z,1747680449.324 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:47:31.338Z,1747680451.338 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184730.00,A,3648.35212,N,12149.40325,W,0.389,90.92,190525,,,D*4B
2025-05-19T18:47:31.340Z,1747680451.340 [NAL9602](INFO): GPS fix at 20250519T184730: (36.805869, -121.823387)
2025-05-19T18:47:31.352Z,1747680451.352 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:47:31.352Z,1747680451.352 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:47:38.836Z,1747680458.836 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0106.lzma
2025-05-19T18:47:39.839Z,1747680459.839 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0106.lzma.bak
2025-05-19T18:47:39.839Z,1747680459.839 [DataOverHttps](INFO): SBD MOMSN=24922157
2025-05-19T18:47:55.737Z,1747680475.737 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250519T154143/Express0107.lzma
2025-05-19T18:47:56.739Z,1747680476.739 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0107.lzma.bak
2025-05-19T18:47:56.739Z,1747680476.739 [DataOverHttps](INFO): SBD MOMSN=24922163
2025-05-19T18:47:58.038Z,1747680478.038 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:47:58.038Z,1747680478.038 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:47:58.038Z,1747680478.038 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:48:06.500Z,1747680486.500 [NAL9602](INFO): SBD MO Status=0, MOMSN=24366, MT Status=0, MTMSN=0
2025-05-19T18:48:06.500Z,1747680486.500 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:48:37.211Z,1747680517.211 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:52:58.601Z,1747680778.601 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:52:58.601Z,1747680778.601 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:52:58.601Z,1747680778.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:52:58.601Z,1747680778.601 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:52:59.045Z,1747680779.045 [Default:CheckIn:D] Stopped
2025-05-19T18:52:59.045Z,1747680779.045 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:52:59.413Z,1747680779.413 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  186.490625 min 
2025-05-19T18:52:59.414Z,1747680779.414 [Default:CheckIn:E] Stopped
2025-05-19T18:52:59.414Z,1747680779.414 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:52:59.414Z,1747680779.414 [Default:CheckIn] Stopped
2025-05-19T18:52:59.414Z,1747680779.414 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:52:59.414Z,1747680779.414 [Default:CheckIn](INFO): Running loop #33
2025-05-19T18:52:59.414Z,1747680779.414 [Default:CheckIn] Running Loop=33
2025-05-19T18:52:59.414Z,1747680779.414 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:52:59.414Z,1747680779.414 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:53:01.421Z,1747680781.421 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185300.00,A,3648.34935,N,12149.34063,W,0.428,87.86,190525,,,D*4F
2025-05-19T18:53:01.423Z,1747680781.423 [NAL9602](INFO): GPS fix at 20250519T185300: (36.805822, -121.822344)
2025-05-19T18:53:01.449Z,1747680781.449 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:53:01.450Z,1747680781.450 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:53:08.796Z,1747680788.796 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250519T154143/Courier0109.lzma
2025-05-19T18:53:09.799Z,1747680789.799 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0109.lzma.bak
2025-05-19T18:53:09.799Z,1747680789.799 [DataOverHttps](INFO): SBD MOMSN=24922177
2025-05-19T18:53:23.640Z,1747680803.640 [NAL9602](INFO): SBD MO Status=2, MOMSN=24367, MT Status=2, MTMSN=0
2025-05-19T18:53:23.641Z,1747680803.641 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-19T18:53:33.729Z,1747680813.729 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250519T154143/Express0110.lzma
2025-05-19T18:53:34.733Z,1747680814.733 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0110.lzma.bak
2025-05-19T18:53:34.733Z,1747680814.733 [DataOverHttps](INFO): SBD MOMSN=24922183
2025-05-19T18:53:35.785Z,1747680815.785 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:53:35.785Z,1747680815.785 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:53:35.785Z,1747680815.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:54:22.232Z,1747680862.232 [NAL9602](INFO): SBD MO Status=0, MOMSN=24367, MT Status=0, MTMSN=0
2025-05-19T18:54:22.232Z,1747680862.232 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:54:52.952Z,1747680892.952 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T18:58:36.370Z,1747681116.370 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T18:58:36.370Z,1747681116.370 [Default:CheckIn:C.Wait] Stopped
2025-05-19T18:58:36.371Z,1747681116.371 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T18:58:36.371Z,1747681116.371 [Default:CheckIn:D] Running Loop=1
2025-05-19T18:58:36.771Z,1747681116.771 [Default:CheckIn:D] Stopped
2025-05-19T18:58:36.771Z,1747681116.771 [Default:CheckIn:E] Running Loop=1
2025-05-19T18:58:37.183Z,1747681117.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  192.119401 min 
2025-05-19T18:58:37.183Z,1747681117.183 [Default:CheckIn:E] Stopped
2025-05-19T18:58:37.183Z,1747681117.183 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T18:58:37.183Z,1747681117.183 [Default:CheckIn] Stopped
2025-05-19T18:58:37.184Z,1747681117.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T18:58:37.184Z,1747681117.184 [Default:CheckIn](INFO): Running loop #34
2025-05-19T18:58:37.184Z,1747681117.184 [Default:CheckIn] Running Loop=34
2025-05-19T18:58:37.184Z,1747681117.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T18:58:37.184Z,1747681117.184 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T18:58:39.184Z,1747681119.184 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185838.00,A,3648.01015,N,12150.08688,W,14.870,238.27,190525,,,D*4F
2025-05-19T18:58:39.187Z,1747681119.187 [NAL9602](INFO): GPS fix at 20250519T185838: (36.800169, -121.834781)
2025-05-19T18:58:39.216Z,1747681119.216 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T18:58:39.216Z,1747681119.216 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T18:58:48.933Z,1747681128.933 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250519T154143/Courier0112.lzma
2025-05-19T18:58:49.935Z,1747681129.935 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0112.lzma.bak
2025-05-19T18:58:49.936Z,1747681129.936 [DataOverHttps](INFO): SBD MOMSN=24922242
2025-05-19T18:59:02.652Z,1747681142.652 [NAL9602](INFO): SBD MO Status=0, MOMSN=24368, MT Status=0, MTMSN=0
2025-05-19T18:59:02.652Z,1747681142.652 [NAL9602](INFO): No messages in MT queue
2025-05-19T18:59:07.109Z,1747681147.109 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250519T154143/Express0113.lzma
2025-05-19T18:59:08.111Z,1747681148.111 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0113.lzma.bak
2025-05-19T18:59:08.111Z,1747681148.111 [DataOverHttps](INFO): SBD MOMSN=24922248
2025-05-19T18:59:09.153Z,1747681149.153 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T18:59:09.153Z,1747681149.153 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T18:59:09.153Z,1747681149.153 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T18:59:33.347Z,1747681173.347 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-19T19:04:09.761Z,1747681449.761 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-19T19:04:09.761Z,1747681449.761 [Default:CheckIn:C.Wait] Stopped
2025-05-19T19:04:09.761Z,1747681449.761 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-19T19:04:09.761Z,1747681449.761 [Default:CheckIn:D] Running Loop=1
2025-05-19T19:04:10.159Z,1747681450.159 [Default:CheckIn:D] Stopped
2025-05-19T19:04:10.159Z,1747681450.159 [Default:CheckIn:E] Running Loop=1
2025-05-19T19:04:10.572Z,1747681450.572 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  197.675863 min 
2025-05-19T19:04:10.572Z,1747681450.572 [Default:CheckIn:E] Stopped
2025-05-19T19:04:10.572Z,1747681450.572 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-19T19:04:10.572Z,1747681450.572 [Default:CheckIn] Stopped
2025-05-19T19:04:10.572Z,1747681450.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-19T19:04:10.572Z,1747681450.572 [Default:CheckIn](INFO): Running loop #35
2025-05-19T19:04:10.572Z,1747681450.572 [Default:CheckIn] Running Loop=35
2025-05-19T19:04:10.573Z,1747681450.573 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-19T19:04:10.573Z,1747681450.573 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-19T19:04:12.576Z,1747681452.576 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190412.00,A,3647.78738,N,12150.67595,W,1.050,126.66,190525,,,D*77
2025-05-19T19:04:12.578Z,1747681452.578 [NAL9602](INFO): GPS fix at 20250519T190412: (36.796456, -121.844599)
2025-05-19T19:04:12.610Z,1747681452.610 [Default:CheckIn:Read_GPS] Stopped
2025-05-19T19:04:12.610Z,1747681452.610 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-19T19:04:20.421Z,1747681460.421 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250519T154143/Courier0115.lzma
2025-05-19T19:04:21.423Z,1747681461.423 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Courier0115.lzma.bak
2025-05-19T19:04:21.423Z,1747681461.423 [DataOverHttps](INFO): SBD MOMSN=24922262
2025-05-19T19:04:27.120Z,1747681467.120 [NAL9602](INFO): SBD MO Status=0, MOMSN=24369, MT Status=0, MTMSN=0
2025-05-19T19:04:27.120Z,1747681467.120 [NAL9602](INFO): No messages in MT queue
2025-05-19T19:04:37.325Z,1747681477.325 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250519T154143/Express0116.lzma
2025-05-19T19:04:38.327Z,1747681478.327 [DataOverHttps](INFO): Moved sent file to Logs/20250519T154143/Express0116.lzma.bak
2025-05-19T19:04:38.328Z,1747681478.328 [DataOverHttps](IMPORTANT): SBD MOMSN=24922273, MTMSN=20250519T190437
2025-05-19T19:04:39.682Z,1747681479.682 [Default:CheckIn:Read_Iridium] Stopped
2025-05-19T19:04:39.682Z,1747681479.682 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-19T19:04:39.683Z,1747681479.683 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-19T19:04:45.765Z,1747681485.765 [DataOverHttps](INFO): Received command: restart logs