2023-12-11T17:05:12.697Z,1702314312.697 [Supervisor](DEBUG): Initializing supervisor.
2023-12-11T17:05:12.701Z,1702314312.701 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-12-11T17:05:12.702Z,1702314312.702 [SyncHandler](INFO): Protected caller Thread ID is 2334
2023-12-11T17:05:12.702Z,1702314312.702 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-12-11T17:05:12.703Z,1702314312.703 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-12-11T17:05:12.703Z,1702314312.703 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2335
2023-12-11T17:05:12.707Z,1702314312.707 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-12-11T17:05:12.725Z,1702314312.725 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-12-11T17:05:12.726Z,1702314312.726 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-12-11T17:05:12.726Z,1702314312.726 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2336
2023-12-11T17:05:12.730Z,1702314312.730 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-12-11T17:05:12.731Z,1702314312.731 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-12-11T17:05:12.731Z,1702314312.731 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2337
2023-12-11T17:05:12.733Z,1702314312.733 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-12-11T17:05:12.734Z,1702314312.734 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-12-11T17:05:12.735Z,1702314312.735 [logger ThreadHandler](INFO): Protected caller Thread ID is 2338
2023-12-11T17:05:12.738Z,1702314312.738 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-12-11T17:05:12.739Z,1702314312.739 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-12-11T17:05:12.740Z,1702314312.740 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-12-11T17:05:12.838Z,1702314312.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-12-11T17:05:12.838Z,1702314312.838 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-12-11T17:05:13.558Z,1702314313.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-12-11T17:05:13.559Z,1702314313.559 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-12-11T17:05:13.864Z,1702314313.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-12-11T17:05:13.865Z,1702314313.865 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-12-11T17:05:13.945Z,1702314313.945 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-12-11T17:05:14.078Z,1702314314.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-12-11T17:05:14.079Z,1702314314.079 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-12-11T17:05:14.161Z,1702314314.161 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-12-11T17:05:14.269Z,1702314314.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-12-11T17:05:14.270Z,1702314314.270 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-12-11T17:05:14.656Z,1702314314.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-12-11T17:05:14.656Z,1702314314.656 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-12-11T17:05:15.147Z,1702314315.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-12-11T17:05:15.147Z,1702314315.147 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-12-11T17:05:15.368Z,1702314315.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-12-11T17:05:15.369Z,1702314315.369 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-12-11T17:05:15.560Z,1702314315.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-12-11T17:05:15.560Z,1702314315.560 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-12-11T17:05:16.082Z,1702314316.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-12-11T17:05:16.083Z,1702314316.083 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-12-11T17:05:16.375Z,1702314316.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-12-11T17:05:16.376Z,1702314316.376 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-12-11T17:05:16.602Z,1702314316.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-12-11T17:05:16.603Z,1702314316.603 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-12-11T17:05:16.981Z,1702314316.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-12-11T17:05:16.982Z,1702314316.982 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-12-11T17:05:17.326Z,1702314317.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-12-11T17:05:17.328Z,1702314317.328 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-12-11T17:05:17.329Z,1702314317.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-12-11T17:05:17.416Z,1702314317.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-12-11T17:05:17.585Z,1702314317.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-12-11T17:05:17.722Z,1702314317.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-12-11T17:05:17.958Z,1702314317.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-12-11T17:05:18.228Z,1702314318.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-12-11T17:05:18.477Z,1702314318.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-12-11T17:05:18.862Z,1702314318.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-12-11T17:05:18.863Z,1702314318.863 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-12-11T17:05:18.959Z,1702314318.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-12-11T17:05:19.060Z,1702314319.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-12-11T17:05:19.204Z,1702314319.204 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-12-11T17:05:19.307Z,1702314319.307 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-12-11T17:05:19.307Z,1702314319.307 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-12-11T17:05:19.314Z,1702314319.314 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-12-11T17:05:19.422Z,1702314319.422 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-12-11T17:05:19.423Z,1702314319.423 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-12-11T17:05:19.473Z,1702314319.473 [VerticalControl](DEBUG): Construct VerticalControl.
2023-12-11T17:05:19.531Z,1702314319.531 [VerticalControl] Loaded
2023-12-11T17:05:19.532Z,1702314319.532 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-12-11T17:05:19.534Z,1702314319.534 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-12-11T17:05:19.574Z,1702314319.574 [HorizontalControl] Loaded
2023-12-11T17:05:19.574Z,1702314319.574 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-12-11T17:05:19.577Z,1702314319.577 [SpeedControl](DEBUG): Construct SpeedControl.
2023-12-11T17:05:19.580Z,1702314319.580 [SpeedControl] Loaded
2023-12-11T17:05:19.580Z,1702314319.580 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-12-11T17:05:19.582Z,1702314319.582 [LoopControl](DEBUG): Construct LoopControl.
2023-12-11T17:05:19.583Z,1702314319.583 [LoopControl] Loaded
2023-12-11T17:05:19.583Z,1702314319.583 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-12-11T17:05:19.584Z,1702314319.584 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-12-11T17:05:19.584Z,1702314319.584 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-12-11T17:05:19.632Z,1702314319.632 [DepthRateCalculator] Loaded
2023-12-11T17:05:19.633Z,1702314319.633 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-12-11T17:05:19.637Z,1702314319.637 [PitchRateCalculator] Loaded
2023-12-11T17:05:19.638Z,1702314319.638 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-12-11T17:05:19.647Z,1702314319.647 [SpeedCalculator] Loaded
2023-12-11T17:05:19.648Z,1702314319.648 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-12-11T17:05:19.652Z,1702314319.652 [YawRateCalculator] Loaded
2023-12-11T17:05:19.652Z,1702314319.652 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-12-11T17:05:19.670Z,1702314319.670 [ElevatorOffsetCalculator] Loaded
2023-12-11T17:05:19.670Z,1702314319.670 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-12-11T17:05:19.671Z,1702314319.671 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-12-11T17:05:19.671Z,1702314319.671 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-12-11T17:05:19.697Z,1702314319.697 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-12-11T17:05:19.697Z,1702314319.697 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-12-11T17:05:19.753Z,1702314319.753 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-12-11T17:05:19.753Z,1702314319.753 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-12-11T17:05:20.082Z,1702314320.082 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-12-11T17:05:20.082Z,1702314320.082 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-12-11T17:05:20.223Z,1702314320.223 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-12-11T17:05:20.224Z,1702314320.224 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-12-11T17:05:20.655Z,1702314320.655 [AHRS_M2] Loaded
2023-12-11T17:05:20.656Z,1702314320.656 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-12-11T17:05:20.691Z,1702314320.691 [BackseatComponent] Loaded
2023-12-11T17:05:20.691Z,1702314320.691 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-12-11T17:05:20.692Z,1702314320.692 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0
2023-12-11T17:05:20.693Z,1702314320.693 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2421
2023-12-11T17:05:20.695Z,1702314320.695 [LcmUniversalReporter] Loaded
2023-12-11T17:05:20.696Z,1702314320.696 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-12-11T17:05:21.716Z,1702314321.716 [BPC1] Loaded
2023-12-11T17:05:21.716Z,1702314321.716 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-12-11T17:05:21.789Z,1702314321.789 [DataOverHttps] Loaded
2023-12-11T17:05:21.789Z,1702314321.789 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-12-11T17:05:21.790Z,1702314321.790 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409D44E0
2023-12-11T17:05:21.791Z,1702314321.791 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2422
2023-12-11T17:05:21.811Z,1702314321.811 [Depth_Keller] Loaded
2023-12-11T17:05:21.811Z,1702314321.811 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-12-11T17:05:21.816Z,1702314321.816 [DropWeight] Loaded
2023-12-11T17:05:21.816Z,1702314321.816 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-12-11T17:05:21.878Z,1702314321.878 [NAL9602] Loaded
2023-12-11T17:05:21.878Z,1702314321.878 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-12-11T17:05:21.907Z,1702314321.907 [Onboard] Loaded
2023-12-11T17:05:21.908Z,1702314321.908 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-12-11T17:05:21.909Z,1702314321.909 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A044E0
2023-12-11T17:05:21.909Z,1702314321.909 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2423
2023-12-11T17:05:21.915Z,1702314321.915 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2023-12-11T17:05:21.926Z,1702314321.926 [PowerOnly] Loaded
2023-12-11T17:05:21.926Z,1702314321.926 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-12-11T17:05:21.938Z,1702314321.938 [Power24vConverter] Loaded
2023-12-11T17:05:21.938Z,1702314321.938 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-12-11T17:05:21.951Z,1702314321.951 [Radio_Surface] Loaded
2023-12-11T17:05:21.951Z,1702314321.951 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-12-11T17:05:21.952Z,1702314321.952 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A344E0
2023-12-11T17:05:21.953Z,1702314321.953 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2424
2023-12-11T17:05:21.997Z,1702314321.997 [RDI_Pathfinder] Loaded
2023-12-11T17:05:21.997Z,1702314321.997 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-12-11T17:05:22.088Z,1702314322.088 [DAT] Loaded
2023-12-11T17:05:22.089Z,1702314322.089 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-12-11T17:05:22.090Z,1702314322.090 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A644E0
2023-12-11T17:05:22.090Z,1702314322.090 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2425
2023-12-11T17:05:22.090Z,1702314322.090 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-12-11T17:05:22.091Z,1702314322.091 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-12-11T17:05:22.201Z,1702314322.201 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-12-11T17:05:22.201Z,1702314322.201 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-12-11T17:05:22.215Z,1702314322.215 [NavChart] Loaded
2023-12-11T17:05:22.215Z,1702314322.215 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-12-11T17:05:22.221Z,1702314322.221 [UniversalFixResidualReporter] Loaded
2023-12-11T17:05:22.221Z,1702314322.221 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-12-11T17:05:22.221Z,1702314322.221 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-12-11T17:05:22.222Z,1702314322.222 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-12-11T17:05:22.359Z,1702314322.359 [SBIT](DEBUG): Construct Startup Built In Test.
2023-12-11T17:05:22.367Z,1702314322.367 [SBIT] Loaded
2023-12-11T17:05:22.368Z,1702314322.368 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-12-11T17:05:22.371Z,1702314322.371 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-12-11T17:05:22.383Z,1702314322.383 [IBIT] Loaded
2023-12-11T17:05:22.384Z,1702314322.384 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-12-11T17:05:22.390Z,1702314322.390 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-12-11T17:05:22.493Z,1702314322.493 [CBIT] Loaded
2023-12-11T17:05:22.493Z,1702314322.493 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-12-11T17:05:22.494Z,1702314322.494 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-12-11T17:05:22.494Z,1702314322.494 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-12-11T17:05:22.622Z,1702314322.622 [BuoyancyServo] Loaded
2023-12-11T17:05:22.622Z,1702314322.622 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-12-11T17:05:22.642Z,1702314322.642 [ElevatorServo] Loaded
2023-12-11T17:05:22.642Z,1702314322.642 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-12-11T17:05:22.662Z,1702314322.662 [MassServo] Loaded
2023-12-11T17:05:22.662Z,1702314322.662 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-12-11T17:05:22.681Z,1702314322.681 [RudderServo] Loaded
2023-12-11T17:05:22.681Z,1702314322.681 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-12-11T17:05:22.698Z,1702314322.698 [ThrusterServo] Loaded
2023-12-11T17:05:22.698Z,1702314322.698 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-12-11T17:05:22.699Z,1702314322.699 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-12-11T17:05:22.699Z,1702314322.699 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-12-11T17:05:22.709Z,1702314322.709 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-12-11T17:05:22.710Z,1702314322.710 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-12-11T17:05:22.879Z,1702314322.879 [CTD_Seabird] Loaded
2023-12-11T17:05:22.879Z,1702314322.879 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-12-11T17:05:22.880Z,1702314322.880 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BAF4E0
2023-12-11T17:05:22.881Z,1702314322.881 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2426
2023-12-11T17:05:22.901Z,1702314322.901 [PAR_Licor] Loaded
2023-12-11T17:05:22.901Z,1702314322.901 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-12-11T17:05:22.941Z,1702314322.941 [WetLabsBB2FL] Loaded
2023-12-11T17:05:22.941Z,1702314322.941 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-12-11T17:05:22.942Z,1702314322.942 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BDF4E0
2023-12-11T17:05:22.942Z,1702314322.942 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2427
2023-12-11T17:05:22.962Z,1702314322.962 [WetLabsUBAT] Loaded
2023-12-11T17:05:22.962Z,1702314322.962 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-12-11T17:05:22.963Z,1702314322.963 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C0F4E0
2023-12-11T17:05:22.963Z,1702314322.963 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 2428
2023-12-11T17:05:22.964Z,1702314322.964 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-12-11T17:05:22.978Z,1702314322.978 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-12-11T17:05:22.989Z,1702314322.989 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-12-11T17:05:23.019Z,1702314323.019 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-12-11T17:05:23.043Z,1702314323.043 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C3F4E0
2023-12-11T17:05:23.043Z,1702314323.043 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2429
2023-12-11T17:05:23.048Z,1702314323.048 [Supervisor](INFO): Main Thread ID is 2333
2023-12-11T17:05:23.048Z,1702314323.048 [Supervisor](DEBUG): Running supervisor.
2023-12-11T17:05:23.049Z,1702314323.049 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2430
2023-12-11T17:05:23.049Z,1702314323.049 [CommandExec](INFO): Initializing the command executive.
2023-12-11T17:05:23.051Z,1702314323.051 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2431
2023-12-11T17:05:23.053Z,1702314323.053 [controlThread ThreadHandler](INFO): Handler Thread ID is 2432
2023-12-11T17:05:23.053Z,1702314323.053 [controlThread](DEBUG): Initializing ControlThread
2023-12-11T17:05:23.054Z,1702314323.054 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-12-11T17:05:23.056Z,1702314323.056 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-12-11T17:05:23.057Z,1702314323.057 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-12-11T17:05:23.057Z,1702314323.057 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-12-11T17:05:23.058Z,1702314323.058 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-12-11T17:05:23.058Z,1702314323.058 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-12-11T17:05:23.059Z,1702314323.059 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-12-11T17:05:23.059Z,1702314323.059 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-12-11T17:05:23.059Z,1702314323.059 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-12-11T17:05:23.067Z,1702314323.067 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-12-11T17:05:23.068Z,1702314323.068 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-12-11T17:05:23.068Z,1702314323.068 [SBIT](INFO): Initialize SBIT Component.
2023-12-11T17:05:23.069Z,1702314323.069 [SBIT](IMPORTANT): git: 2023-12-07
2023-12-11T17:05:23.069Z,1702314323.069 [SBIT](INFO): git hash: dbc7300d59e166afd06c26988583bb6e3d4bec93
2023-12-11T17:05:23.069Z,1702314323.069 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-12-11T17:05:23.070Z,1702314323.070 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-12-11T17:05:23.071Z,1702314323.071 [SBIT](INFO): Beginning SBIT in 48.000000 seconds.
2023-12-11T17:05:23.077Z,1702314323.077 [logger ThreadHandler](INFO): Handler Thread ID is 2433
2023-12-11T17:05:23.101Z,1702314323.101 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2434
2023-12-11T17:05:23.102Z,1702314323.102 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2435
2023-12-11T17:05:23.105Z,1702314323.105 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-12-11T17:05:23.107Z,1702314323.107 [Onboard ThreadHandler](INFO): Handler Thread ID is 2436
2023-12-11T17:05:23.134Z,1702314323.134 [IBIT](INFO): Initialize IBIT Component.
2023-12-11T17:05:23.138Z,1702314323.138 [CBIT](DEBUG): Initialize CBIT Component.
2023-12-11T17:05:23.140Z,1702314323.140 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2437
2023-12-11T17:05:23.153Z,1702314323.153 [CBIT](DEBUG): Initialized mux pins.
2023-12-11T17:05:23.153Z,1702314323.153 [CBIT](DEBUG): Initializing the watchdog timer.
2023-12-11T17:05:23.168Z,1702314323.168 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2439
2023-12-11T17:05:23.169Z,1702314323.169 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-12-11T17:05:23.171Z,1702314323.171 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2441
2023-12-11T17:05:23.174Z,1702314323.174 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 2442
2023-12-11T17:05:23.176Z,1702314323.176 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-12-11T17:05:23.177Z,1702314323.177 [CBIT](DEBUG): Initializing heartbeat.
2023-12-11T17:05:23.180Z,1702314323.180 [DAT ThreadHandler](INFO): Handler Thread ID is 2438
2023-12-11T17:05:23.181Z,1702314323.181 [DAT](INFO): Powering up
2023-12-11T17:05:23.181Z,1702314323.181 [DAT](DEBUG): Initializing DAT.
2023-12-11T17:05:23.176Z,1702314323.176 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2443
2023-12-11T17:05:23.215Z,1702314323.215 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-12-11T17:05:23.215Z,1702314323.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-12-11T17:05:23.216Z,1702314323.216 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-12-11T17:05:23.216Z,1702314323.216 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-12-11T17:05:23.216Z,1702314323.216 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-12-11T17:05:23.216Z,1702314323.216 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-12-11T17:05:23.216Z,1702314323.216 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-12-11T17:05:23.216Z,1702314323.216 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-12-11T17:05:23.248Z,1702314323.248 [CBIT](DEBUG): Deactivating GF circuits.
2023-12-11T17:05:23.248Z,1702314323.248 [CBIT](DEBUG): Deactivating emergency mode.
2023-12-11T17:05:23.288Z,1702314323.288 [CBIT](DEBUG): Backplane powered.
2023-12-11T17:05:23.293Z,1702314323.293 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-12-11T17:05:23.307Z,1702314323.307 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-12-11T17:05:23.313Z,1702314323.313 [WetLabsBB2FL](INFO): Powering up
2023-12-11T17:05:23.373Z,1702314323.373 [WetLabsUBAT](INFO): Powering up
2023-12-11T17:05:23.407Z,1702314323.407 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-12-11T17:05:23.407Z,1702314323.407 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-12-11T17:05:23.499Z,1702314323.499 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-12-11T17:05:23.500Z,1702314323.500 [Default:A.Wait](DEBUG): Construct Wait.
2023-12-11T17:05:23.502Z,1702314323.502 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-12-11T17:05:23.542Z,1702314323.542 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-12-11T17:05:23.544Z,1702314323.544 [Radio_Surface](INFO): Powering up
2023-12-11T17:05:23.553Z,1702314323.553 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-12-11T17:05:23.558Z,1702314323.558 [Default:E.Execute](DEBUG): Construct Execute.
2023-12-11T17:05:23.583Z,1702314323.583 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-12-11T17:05:23.589Z,1702314323.589 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-12-11T17:05:23.629Z,1702314323.629 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-12-11T17:05:23.702Z,1702314323.702 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1650.297119 decibar, 0.000000 m
2023-12-11T17:05:23.704Z,1702314323.704 [PowerOnly](INFO): Powering up loadControl
2023-12-11T17:05:23.721Z,1702314323.721 [Power24vConverter](INFO): Powering up.
2023-12-11T17:05:23.722Z,1702314323.722 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-12-11T17:05:23.745Z,1702314323.745 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-12-11T17:05:23.774Z,1702314323.774 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-12-11T17:05:23.780Z,1702314323.780 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-12-11T17:05:23.782Z,1702314323.782 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-12-11T17:05:23.796Z,1702314323.796 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-12-11T17:05:23.797Z,1702314323.797 [MassServo](DEBUG): Initializing EZServoServo.
2023-12-11T17:05:23.804Z,1702314323.804 [MassServo](DEBUG): Initializing MassServo.
2023-12-11T17:05:23.805Z,1702314323.805 [RudderServo](DEBUG): Initializing EZServoServo.
2023-12-11T17:05:23.812Z,1702314323.812 [RudderServo](DEBUG): Initializing RudderServo.
2023-12-11T17:05:23.813Z,1702314323.813 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-12-11T17:05:23.820Z,1702314323.820 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-12-11T17:05:25.784Z,1702314325.784 [WetLabsBB2FL](INFO): Powering down
2023-12-11T17:05:32.632Z,1702314332.632 [WetLabsUBAT](INFO): Powering down
2023-12-11T17:05:35.529Z,1702314335.529 [DAT](INFO): DAT read: 

2023-12-11T17:05:35.530Z,1702314335.530 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-12-11T17:05:37.293Z,1702314337.293 [DAT](INFO): DAT read: MF Frequency Band

2023-12-11T17:05:37.294Z,1702314337.294 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-12-11T17:05:37.295Z,1702314337.295 [DAT](INFO): DAT read: Dec 11 2023 17:03:57 

2023-12-11T17:05:37.423Z,1702314337.423 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004734
2023-12-11T17:05:38.301Z,1702314338.301 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-12-11T17:05:38.303Z,1702314338.303 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-12-11T17:05:38.303Z,1702314338.303 [DAT](INFO): commRate: 800
2023-12-11T17:05:40.368Z,1702314340.368 [DAT](INFO): entering command mode
2023-12-11T17:05:40.569Z,1702314340.569 [DAT](INFO): DAT read: 

2023-12-11T17:05:40.569Z,1702314340.569 [DAT](INFO): DAT read: user:1>

2023-12-11T17:05:40.570Z,1702314340.570 [DAT](INFO): setting verbose to 3
2023-12-11T17:05:40.821Z,1702314340.821 [DAT](INFO): DAT read: user:1>

2023-12-11T17:05:40.822Z,1702314340.822 [DAT](INFO): DAT read: Verbose         | 3           

2023-12-11T17:05:40.822Z,1702314340.822 [DAT](INFO): set verbose to 3
2023-12-11T17:05:40.823Z,1702314340.823 [DAT](INFO): setting DatVerbose to 27440
2023-12-11T17:05:41.073Z,1702314341.073 [DAT](INFO): DAT read: user:2>

2023-12-11T17:05:41.074Z,1702314341.074 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-12-11T17:05:41.074Z,1702314341.074 [DAT](INFO): set DatVerbose to 27440
2023-12-11T17:05:41.074Z,1702314341.074 [DAT](INFO): setting transmit power to 8
2023-12-11T17:05:41.325Z,1702314341.325 [DAT](INFO): DAT read: user:3>

2023-12-11T17:05:41.326Z,1702314341.326 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-12-11T17:05:41.326Z,1702314341.326 [DAT](INFO): set transmit power to 8
2023-12-11T17:05:41.326Z,1702314341.326 [DAT](INFO): setting local address to 10
2023-12-11T17:05:41.577Z,1702314341.577 [DAT](INFO): DAT read: user:4>

2023-12-11T17:05:41.578Z,1702314341.578 [DAT](INFO): DAT read: LocalAddr       | 10          

2023-12-11T17:05:41.578Z,1702314341.578 [DAT](INFO): set local address to 10
2023-12-11T17:05:41.579Z,1702314341.579 [DAT](INFO): Setting time to: 17:5:41 And date to:12/11/2023
2023-12-11T17:05:41.829Z,1702314341.829 [DAT](INFO): DAT read: user:5>

2023-12-11T17:05:41.830Z,1702314341.830 [DAT](INFO): DAT read: Mon Dec 11, 2023  17:05:41  

2023-12-11T17:05:41.830Z,1702314341.830 [DAT](INFO): Local DAT time set to Mon Dec 11, 2023  17:05:41  

2023-12-11T17:05:51.882Z,1702314351.882 [NAL9602](INFO): Powering up NAL9602
2023-12-11T17:06:02.798Z,1702314362.798 [NAL9602](INFO): NAL9602 initialized
2023-12-11T17:06:11.741Z,1702314371.741 [SBIT](IMPORTANT): Beginning Startup BIT
2023-12-11T17:06:11.749Z,1702314371.749 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-11T17:06:22.677Z,1702314382.677 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.010753
CHAN A1 (24V): 0.008700
CHAN A2 (12V): -0.003428
CHAN A3 (5V): -0.001012
CHAN B0 (3.3V): 0.000799
CHAN B1 (3.15aV): -0.000135
CHAN B2 (3.15bV): 0.000811
CHAN B3 (GND): 0.001438
OPEN: 0.005718
Full Scale: +/- 1 mA
2023-12-11T17:07:05.462Z,1702314425.462 [SBIT](IMPORTANT): SBIT PASSED
2023-12-11T17:07:05.462Z,1702314425.462 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-12-11T17:07:05.463Z,1702314425.463 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-12-11T17:07:05.463Z,1702314425.463 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;

2023-12-11T17:07:05.463Z,1702314425.463 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-12-11T17:07:05.463Z,1702314425.463 [SBIT](IMPORTANT): Express linearApproximation downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;

2023-12-11T17:07:05.464Z,1702314425.464 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=258.057070 cubic_centimeter;

2023-12-11T17:07:05.464Z,1702314425.464 [SBIT](IMPORTANT): VerticalControl.massDefault=7.169203 millimeter;

2023-12-11T17:07:05.839Z,1702314425.839 [MissionManager](IMPORTANT): Started mission Startup
2023-12-11T17:07:05.839Z,1702314425.839 [Startup] Running Loop=1
2023-12-11T17:07:05.839Z,1702314425.839 [Startup](DEBUG): Aggregate::initialize Startup
2023-12-11T17:07:05.839Z,1702314425.839 [Startup:A.GoToSurface] Running Loop=1
2023-12-11T17:07:05.839Z,1702314425.839 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-12-11T17:07:05.840Z,1702314425.840 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-12-11T17:07:05.841Z,1702314425.841 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-12-11T17:07:05.841Z,1702314425.841 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-12-11T17:07:05.841Z,1702314425.841 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-12-11T17:07:05.842Z,1702314425.842 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-12-11T17:07:05.843Z,1702314425.843 [Startup:StartupSatComms] Running Loop=1
2023-12-11T17:07:05.843Z,1702314425.843 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-12-11T17:07:05.843Z,1702314425.843 [Startup:StartupSatComms:A] Running Loop=1
2023-12-11T17:07:06.242Z,1702314426.242 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-12-11T17:08:06.055Z,1702314486.055 [Startup:StartupSatComms:A](INFO): Timed out from 2023-12-11T17:07:05.8Z
2023-12-11T17:08:06.055Z,1702314486.055 [Startup:StartupSatComms:A] Stopped
2023-12-11T17:08:06.055Z,1702314486.055 [Startup:StartupSatComms:B] Running Loop=1
2023-12-11T17:08:06.463Z,1702314486.463 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-12-11T17:08:13.348Z,1702314493.348 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231211T164935/Courier0007.lzma
2023-12-11T17:08:14.351Z,1702314494.351 [DataOverHttps](INFO): Moved sent file to Logs/20231211T164935/Courier0007.lzma.bak
2023-12-11T17:08:14.351Z,1702314494.351 [DataOverHttps](INFO): SBD MOMSN=19100396
2023-12-11T17:08:23.441Z,1702314503.441 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-12-11T17:08:23.441Z,1702314503.441 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T17:08:23.457Z,1702314503.457 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T17:08:23.847Z,1702314503.847 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T17:08:23.847Z,1702314503.847 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-12-11T17:08:30.448Z,1702314510.448 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231211T170512/Courier0000.lzma
2023-12-11T17:08:31.450Z,1702314511.450 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0000.lzma.bak
2023-12-11T17:08:31.450Z,1702314511.450 [DataOverHttps](INFO): SBD MOMSN=19100398
2023-12-11T17:09:06.251Z,1702314546.251 [Startup:StartupSatComms:B](INFO): Timed out from 2023-12-11T17:08:06.1Z
2023-12-11T17:09:06.251Z,1702314546.251 [Startup:StartupSatComms:B] Stopped
2023-12-11T17:09:06.251Z,1702314546.251 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-12-11T17:09:06.251Z,1702314546.251 [Startup:StartupSatComms] Stopped
2023-12-11T17:09:06.251Z,1702314546.251 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-12-11T17:09:06.256Z,1702314546.256 [Startup](INFO): Completed Startup
2023-12-11T17:09:06.257Z,1702314546.257 [MissionManager](INFO): Startup is completed.
2023-12-11T17:09:06.257Z,1702314546.257 [MissionManager](INFO): Uninitializing Mission Startup
2023-12-11T17:09:06.257Z,1702314546.257 [Startup] Stopped
2023-12-11T17:09:06.257Z,1702314546.257 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-12-11T17:09:06.257Z,1702314546.257 [Startup:A.GoToSurface] Stopped
2023-12-11T17:09:06.257Z,1702314546.257 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-12-11T17:09:06.662Z,1702314546.662 [MissionManager](IMPORTANT): Started mission Default
2023-12-11T17:09:06.662Z,1702314546.662 [Default] Running Loop=1
2023-12-11T17:09:06.662Z,1702314546.662 [Default](DEBUG): Aggregate::initialize Default
2023-12-11T17:09:06.662Z,1702314546.662 [Default:B.GoToSurface] Running Loop=1
2023-12-11T17:09:06.662Z,1702314546.662 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-12-11T17:09:06.662Z,1702314546.662 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-12-11T17:09:06.663Z,1702314546.663 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-12-11T17:09:06.663Z,1702314546.663 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-12-11T17:09:06.663Z,1702314546.663 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-12-11T17:09:06.664Z,1702314546.664 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-12-11T17:09:06.668Z,1702314546.668 [Default:A.Wait] Running Loop=1
2023-12-11T17:09:06.668Z,1702314546.668 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:09:19.188Z,1702314559.188 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20231211T164935/Express0008.lzma
2023-12-11T17:09:19.976Z,1702314559.976 [Default:A.Wait](INFO): Done Waiting.
2023-12-11T17:09:19.976Z,1702314559.976 [Default:A.Wait] Stopped
2023-12-11T17:09:19.976Z,1702314559.976 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:09:20.190Z,1702314560.190 [DataOverHttps](INFO): Moved sent file to Logs/20231211T164935/Express0008.lzma.bak
2023-12-11T17:09:20.190Z,1702314560.190 [DataOverHttps](INFO): SBD MOMSN=19100414
2023-12-11T17:09:20.392Z,1702314560.392 [Default:CheckIn] Running Loop=1
2023-12-11T17:09:20.393Z,1702314560.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:09:20.393Z,1702314560.393 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:09:20.783Z,1702314560.783 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-12-11T17:11:06.220Z,1702314666.220 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-11T17:11:24.413Z,1702314684.413 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-12-11T17:11:24.413Z,1702314684.413 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T17:11:24.424Z,1702314684.424 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T17:11:24.832Z,1702314684.832 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T17:11:24.832Z,1702314684.832 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-12-11T17:12:17.334Z,1702314737.334 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-12-11T17:12:17.341Z,1702314737.341 [BPC1](INFO): Received data from all battery sticks.
2023-12-11T17:14:20.619Z,1702314860.619 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-12-11T17:09:20.4Z
2023-12-11T17:14:20.619Z,1702314860.619 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:14:20.619Z,1702314860.619 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:14:21.026Z,1702314861.026 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-12-11T17:14:25.482Z,1702314865.482 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-12-11T17:14:25.482Z,1702314865.482 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T17:14:25.522Z,1702314865.522 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T17:14:25.905Z,1702314865.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T17:14:25.905Z,1702314865.905 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-12-11T17:14:28.344Z,1702314868.344 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20231211T170512/Courier0004.lzma
2023-12-11T17:14:29.346Z,1702314869.346 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0004.lzma.bak
2023-12-11T17:14:29.346Z,1702314869.346 [DataOverHttps](INFO): SBD MOMSN=19100498
2023-12-11T17:14:45.293Z,1702314885.293 [DataOverHttps](INFO): Sending 979 bytes from file Logs/20231211T170512/Express0001.lzma
2023-12-11T17:14:46.286Z,1702314886.286 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0001.lzma.bak
2023-12-11T17:14:46.286Z,1702314886.286 [DataOverHttps](INFO): SBD MOMSN=19100500
2023-12-11T17:15:02.212Z,1702314902.212 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20231211T170512/Express0005.lzma
2023-12-11T17:15:03.214Z,1702314903.214 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0005.lzma.bak
2023-12-11T17:15:03.214Z,1702314903.214 [DataOverHttps](INFO): SBD MOMSN=19100520
2023-12-11T17:15:04.238Z,1702314904.238 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:15:04.238Z,1702314904.238 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:15:04.238Z,1702314904.238 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:16:03.678Z,1702314963.678 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-12-11T17:16:03.678Z,1702314963.678 [NAL9602] Data Fault, FailCount= 1
2023-12-11T17:16:03.678Z,1702314963.678 [NAL9602](ERROR): Data Fault
2023-12-11T17:16:03.728Z,1702314963.728 [CBIT](ERROR): Data Fault in component: NAL9602
2023-12-11T17:16:04.065Z,1702314964.065 [NAL9602](INFO): Powering down
2023-12-11T17:16:04.908Z,1702314964.908 [CBIT](INFO): Clearing failed state for component NAL9602
2023-12-11T17:16:04.908Z,1702314964.908 [NAL9602] No Fault, FailCount= 1
2023-12-11T17:16:34.406Z,1702314994.406 [NAL9602](INFO): Powering up NAL9602
2023-12-11T17:16:45.310Z,1702315005.310 [NAL9602](INFO): NAL9602 initialized
2023-12-11T17:17:26.545Z,1702315046.545 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-12-11T17:17:26.546Z,1702315046.546 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T17:17:26.581Z,1702315046.581 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T17:17:26.941Z,1702315046.941 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T17:17:26.941Z,1702315046.941 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-12-11T17:20:04.917Z,1702315204.917 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:20:04.917Z,1702315204.917 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:20:04.917Z,1702315204.917 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:20:04.918Z,1702315204.918 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:20:05.341Z,1702315205.341 [Default:CheckIn:D] Stopped
2023-12-11T17:20:05.341Z,1702315205.341 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:20:05.738Z,1702315205.738 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.977987 min 
2023-12-11T17:20:05.738Z,1702315205.738 [Default:CheckIn:E] Stopped
2023-12-11T17:20:05.739Z,1702315205.739 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:20:05.739Z,1702315205.739 [Default:CheckIn] Stopped
2023-12-11T17:20:05.739Z,1702315205.739 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:20:05.739Z,1702315205.739 [Default:CheckIn](INFO): Running loop #2
2023-12-11T17:20:05.739Z,1702315205.739 [Default:CheckIn] Running Loop=2
2023-12-11T17:20:05.739Z,1702315205.739 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:20:05.739Z,1702315205.739 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:20:27.559Z,1702315227.559 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-12-11T17:20:27.559Z,1702315227.559 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T17:20:27.602Z,1702315227.602 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T17:20:27.968Z,1702315227.968 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T17:20:27.969Z,1702315227.969 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-12-11T17:22:18.433Z,1702315338.433 [DataOverHttps](IMPORTANT): SBD MTMSN=20231211T172217
2023-12-11T17:22:25.880Z,1702315345.880 [DataOverHttps](INFO): Received command: ibit
2023-12-11T17:22:25.961Z,1702315345.961 [CommandExec](IMPORTANT): got command ibit
2023-12-11T17:22:26.388Z,1702315346.388 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-12-11T17:22:26.389Z,1702315346.389 [IBIT](IMPORTANT): Beginning control surface checks.
2023-12-11T17:22:26.392Z,1702315346.392 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-11T17:22:37.265Z,1702315357.265 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005087
CHAN A1 (24V): 0.008299
CHAN A2 (12V): -0.003818
CHAN A3 (5V): -0.000441
CHAN B0 (3.3V): 0.000659
CHAN B1 (3.15aV): 0.000704
CHAN B2 (3.15bV): 0.000601
CHAN B3 (GND): 0.001471
OPEN: 0.005233
Full Scale: +/- 1 mA
2023-12-11T17:23:26.083Z,1702315406.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172324.00,A,3648.14304,N,12147.22868,W,0.175,299.27,111223,,,A*77
2023-12-11T17:23:26.087Z,1702315406.087 [NAL9602](INFO): GPS fix at 20231211T172324: (36.802384, -121.787145)
2023-12-11T17:23:28.911Z,1702315408.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172327.00,A,3648.14254,N,12147.22830,W,0.078,299.27,111223,,,A*71
2023-12-11T17:23:28.913Z,1702315408.913 [NAL9602](INFO): GPS fix at 20231211T172327: (36.802376, -121.787138)
2023-12-11T17:23:31.745Z,1702315411.745 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172330.00,A,3648.14279,N,12147.22905,W,0.136,299.27,111223,,,A*74
2023-12-11T17:23:31.748Z,1702315411.748 [NAL9602](INFO): GPS fix at 20231211T172330: (36.802380, -121.787151)
2023-12-11T17:23:34.975Z,1702315414.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172333.00,A,3648.14322,N,12147.22947,W,0.078,299.27,111223,,,A*75
2023-12-11T17:23:34.977Z,1702315414.977 [NAL9602](INFO): GPS fix at 20231211T172333: (36.802387, -121.787158)
2023-12-11T17:23:37.803Z,1702315417.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172337.00,A,3648.14342,N,12147.22950,W,0.680,299.27,111223,,,A*70
2023-12-11T17:23:37.805Z,1702315417.805 [NAL9602](INFO): GPS fix at 20231211T172337: (36.802390, -121.787158)
2023-12-11T17:23:41.030Z,1702315421.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172340.00,A,3648.14323,N,12147.22956,W,0.039,238.83,111223,,,A*70
2023-12-11T17:23:41.033Z,1702315421.033 [NAL9602](INFO): GPS fix at 20231211T172340: (36.802387, -121.787159)
2023-12-11T17:23:43.863Z,1702315423.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172343.00,A,3648.14358,N,12147.22968,W,0.564,238.83,111223,,,A*7F
2023-12-11T17:23:43.865Z,1702315423.865 [NAL9602](INFO): GPS fix at 20231211T172343: (36.802393, -121.787161)
2023-12-11T17:23:47.095Z,1702315427.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172346.00,A,3648.14374,N,12147.22992,W,0.078,238.83,111223,,,A*79
2023-12-11T17:23:47.097Z,1702315427.097 [NAL9602](INFO): GPS fix at 20231211T172346: (36.802396, -121.787165)
2023-12-11T17:23:49.919Z,1702315429.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172349.00,A,3648.14360,N,12147.23015,W,0.272,238.83,111223,,,A*7C
2023-12-11T17:23:49.922Z,1702315429.922 [NAL9602](INFO): GPS fix at 20231211T172349: (36.802393, -121.787169)
2023-12-11T17:23:52.753Z,1702315432.753 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172352.00,A,3648.14350,N,12147.23027,W,0.097,238.83,111223,,,A*7D
2023-12-11T17:23:52.755Z,1702315432.755 [NAL9602](INFO): GPS fix at 20231211T172352: (36.802392, -121.787171)
2023-12-11T17:23:55.987Z,1702315435.987 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172355.00,A,3648.14356,N,12147.23034,W,0.564,238.83,111223,,,A*77
2023-12-11T17:23:55.990Z,1702315435.990 [NAL9602](INFO): GPS fix at 20231211T172355: (36.802393, -121.787172)
2023-12-11T17:23:58.821Z,1702315438.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172358.00,A,3648.14359,N,12147.23041,W,0.311,238.83,111223,,,A*73
2023-12-11T17:23:58.823Z,1702315438.823 [NAL9602](INFO): GPS fix at 20231211T172358: (36.802393, -121.787173)
2023-12-11T17:24:02.039Z,1702315442.039 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172401.00,A,3648.14380,N,12147.23051,W,0.428,238.83,111223,,,A*70
2023-12-11T17:24:02.041Z,1702315442.041 [NAL9602](INFO): GPS fix at 20231211T172401: (36.802397, -121.787175)
2023-12-11T17:24:04.867Z,1702315444.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172404.00,A,3648.14389,N,12147.23060,W,0.350,238.83,111223,,,A*76
2023-12-11T17:24:04.869Z,1702315444.869 [NAL9602](INFO): GPS fix at 20231211T172404: (36.802398, -121.787177)
2023-12-11T17:24:08.099Z,1702315448.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172407.00,A,3648.14371,N,12147.23069,W,0.194,238.83,111223,,,A*71
2023-12-11T17:24:08.101Z,1702315448.101 [NAL9602](INFO): GPS fix at 20231211T172407: (36.802395, -121.787178)
2023-12-11T17:24:10.935Z,1702315450.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172410.00,A,3648.14368,N,12147.23063,W,0.505,238.83,111223,,,A*79
2023-12-11T17:24:10.938Z,1702315450.938 [NAL9602](INFO): GPS fix at 20231211T172410: (36.802395, -121.787177)
2023-12-11T17:24:13.759Z,1702315453.759 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172413.00,A,3648.14336,N,12147.23055,W,0.505,238.83,111223,,,A*74
2023-12-11T17:24:13.763Z,1702315453.763 [NAL9602](INFO): GPS fix at 20231211T172413: (36.802389, -121.787176)
2023-12-11T17:24:16.988Z,1702315456.988 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172416.00,A,3648.14378,N,12147.23075,W,0.194,324.68,111223,,,A*7C
2023-12-11T17:24:16.990Z,1702315456.990 [NAL9602](INFO): GPS fix at 20231211T172416: (36.802396, -121.787179)
2023-12-11T17:24:17.007Z,1702315457.007 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2023-12-11T17:24:17.440Z,1702315457.440 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.690001
2023-12-11T17:24:17.441Z,1702315457.441 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-12-11T17:24:17.441Z,1702315457.441 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-12-11T17:24:17.811Z,1702315457.811 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-12-11T17:24:17.812Z,1702315457.812 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-12-11T17:24:17.828Z,1702315457.828 [IBIT](IMPORTANT): Pressure:7.878820 PSI
2023-12-11T17:24:17.829Z,1702315457.829 [IBIT](IMPORTANT): Humidity:25.204491 %
2023-12-11T17:24:18.218Z,1702315458.218 [IBIT](IMPORTANT): Vehicle Pitch:-1.112007 degrees
2023-12-11T17:24:18.218Z,1702315458.218 [IBIT](IMPORTANT): Vehicle  Roll:2.439186 degrees
2023-12-11T17:24:18.219Z,1702315458.219 [IBIT](IMPORTANT): Vehicle  Heading:26.651428 degrees
2023-12-11T17:24:18.633Z,1702315458.633 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-12-11T17:24:18.634Z,1702315458.634 [IBIT](IMPORTANT): buoyancyNeutral: 258.057068 cc
2023-12-11T17:24:18.634Z,1702315458.634 [IBIT](IMPORTANT): massDefault: 0.716920 cm
2023-12-11T17:24:18.634Z,1702315458.634 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-12-11T17:24:18.635Z,1702315458.635 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2023-12-11T17:24:18.635Z,1702315458.635 [IBIT](IMPORTANT): IBIT FAILED
2023-12-11T17:24:19.037Z,1702315459.037 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:24:19.037Z,1702315459.037 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:24:19.823Z,1702315459.823 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172419.00,A,3648.14365,N,12147.23086,W,0.428,324.68,111223,,,A*71
2023-12-11T17:24:19.826Z,1702315459.826 [NAL9602](INFO): GPS fix at 20231211T172419: (36.802394, -121.787181)
2023-12-11T17:24:26.840Z,1702315466.840 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20231211T170512/Courier0007.lzma
2023-12-11T17:24:27.838Z,1702315467.838 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0007.lzma.bak
2023-12-11T17:24:27.838Z,1702315467.838 [DataOverHttps](INFO): SBD MOMSN=19100537
2023-12-11T17:24:45.352Z,1702315485.352 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20231211T170512/Courier0010.lzma
2023-12-11T17:24:46.354Z,1702315486.354 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0010.lzma.bak
2023-12-11T17:24:46.354Z,1702315486.354 [DataOverHttps](INFO): SBD MOMSN=19100543
2023-12-11T17:24:52.553Z,1702315492.553 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:25:02.702Z,1702315502.702 [DataOverHttps](INFO): Sending 970 bytes from file Logs/20231211T170512/Express0008.lzma
2023-12-11T17:25:03.702Z,1702315503.702 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0008.lzma.bak
2023-12-11T17:25:03.703Z,1702315503.703 [DataOverHttps](INFO): SBD MOMSN=19100546
2023-12-11T17:25:19.988Z,1702315519.988 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T170512/Express0011.lzma
2023-12-11T17:25:20.990Z,1702315520.990 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0011.lzma.bak
2023-12-11T17:25:20.990Z,1702315520.990 [DataOverHttps](INFO): SBD MOMSN=19100573
2023-12-11T17:25:22.059Z,1702315522.059 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:25:22.059Z,1702315522.059 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:25:22.059Z,1702315522.059 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:26:07.910Z,1702315567.910 [DataOverHttps](IMPORTANT): SBD MTMSN=20231211T172606
2023-12-11T17:26:17.028Z,1702315577.028 [DataOverHttps](INFO): Received command: failc
2023-12-11T17:26:17.041Z,1702315577.041 [CommandExec](IMPORTANT): got command failComponent
2023-12-11T17:26:17.041Z,1702315577.041 [CommandExec](IMPORTANT): Failed components:
2023-12-11T17:26:17.041Z,1702315577.041 [CommandExec](IMPORTANT): No failed Components.
2023-12-11T17:26:51.990Z,1702315611.990 [DataOverHttps](IMPORTANT): SBD MTMSN=20231211T172651
2023-12-11T17:26:59.548Z,1702315619.548 [DataOverHttps](INFO): Received command: ibit
2023-12-11T17:26:59.617Z,1702315619.617 [CommandExec](IMPORTANT): got command ibit
2023-12-11T17:26:59.859Z,1702315619.859 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-12-11T17:26:59.859Z,1702315619.859 [IBIT](IMPORTANT): Beginning control surface checks.
2023-12-11T17:26:59.862Z,1702315619.862 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-11T17:27:01.453Z,1702315621.453 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172700.00,A,3648.13924,N,12147.23462,W,0.467,338.42,111223,,,A*72
2023-12-11T17:27:01.455Z,1702315621.455 [NAL9602](INFO): GPS fix at 20231211T172700: (36.802321, -121.787244)
2023-12-11T17:27:10.764Z,1702315630.764 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005268
CHAN A1 (24V): 0.009140
CHAN A2 (12V): -0.004507
CHAN A3 (5V): -0.000738
CHAN B0 (3.3V): 0.000181
CHAN B1 (3.15aV): 0.000625
CHAN B2 (3.15bV): 0.001090
CHAN B3 (GND): 0.001713
OPEN: 0.005650
Full Scale: +/- 1 mA
2023-12-11T17:27:13.570Z,1702315633.570 [NAL9602](INFO): SBD MO Status=0, MOMSN=34151, MT Status=0, MTMSN=0
2023-12-11T17:27:13.570Z,1702315633.570 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:27:14.782Z,1702315634.782 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172714.00,A,3648.13902,N,12147.23430,W,0.156,338.42,111223,,,A*73
2023-12-11T17:27:14.785Z,1702315634.785 [NAL9602](INFO): GPS fix at 20231211T172714: (36.802317, -121.787238)
2023-12-11T17:27:18.014Z,1702315638.014 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172717.00,A,3648.13860,N,12147.23450,W,0.836,338.42,111223,,,A*7C
2023-12-11T17:27:18.016Z,1702315638.016 [NAL9602](INFO): GPS fix at 20231211T172717: (36.802310, -121.787242)
2023-12-11T17:27:20.842Z,1702315640.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172720.00,A,3648.13813,N,12147.23456,W,0.214,338.42,111223,,,A*70
2023-12-11T17:27:20.844Z,1702315640.844 [NAL9602](INFO): GPS fix at 20231211T172720: (36.802302, -121.787243)
2023-12-11T17:27:24.079Z,1702315644.079 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172723.00,A,3648.13780,N,12147.23466,W,0.233,338.42,111223,,,A*70
2023-12-11T17:27:24.082Z,1702315644.082 [NAL9602](INFO): GPS fix at 20231211T172723: (36.802297, -121.787244)
2023-12-11T17:27:26.907Z,1702315646.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172726.00,A,3648.13778,N,12147.23478,W,0.311,338.42,111223,,,A*7C
2023-12-11T17:27:26.910Z,1702315646.910 [NAL9602](INFO): GPS fix at 20231211T172726: (36.802296, -121.787246)
2023-12-11T17:27:30.134Z,1702315650.134 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172729.00,A,3648.13786,N,12147.23495,W,0.078,338.42,111223,,,A*7D
2023-12-11T17:27:30.136Z,1702315650.136 [NAL9602](INFO): GPS fix at 20231211T172729: (36.802298, -121.787249)
2023-12-11T17:27:32.963Z,1702315652.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172732.00,A,3648.13814,N,12147.23514,W,0.525,338.42,111223,,,A*76
2023-12-11T17:27:32.966Z,1702315652.966 [NAL9602](INFO): GPS fix at 20231211T172732: (36.802302, -121.787252)
2023-12-11T17:27:35.790Z,1702315655.790 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172735.00,A,3648.13901,N,12147.23544,W,0.758,338.42,111223,,,A*79
2023-12-11T17:27:35.793Z,1702315655.793 [NAL9602](INFO): GPS fix at 20231211T172735: (36.802317, -121.787257)
2023-12-11T17:27:39.023Z,1702315659.023 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172738.00,A,3648.13918,N,12147.23576,W,0.564,338.42,111223,,,A*70
2023-12-11T17:27:39.026Z,1702315659.026 [NAL9602](INFO): GPS fix at 20231211T172738: (36.802320, -121.787263)
2023-12-11T17:27:41.851Z,1702315661.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172741.00,A,3648.13937,N,12147.23592,W,0.428,338.42,111223,,,A*70
2023-12-11T17:27:41.854Z,1702315661.854 [NAL9602](INFO): GPS fix at 20231211T172741: (36.802323, -121.787265)
2023-12-11T17:27:45.094Z,1702315665.094 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172744.00,A,3648.13948,N,12147.23597,W,0.156,338.42,111223,,,A*74
2023-12-11T17:27:45.096Z,1702315665.096 [NAL9602](INFO): GPS fix at 20231211T172744: (36.802325, -121.787266)
2023-12-11T17:27:46.322Z,1702315666.322 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802326 Longitude: -121.787270
2023-12-11T17:27:46.732Z,1702315666.732 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.690001
2023-12-11T17:27:46.733Z,1702315666.733 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-12-11T17:27:46.733Z,1702315666.733 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-12-11T17:27:47.139Z,1702315667.139 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-12-11T17:27:47.139Z,1702315667.139 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-12-11T17:27:47.139Z,1702315667.139 [IBIT](IMPORTANT): Pressure:7.840046 PSI
2023-12-11T17:27:47.139Z,1702315667.139 [IBIT](IMPORTANT): Humidity:25.271639 %
2023-12-11T17:27:47.575Z,1702315667.575 [IBIT](IMPORTANT): Vehicle Pitch:-1.127651 degrees
2023-12-11T17:27:47.575Z,1702315667.575 [IBIT](IMPORTANT): Vehicle  Roll:2.447095 degrees
2023-12-11T17:27:47.576Z,1702315667.576 [IBIT](IMPORTANT): Vehicle  Heading:26.659914 degrees
2023-12-11T17:27:47.930Z,1702315667.930 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-12-11T17:27:47.931Z,1702315667.931 [IBIT](IMPORTANT): buoyancyNeutral: 258.057068 cc
2023-12-11T17:27:47.931Z,1702315667.931 [IBIT](IMPORTANT): massDefault: 0.716920 cm
2023-12-11T17:27:47.931Z,1702315667.931 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-12-11T17:27:47.931Z,1702315667.931 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2023-12-11T17:27:47.932Z,1702315667.932 [IBIT](IMPORTANT): IBIT PASSED
2023-12-11T17:27:49.547Z,1702315669.547 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-32768,
2023-12-11T17:28:17.417Z,1702315697.417 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:30:22.684Z,1702315822.684 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:30:22.685Z,1702315822.685 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:30:22.685Z,1702315822.685 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:30:22.685Z,1702315822.685 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:30:23.086Z,1702315823.086 [Default:CheckIn:D] Stopped
2023-12-11T17:30:23.086Z,1702315823.086 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:30:23.537Z,1702315823.537 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.273730 min 
2023-12-11T17:30:23.537Z,1702315823.537 [Default:CheckIn:E] Stopped
2023-12-11T17:30:23.538Z,1702315823.538 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:30:23.538Z,1702315823.538 [Default:CheckIn] Stopped
2023-12-11T17:30:23.538Z,1702315823.538 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:30:23.538Z,1702315823.538 [Default:CheckIn](INFO): Running loop #3
2023-12-11T17:30:23.538Z,1702315823.538 [Default:CheckIn] Running Loop=3
2023-12-11T17:30:23.538Z,1702315823.538 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:30:23.538Z,1702315823.538 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:30:25.492Z,1702315825.492 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173024.00,A,3648.13718,N,12147.23565,W,0.039,338.42,111223,,,A*7A
2023-12-11T17:30:25.494Z,1702315825.494 [NAL9602](INFO): GPS fix at 20231211T173024: (36.802286, -121.787261)
2023-12-11T17:30:25.526Z,1702315825.526 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:30:25.526Z,1702315825.526 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:30:33.147Z,1702315833.147 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T170512/Courier0013.lzma
2023-12-11T17:30:34.150Z,1702315834.150 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0013.lzma.bak
2023-12-11T17:30:34.150Z,1702315834.150 [DataOverHttps](INFO): SBD MOMSN=19100588
2023-12-11T17:30:35.999Z,1702315835.999 [NAL9602](INFO): SBD MO Status=0, MOMSN=34152, MT Status=0, MTMSN=0
2023-12-11T17:30:35.999Z,1702315835.999 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:30:50.669Z,1702315850.669 [DataOverHttps](INFO): Sending 836 bytes from file Logs/20231211T170512/Express0014.lzma
2023-12-11T17:30:51.670Z,1702315851.670 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0014.lzma.bak
2023-12-11T17:30:51.670Z,1702315851.670 [DataOverHttps](INFO): SBD MOMSN=19100591
2023-12-11T17:31:06.697Z,1702315866.697 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:31:07.992Z,1702315867.992 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20231211T170512/Express0017.lzma
2023-12-11T17:31:08.990Z,1702315868.990 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0017.lzma.bak
2023-12-11T17:31:08.990Z,1702315868.990 [DataOverHttps](INFO): SBD MOMSN=19100621
2023-12-11T17:31:10.345Z,1702315870.345 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:31:10.345Z,1702315870.345 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:31:10.345Z,1702315870.345 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:36:10.930Z,1702316170.930 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:36:10.930Z,1702316170.930 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:36:10.930Z,1702316170.930 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:36:10.930Z,1702316170.930 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:36:11.356Z,1702316171.356 [Default:CheckIn:D] Stopped
2023-12-11T17:36:11.356Z,1702316171.356 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:36:11.733Z,1702316171.733 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.078239 min 
2023-12-11T17:36:11.733Z,1702316171.733 [Default:CheckIn:E] Stopped
2023-12-11T17:36:11.733Z,1702316171.733 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:36:11.733Z,1702316171.733 [Default:CheckIn] Stopped
2023-12-11T17:36:11.734Z,1702316171.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:36:11.734Z,1702316171.734 [Default:CheckIn](INFO): Running loop #4
2023-12-11T17:36:11.734Z,1702316171.734 [Default:CheckIn] Running Loop=4
2023-12-11T17:36:11.734Z,1702316171.734 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:36:11.734Z,1702316171.734 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:36:13.750Z,1702316173.750 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173613.00,A,3648.14253,N,12147.23643,W,0.058,338.42,111223,,,A*75
2023-12-11T17:36:13.752Z,1702316173.752 [NAL9602](INFO): GPS fix at 20231211T173613: (36.802375, -121.787274)
2023-12-11T17:36:13.763Z,1702316173.763 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:36:13.764Z,1702316173.764 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:36:21.544Z,1702316181.544 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0019.lzma
2023-12-11T17:36:22.547Z,1702316182.547 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0019.lzma.bak
2023-12-11T17:36:22.547Z,1702316182.547 [DataOverHttps](INFO): SBD MOMSN=19100631
2023-12-11T17:36:25.455Z,1702316185.455 [NAL9602](INFO): SBD MO Status=0, MOMSN=34153, MT Status=0, MTMSN=0
2023-12-11T17:36:25.455Z,1702316185.455 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:36:38.848Z,1702316198.848 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231211T170512/Express0020.lzma
2023-12-11T17:36:39.850Z,1702316199.850 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0020.lzma.bak
2023-12-11T17:36:39.850Z,1702316199.850 [DataOverHttps](INFO): SBD MOMSN=19100634
2023-12-11T17:36:41.243Z,1702316201.243 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:36:41.243Z,1702316201.243 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:36:41.243Z,1702316201.243 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:36:56.156Z,1702316216.156 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:41:41.893Z,1702316501.893 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:41:41.893Z,1702316501.893 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:41:41.893Z,1702316501.893 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:41:41.894Z,1702316501.894 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:41:42.314Z,1702316502.314 [Default:CheckIn:D] Stopped
2023-12-11T17:41:42.314Z,1702316502.314 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:41:42.698Z,1702316502.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.594196 min 
2023-12-11T17:41:42.698Z,1702316502.698 [Default:CheckIn:E] Stopped
2023-12-11T17:41:42.698Z,1702316502.698 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:41:42.698Z,1702316502.698 [Default:CheckIn] Stopped
2023-12-11T17:41:42.698Z,1702316502.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:41:42.698Z,1702316502.698 [Default:CheckIn](INFO): Running loop #5
2023-12-11T17:41:42.698Z,1702316502.698 [Default:CheckIn] Running Loop=5
2023-12-11T17:41:42.699Z,1702316502.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:41:42.699Z,1702316502.699 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:41:44.704Z,1702316504.704 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174143.00,A,3648.13497,N,12147.23186,W,0.117,104.78,111223,,,A*79
2023-12-11T17:41:44.706Z,1702316504.706 [NAL9602](INFO): GPS fix at 20231211T174143: (36.802250, -121.787198)
2023-12-11T17:41:44.718Z,1702316504.718 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:41:44.718Z,1702316504.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:41:52.615Z,1702316512.615 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0022.lzma
2023-12-11T17:41:53.626Z,1702316513.626 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0022.lzma.bak
2023-12-11T17:41:53.626Z,1702316513.626 [DataOverHttps](INFO): SBD MOMSN=19100645
2023-12-11T17:41:59.296Z,1702316519.296 [NAL9602](INFO): SBD MO Status=0, MOMSN=34154, MT Status=0, MTMSN=0
2023-12-11T17:41:59.296Z,1702316519.296 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:42:09.000Z,1702316530.000 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231211T170512/Express0023.lzma
2023-12-11T17:42:11.002Z,1702316531.002 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0023.lzma.bak
2023-12-11T17:42:11.002Z,1702316531.002 [DataOverHttps](INFO): SBD MOMSN=19100648
2023-12-11T17:42:12.193Z,1702316532.193 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:42:12.193Z,1702316532.193 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:42:12.193Z,1702316532.193 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:42:29.990Z,1702316549.990 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:47:12.877Z,1702316832.877 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:47:12.877Z,1702316832.877 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:47:12.877Z,1702316832.877 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:47:12.877Z,1702316832.877 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:47:13.344Z,1702316833.344 [Default:CheckIn:D] Stopped
2023-12-11T17:47:13.344Z,1702316833.344 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:47:13.677Z,1702316833.677 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.111377 min 
2023-12-11T17:47:13.677Z,1702316833.677 [Default:CheckIn:E] Stopped
2023-12-11T17:47:13.677Z,1702316833.677 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:47:13.677Z,1702316833.677 [Default:CheckIn] Stopped
2023-12-11T17:47:13.677Z,1702316833.677 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:47:13.678Z,1702316833.678 [Default:CheckIn](INFO): Running loop #6
2023-12-11T17:47:13.678Z,1702316833.678 [Default:CheckIn] Running Loop=6
2023-12-11T17:47:13.678Z,1702316833.678 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:47:13.678Z,1702316833.678 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:47:15.683Z,1702316835.683 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174715.00,A,3648.13190,N,12147.23464,W,0.175,128.46,111223,,,A*70
2023-12-11T17:47:15.686Z,1702316835.686 [NAL9602](INFO): GPS fix at 20231211T174715: (36.802198, -121.787244)
2023-12-11T17:47:15.697Z,1702316835.697 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:47:15.697Z,1702316835.697 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:47:23.120Z,1702316843.120 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T170512/Courier0025.lzma
2023-12-11T17:47:24.122Z,1702316844.122 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0025.lzma.bak
2023-12-11T17:47:24.122Z,1702316844.122 [DataOverHttps](INFO): SBD MOMSN=19100673
2023-12-11T17:47:36.291Z,1702316856.291 [NAL9602](INFO): SBD MO Status=0, MOMSN=34155, MT Status=0, MTMSN=0
2023-12-11T17:47:36.291Z,1702316856.291 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:47:40.492Z,1702316860.492 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20231211T170512/Express0026.lzma
2023-12-11T17:47:41.494Z,1702316861.494 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0026.lzma.bak
2023-12-11T17:47:41.494Z,1702316861.494 [DataOverHttps](INFO): SBD MOMSN=19100676
2023-12-11T17:47:42.762Z,1702316862.762 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:47:42.762Z,1702316862.762 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:47:42.762Z,1702316862.762 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:48:07.008Z,1702316887.008 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:52:43.377Z,1702317163.377 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:52:43.377Z,1702317163.377 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:52:43.377Z,1702317163.377 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:52:43.377Z,1702317163.377 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:52:43.764Z,1702317163.764 [Default:CheckIn:D] Stopped
2023-12-11T17:52:43.764Z,1702317163.764 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:52:44.156Z,1702317164.156 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.618376 min 
2023-12-11T17:52:44.156Z,1702317164.156 [Default:CheckIn:E] Stopped
2023-12-11T17:52:44.156Z,1702317164.156 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:52:44.156Z,1702317164.156 [Default:CheckIn] Stopped
2023-12-11T17:52:44.156Z,1702317164.156 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:52:44.156Z,1702317164.156 [Default:CheckIn](INFO): Running loop #7
2023-12-11T17:52:44.156Z,1702317164.156 [Default:CheckIn] Running Loop=7
2023-12-11T17:52:44.157Z,1702317164.157 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:52:44.157Z,1702317164.157 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:52:46.171Z,1702317166.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175245.00,A,3648.13516,N,12147.23460,W,0.039,128.46,111223,,,D*73
2023-12-11T17:52:46.173Z,1702317166.173 [NAL9602](INFO): GPS fix at 20231211T175245: (36.802253, -121.787243)
2023-12-11T17:52:46.185Z,1702317166.185 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:52:46.186Z,1702317166.186 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:52:53.924Z,1702317173.924 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0028.lzma
2023-12-11T17:52:54.926Z,1702317174.926 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0028.lzma.bak
2023-12-11T17:52:54.927Z,1702317174.927 [DataOverHttps](INFO): SBD MOMSN=19100704
2023-12-11T17:53:11.254Z,1702317191.254 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231211T170512/Express0029.lzma
2023-12-11T17:53:11.619Z,1702317191.619 [NAL9602](INFO): SBD MO Status=0, MOMSN=34156, MT Status=0, MTMSN=0
2023-12-11T17:53:11.619Z,1702317191.619 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:53:12.251Z,1702317192.251 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0029.lzma.bak
2023-12-11T17:53:12.252Z,1702317192.252 [DataOverHttps](INFO): SBD MOMSN=19100707
2023-12-11T17:53:13.339Z,1702317193.339 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:53:13.339Z,1702317193.339 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:53:13.339Z,1702317193.339 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:53:42.321Z,1702317222.321 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:58:13.897Z,1702317493.897 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:58:13.897Z,1702317493.897 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:58:13.897Z,1702317493.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:58:13.898Z,1702317493.898 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:58:14.313Z,1702317494.313 [Default:CheckIn:D] Stopped
2023-12-11T17:58:14.313Z,1702317494.313 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:58:14.708Z,1702317494.708 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.127515 min 
2023-12-11T17:58:14.725Z,1702317494.725 [Default:CheckIn:E] Stopped
2023-12-11T17:58:14.725Z,1702317494.725 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:58:14.725Z,1702317494.725 [Default:CheckIn] Stopped
2023-12-11T17:58:14.725Z,1702317494.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:58:14.725Z,1702317494.725 [Default:CheckIn](INFO): Running loop #8
2023-12-11T17:58:14.725Z,1702317494.725 [Default:CheckIn] Running Loop=8
2023-12-11T17:58:14.725Z,1702317494.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:58:14.725Z,1702317494.725 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:58:16.719Z,1702317496.719 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175816.00,A,3648.13714,N,12147.23324,W,0.039,347.04,111223,,,D*75
2023-12-11T17:58:16.721Z,1702317496.721 [NAL9602](INFO): GPS fix at 20231211T175816: (36.802286, -121.787221)
2023-12-11T17:58:16.733Z,1702317496.733 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:58:16.733Z,1702317496.733 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:58:24.812Z,1702317504.812 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20231211T170512/Courier0031.lzma
2023-12-11T17:58:25.814Z,1702317505.814 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0031.lzma.bak
2023-12-11T17:58:25.814Z,1702317505.814 [DataOverHttps](INFO): SBD MOMSN=19100720
2023-12-11T17:58:36.915Z,1702317516.915 [NAL9602](INFO): SBD MO Status=2, MOMSN=34157, MT Status=2, MTMSN=0
2023-12-11T17:58:36.915Z,1702317516.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T17:58:42.132Z,1702317522.132 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231211T170512/Express0032.lzma
2023-12-11T17:58:43.134Z,1702317523.134 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0032.lzma.bak
2023-12-11T17:58:43.134Z,1702317523.134 [DataOverHttps](INFO): SBD MOMSN=19100723
2023-12-11T17:58:44.205Z,1702317524.205 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:58:44.205Z,1702317524.205 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:58:44.205Z,1702317524.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:58:56.710Z,1702317536.710 [NAL9602](INFO): SBD MO Status=2, MOMSN=34157, MT Status=2, MTMSN=0
2023-12-11T17:58:56.711Z,1702317536.711 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T17:59:12.072Z,1702317552.072 [NAL9602](INFO): SBD MO Status=0, MOMSN=34157, MT Status=0, MTMSN=0
2023-12-11T17:59:12.072Z,1702317552.072 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:59:42.769Z,1702317582.769 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:03:44.779Z,1702317824.779 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:03:44.779Z,1702317824.779 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:03:44.779Z,1702317824.779 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:03:44.779Z,1702317824.779 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:03:45.193Z,1702317825.193 [Default:CheckIn:D] Stopped
2023-12-11T18:03:45.193Z,1702317825.193 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:03:45.663Z,1702317825.663 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.642183 min 
2023-12-11T18:03:45.664Z,1702317825.664 [Default:CheckIn:E] Stopped
2023-12-11T18:03:45.664Z,1702317825.664 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:03:45.664Z,1702317825.664 [Default:CheckIn] Stopped
2023-12-11T18:03:45.668Z,1702317825.668 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:03:45.668Z,1702317825.668 [Default:CheckIn](INFO): Running loop #9
2023-12-11T18:03:45.668Z,1702317825.668 [Default:CheckIn] Running Loop=9
2023-12-11T18:03:45.668Z,1702317825.668 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:03:45.669Z,1702317825.669 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:03:47.594Z,1702317827.594 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180346.00,A,3648.13922,N,12147.22991,W,0.019,347.04,111223,,,D*7D
2023-12-11T18:03:47.597Z,1702317827.597 [NAL9602](INFO): GPS fix at 20231211T180346: (36.802320, -121.787165)
2023-12-11T18:03:47.668Z,1702317827.668 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:03:47.668Z,1702317827.668 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:03:54.467Z,1702317834.467 [NAL9602](INFO): SBD MO Status=0, MOMSN=34158, MT Status=0, MTMSN=0
2023-12-11T18:03:54.467Z,1702317834.467 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:03:55.752Z,1702317835.752 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0034.lzma
2023-12-11T18:03:56.754Z,1702317836.754 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0034.lzma.bak
2023-12-11T18:03:56.754Z,1702317836.754 [DataOverHttps](INFO): SBD MOMSN=19100736
2023-12-11T18:04:16.036Z,1702317856.036 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20231211T170512/Express0035.lzma
2023-12-11T18:04:17.039Z,1702317857.039 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0035.lzma.bak
2023-12-11T18:04:17.039Z,1702317857.039 [DataOverHttps](INFO): SBD MOMSN=19100739
2023-12-11T18:04:18.382Z,1702317858.382 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:04:18.382Z,1702317858.382 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:04:18.382Z,1702317858.382 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:04:25.221Z,1702317865.221 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:04:31.327Z,1702317871.327 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-3276
2023-12-11T18:09:19.024Z,1702318159.024 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:09:19.024Z,1702318159.024 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:09:19.024Z,1702318159.024 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:09:19.025Z,1702318159.025 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:09:19.455Z,1702318159.455 [Default:CheckIn:D] Stopped
2023-12-11T18:09:19.455Z,1702318159.455 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:09:19.846Z,1702318159.846 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.213212 min 
2023-12-11T18:09:19.846Z,1702318159.846 [Default:CheckIn:E] Stopped
2023-12-11T18:09:19.846Z,1702318159.846 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:09:19.846Z,1702318159.846 [Default:CheckIn] Stopped
2023-12-11T18:09:19.846Z,1702318159.846 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:09:19.846Z,1702318159.846 [Default:CheckIn](INFO): Running loop #10
2023-12-11T18:09:19.846Z,1702318159.846 [Default:CheckIn] Running Loop=10
2023-12-11T18:09:19.846Z,1702318159.846 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:09:19.847Z,1702318159.847 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:09:21.838Z,1702318161.838 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180921.00,A,3648.13928,N,12147.23029,W,0.039,347.04,111223,,,D*75
2023-12-11T18:09:21.840Z,1702318161.840 [NAL9602](INFO): GPS fix at 20231211T180921: (36.802321, -121.787171)
2023-12-11T18:09:21.852Z,1702318161.852 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:09:21.852Z,1702318161.852 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:09:29.492Z,1702318169.492 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0037.lzma
2023-12-11T18:09:30.494Z,1702318170.494 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0037.lzma.bak
2023-12-11T18:09:30.494Z,1702318170.494 [DataOverHttps](INFO): SBD MOMSN=19100752
2023-12-11T18:09:41.239Z,1702318181.239 [NAL9602](INFO): SBD MO Status=0, MOMSN=34159, MT Status=0, MTMSN=0
2023-12-11T18:09:41.240Z,1702318181.240 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:09:46.828Z,1702318186.828 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20231211T170512/Express0038.lzma
2023-12-11T18:09:47.826Z,1702318187.826 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0038.lzma.bak
2023-12-11T18:09:47.826Z,1702318187.826 [DataOverHttps](INFO): SBD MOMSN=19100755
2023-12-11T18:09:48.952Z,1702318188.952 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:09:48.952Z,1702318188.952 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:09:48.952Z,1702318188.952 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:10:12.014Z,1702318212.014 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:14:49.620Z,1702318489.620 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:14:49.620Z,1702318489.620 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:14:49.620Z,1702318489.620 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:14:49.620Z,1702318489.620 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:14:49.982Z,1702318489.982 [Default:CheckIn:D] Stopped
2023-12-11T18:14:49.982Z,1702318489.982 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:14:50.405Z,1702318490.405 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.722001 min 
2023-12-11T18:14:50.406Z,1702318490.406 [Default:CheckIn:E] Stopped
2023-12-11T18:14:50.406Z,1702318490.406 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:14:50.406Z,1702318490.406 [Default:CheckIn] Stopped
2023-12-11T18:14:50.406Z,1702318490.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:14:50.406Z,1702318490.406 [Default:CheckIn](INFO): Running loop #11
2023-12-11T18:14:50.406Z,1702318490.406 [Default:CheckIn] Running Loop=11
2023-12-11T18:14:50.406Z,1702318490.406 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:14:50.406Z,1702318490.406 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:14:52.399Z,1702318492.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181451.00,A,3648.13973,N,12147.23021,W,0.058,347.04,111223,,,D*7F
2023-12-11T18:14:52.401Z,1702318492.401 [NAL9602](INFO): GPS fix at 20231211T181451: (36.802329, -121.787170)
2023-12-11T18:14:52.418Z,1702318492.418 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:14:52.418Z,1702318492.418 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:14:59.299Z,1702318499.299 [NAL9602](INFO): SBD MO Status=0, MOMSN=34160, MT Status=0, MTMSN=0
2023-12-11T18:14:59.299Z,1702318499.299 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:15:00.080Z,1702318500.080 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231211T170512/Courier0040.lzma
2023-12-11T18:15:01.082Z,1702318501.082 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0040.lzma.bak
2023-12-11T18:15:01.082Z,1702318501.082 [DataOverHttps](INFO): SBD MOMSN=19100768
2023-12-11T18:15:17.584Z,1702318517.584 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20231211T170512/Express0041.lzma
2023-12-11T18:15:18.586Z,1702318518.586 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0041.lzma.bak
2023-12-11T18:15:18.586Z,1702318518.586 [DataOverHttps](INFO): SBD MOMSN=19100771
2023-12-11T18:15:19.914Z,1702318519.914 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:15:19.914Z,1702318519.914 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:15:19.914Z,1702318519.914 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:15:29.989Z,1702318529.989 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:19:43.319Z,1702318783.319 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-12-11T18:19:43.322Z,1702318783.322 [BPC1](INFO): Received data from all battery sticks.
2023-12-11T18:20:20.503Z,1702318820.503 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:20:20.503Z,1702318820.503 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:20:20.503Z,1702318820.503 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:20:20.503Z,1702318820.503 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:20:20.917Z,1702318820.917 [Default:CheckIn:D] Stopped
2023-12-11T18:20:20.917Z,1702318820.917 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:20:21.349Z,1702318821.349 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.237590 min 
2023-12-11T18:20:21.349Z,1702318821.349 [Default:CheckIn:E] Stopped
2023-12-11T18:20:21.349Z,1702318821.349 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:20:21.349Z,1702318821.349 [Default:CheckIn] Stopped
2023-12-11T18:20:21.350Z,1702318821.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:20:21.350Z,1702318821.350 [Default:CheckIn](INFO): Running loop #12
2023-12-11T18:20:21.350Z,1702318821.350 [Default:CheckIn] Running Loop=12
2023-12-11T18:20:21.350Z,1702318821.350 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:20:21.350Z,1702318821.350 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:20:23.323Z,1702318823.323 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182022.00,A,3648.14011,N,12147.23187,W,0.000,347.04,111223,,,D*76
2023-12-11T18:20:23.325Z,1702318823.325 [NAL9602](INFO): GPS fix at 20231211T182022: (36.802335, -121.787198)
2023-12-11T18:20:23.376Z,1702318823.376 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:20:23.376Z,1702318823.376 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:20:30.668Z,1702318830.668 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20231211T170512/Courier0043.lzma
2023-12-11T18:20:31.670Z,1702318831.670 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0043.lzma.bak
2023-12-11T18:20:31.670Z,1702318831.670 [DataOverHttps](INFO): SBD MOMSN=19100798
2023-12-11T18:20:35.039Z,1702318835.039 [NAL9602](INFO): SBD MO Status=0, MOMSN=34161, MT Status=0, MTMSN=0
2023-12-11T18:20:35.039Z,1702318835.039 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:20:48.104Z,1702318848.104 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231211T170512/Express0044.lzma
2023-12-11T18:20:49.106Z,1702318849.106 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0044.lzma.bak
2023-12-11T18:20:49.106Z,1702318849.106 [DataOverHttps](INFO): SBD MOMSN=19100801
2023-12-11T18:20:50.412Z,1702318850.412 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:20:50.412Z,1702318850.412 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:20:50.412Z,1702318850.412 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:21:05.741Z,1702318865.741 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:25:51.049Z,1702319151.049 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:25:51.049Z,1702319151.049 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:25:51.049Z,1702319151.049 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:25:51.049Z,1702319151.049 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:25:51.478Z,1702319151.478 [Default:CheckIn:D] Stopped
2023-12-11T18:25:51.478Z,1702319151.478 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:25:51.848Z,1702319151.848 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.746932 min 
2023-12-11T18:25:51.849Z,1702319151.849 [Default:CheckIn:E] Stopped
2023-12-11T18:25:51.849Z,1702319151.849 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:25:51.849Z,1702319151.849 [Default:CheckIn] Stopped
2023-12-11T18:25:51.849Z,1702319151.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:25:51.849Z,1702319151.849 [Default:CheckIn](INFO): Running loop #13
2023-12-11T18:25:51.849Z,1702319151.849 [Default:CheckIn] Running Loop=13
2023-12-11T18:25:51.849Z,1702319151.849 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:25:51.849Z,1702319151.849 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:25:53.859Z,1702319153.859 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182553.00,A,3648.13841,N,12147.23202,W,0.019,0.00,111223,,,D*7D
2023-12-11T18:25:53.862Z,1702319153.862 [NAL9602](INFO): GPS fix at 20231211T182553: (36.802307, -121.787200)
2023-12-11T18:25:53.874Z,1702319153.874 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:25:53.874Z,1702319153.874 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:26:03.672Z,1702319163.672 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0046.lzma
2023-12-11T18:26:04.674Z,1702319164.674 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0046.lzma.bak
2023-12-11T18:26:04.674Z,1702319164.674 [DataOverHttps](INFO): SBD MOMSN=19100821
2023-12-11T18:26:09.285Z,1702319169.285 [NAL9602](INFO): SBD MO Status=0, MOMSN=34162, MT Status=0, MTMSN=0
2023-12-11T18:26:09.285Z,1702319169.285 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:26:22.621Z,1702319182.621 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231211T170512/Express0047.lzma
2023-12-11T18:26:23.622Z,1702319183.622 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0047.lzma.bak
2023-12-11T18:26:23.622Z,1702319183.622 [DataOverHttps](INFO): SBD MOMSN=19100824
2023-12-11T18:26:25.016Z,1702319185.016 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:26:25.017Z,1702319185.017 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:26:25.017Z,1702319185.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:26:39.946Z,1702319199.946 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:31:25.672Z,1702319485.672 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:31:25.672Z,1702319485.672 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:31:25.672Z,1702319485.672 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:31:25.672Z,1702319485.672 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:31:26.059Z,1702319486.059 [Default:CheckIn:D] Stopped
2023-12-11T18:31:26.059Z,1702319486.059 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:31:26.453Z,1702319486.453 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.323291 min 
2023-12-11T18:31:26.453Z,1702319486.453 [Default:CheckIn:E] Stopped
2023-12-11T18:31:26.453Z,1702319486.453 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:31:26.453Z,1702319486.453 [Default:CheckIn] Stopped
2023-12-11T18:31:26.453Z,1702319486.453 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:31:26.453Z,1702319486.453 [Default:CheckIn](INFO): Running loop #14
2023-12-11T18:31:26.453Z,1702319486.453 [Default:CheckIn] Running Loop=14
2023-12-11T18:31:26.453Z,1702319486.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:31:26.453Z,1702319486.453 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:31:28.467Z,1702319488.467 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183127.00,A,3648.13687,N,12147.23223,W,0.019,0.00,111223,,,D*7C
2023-12-11T18:31:28.470Z,1702319488.470 [NAL9602](INFO): GPS fix at 20231211T183127: (36.802281, -121.787204)
2023-12-11T18:31:28.481Z,1702319488.481 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:31:28.482Z,1702319488.482 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:31:36.668Z,1702319496.668 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0049.lzma
2023-12-11T18:31:37.670Z,1702319497.670 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0049.lzma.bak
2023-12-11T18:31:37.671Z,1702319497.671 [DataOverHttps](INFO): SBD MOMSN=19100840
2023-12-11T18:31:39.375Z,1702319499.375 [NAL9602](INFO): SBD MO Status=0, MOMSN=34163, MT Status=0, MTMSN=0
2023-12-11T18:31:39.375Z,1702319499.375 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:31:54.224Z,1702319514.224 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20231211T170512/Express0050.lzma
2023-12-11T18:31:55.227Z,1702319515.227 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0050.lzma.bak
2023-12-11T18:31:55.227Z,1702319515.227 [DataOverHttps](INFO): SBD MOMSN=19100843
2023-12-11T18:31:56.452Z,1702319516.452 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:31:56.452Z,1702319516.452 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:31:56.452Z,1702319516.452 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:32:10.129Z,1702319530.129 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:36:56.993Z,1702319816.993 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:36:56.993Z,1702319816.993 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:36:56.993Z,1702319816.993 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:36:56.993Z,1702319816.993 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:36:57.415Z,1702319817.415 [Default:CheckIn:D] Stopped
2023-12-11T18:36:57.415Z,1702319817.415 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:36:57.792Z,1702319817.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.845890 min 
2023-12-11T18:36:57.792Z,1702319817.792 [Default:CheckIn:E] Stopped
2023-12-11T18:36:57.793Z,1702319817.793 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:36:57.793Z,1702319817.793 [Default:CheckIn] Stopped
2023-12-11T18:36:57.793Z,1702319817.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:36:57.793Z,1702319817.793 [Default:CheckIn](INFO): Running loop #15
2023-12-11T18:36:57.793Z,1702319817.793 [Default:CheckIn] Running Loop=15
2023-12-11T18:36:57.793Z,1702319817.793 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:36:57.793Z,1702319817.793 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:36:59.802Z,1702319819.802 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183659.00,A,3648.13958,N,12147.23078,W,0.039,0.00,111223,,,D*71
2023-12-11T18:36:59.805Z,1702319819.805 [NAL9602](INFO): GPS fix at 20231211T183659: (36.802326, -121.787180)
2023-12-11T18:36:59.817Z,1702319819.817 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:36:59.817Z,1702319819.817 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:37:07.940Z,1702319827.940 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0052.lzma
2023-12-11T18:37:08.943Z,1702319828.943 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0052.lzma.bak
2023-12-11T18:37:08.943Z,1702319828.943 [DataOverHttps](INFO): SBD MOMSN=19100858
2023-12-11T18:37:14.751Z,1702319834.751 [NAL9602](INFO): SBD MO Status=0, MOMSN=34164, MT Status=0, MTMSN=0
2023-12-11T18:37:14.751Z,1702319834.751 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:37:25.316Z,1702319845.316 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20231211T170512/Express0053.lzma
2023-12-11T18:37:26.319Z,1702319846.319 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0053.lzma.bak
2023-12-11T18:37:26.319Z,1702319846.319 [DataOverHttps](INFO): SBD MOMSN=19100861
2023-12-11T18:37:27.362Z,1702319847.362 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:37:27.362Z,1702319847.362 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:37:27.362Z,1702319847.362 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:37:45.450Z,1702319865.450 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:42:28.036Z,1702320148.036 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:42:28.036Z,1702320148.036 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:42:28.036Z,1702320148.036 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:42:28.036Z,1702320148.036 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:42:28.440Z,1702320148.440 [Default:CheckIn:D] Stopped
2023-12-11T18:42:28.440Z,1702320148.440 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:42:28.801Z,1702320148.801 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.362980 min 
2023-12-11T18:42:28.805Z,1702320148.805 [Default:CheckIn:E] Stopped
2023-12-11T18:42:28.805Z,1702320148.805 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:42:28.805Z,1702320148.805 [Default:CheckIn] Stopped
2023-12-11T18:42:28.805Z,1702320148.805 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:42:28.805Z,1702320148.805 [Default:CheckIn](INFO): Running loop #16
2023-12-11T18:42:28.805Z,1702320148.805 [Default:CheckIn] Running Loop=16
2023-12-11T18:42:28.805Z,1702320148.805 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:42:28.805Z,1702320148.805 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:42:30.808Z,1702320150.808 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184230.00,A,3648.13756,N,12147.23256,W,0.019,0.00,111223,,,D*71
2023-12-11T18:42:30.810Z,1702320150.810 [NAL9602](INFO): GPS fix at 20231211T184230: (36.802293, -121.787209)
2023-12-11T18:42:30.842Z,1702320150.842 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:42:30.842Z,1702320150.842 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:42:38.996Z,1702320158.996 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0055.lzma
2023-12-11T18:42:39.998Z,1702320159.998 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0055.lzma.bak
2023-12-11T18:42:39.998Z,1702320159.998 [DataOverHttps](INFO): SBD MOMSN=19100873
2023-12-11T18:42:53.435Z,1702320173.435 [NAL9602](INFO): SBD MO Status=0, MOMSN=34165, MT Status=0, MTMSN=0
2023-12-11T18:42:53.435Z,1702320173.435 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:42:56.337Z,1702320176.337 [DataOverHttps](INFO): Sending 580 bytes from file Logs/20231211T170512/Express0056.lzma
2023-12-11T18:42:57.338Z,1702320177.338 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0056.lzma.bak
2023-12-11T18:42:57.338Z,1702320177.338 [DataOverHttps](INFO): SBD MOMSN=19100877
2023-12-11T18:42:58.705Z,1702320178.705 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:42:58.705Z,1702320178.705 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:42:58.705Z,1702320178.705 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:43:24.133Z,1702320204.133 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:47:59.410Z,1702320479.410 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:47:59.410Z,1702320479.410 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:47:59.410Z,1702320479.410 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:47:59.410Z,1702320479.410 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:47:59.745Z,1702320479.745 [Default:CheckIn:D] Stopped
2023-12-11T18:47:59.745Z,1702320479.745 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:48:00.165Z,1702320480.165 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.884725 min 
2023-12-11T18:48:00.165Z,1702320480.165 [Default:CheckIn:E] Stopped
2023-12-11T18:48:00.165Z,1702320480.165 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:48:00.166Z,1702320480.166 [Default:CheckIn] Stopped
2023-12-11T18:48:00.166Z,1702320480.166 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:48:00.166Z,1702320480.166 [Default:CheckIn](INFO): Running loop #17
2023-12-11T18:48:00.166Z,1702320480.166 [Default:CheckIn] Running Loop=17
2023-12-11T18:48:00.166Z,1702320480.166 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:48:00.166Z,1702320480.166 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:48:02.147Z,1702320482.147 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184801.00,A,3648.13867,N,12147.23102,W,0.039,0.00,111223,,,D*74
2023-12-11T18:48:02.149Z,1702320482.149 [NAL9602](INFO): GPS fix at 20231211T184801: (36.802311, -121.787184)
2023-12-11T18:48:02.169Z,1702320482.169 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:48:02.169Z,1702320482.169 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:48:03.026Z,1702320483.026 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:48:03.026Z,1702320483.026 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:48:03.026Z,1702320483.026 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:48:18.380Z,1702320498.380 [NAL9602](INFO): SBD MO Status=0, MOMSN=34166, MT Status=0, MTMSN=0
2023-12-11T18:48:18.380Z,1702320498.380 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:48:49.077Z,1702320529.077 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:53:03.699Z,1702320783.699 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:53:03.699Z,1702320783.699 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:53:03.699Z,1702320783.699 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:53:03.699Z,1702320783.699 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:53:04.072Z,1702320784.072 [Default:CheckIn:D] Stopped
2023-12-11T18:53:04.072Z,1702320784.072 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:53:04.505Z,1702320784.505 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.956836 min 
2023-12-11T18:53:04.505Z,1702320784.505 [Default:CheckIn:E] Stopped
2023-12-11T18:53:04.505Z,1702320784.505 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:53:04.505Z,1702320784.505 [Default:CheckIn] Stopped
2023-12-11T18:53:04.505Z,1702320784.505 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:53:04.505Z,1702320784.505 [Default:CheckIn](INFO): Running loop #18
2023-12-11T18:53:04.506Z,1702320784.506 [Default:CheckIn] Running Loop=18
2023-12-11T18:53:04.506Z,1702320784.506 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:53:04.506Z,1702320784.506 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:53:06.476Z,1702320786.476 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185305.00,A,3648.13686,N,12147.23120,W,0.097,142.18,111223,,,D*71
2023-12-11T18:53:06.478Z,1702320786.478 [NAL9602](INFO): GPS fix at 20231211T185305: (36.802281, -121.787187)
2023-12-11T18:53:06.490Z,1702320786.490 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:53:06.490Z,1702320786.490 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:53:13.896Z,1702320793.896 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0058.lzma
2023-12-11T18:53:14.898Z,1702320794.898 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0058.lzma.bak
2023-12-11T18:53:14.898Z,1702320794.898 [DataOverHttps](INFO): SBD MOMSN=19100897
2023-12-11T18:53:31.147Z,1702320811.147 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0061.lzma
2023-12-11T18:53:31.925Z,1702320811.925 [NAL9602](INFO): SBD MO Status=0, MOMSN=34167, MT Status=0, MTMSN=0
2023-12-11T18:53:31.925Z,1702320811.925 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:53:32.150Z,1702320812.150 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0061.lzma.bak
2023-12-11T18:53:32.150Z,1702320812.150 [DataOverHttps](INFO): SBD MOMSN=19100900
2023-12-11T18:53:48.549Z,1702320828.549 [DataOverHttps](INFO): Sending 863 bytes from file Logs/20231211T170512/Express0059.lzma
2023-12-11T18:53:49.550Z,1702320829.550 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0059.lzma.bak
2023-12-11T18:53:49.550Z,1702320829.550 [DataOverHttps](INFO): SBD MOMSN=19100903
2023-12-11T18:54:02.645Z,1702320842.645 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:54:05.872Z,1702320845.872 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20231211T170512/Express0062.lzma
2023-12-11T18:54:06.874Z,1702320846.874 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0062.lzma.bak
2023-12-11T18:54:06.874Z,1702320846.874 [DataOverHttps](INFO): SBD MOMSN=19100906
2023-12-11T18:54:07.916Z,1702320847.916 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:54:07.916Z,1702320847.916 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:54:07.916Z,1702320847.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:59:08.552Z,1702321148.552 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:59:08.552Z,1702321148.552 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:59:08.552Z,1702321148.552 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:59:08.552Z,1702321148.552 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:59:08.952Z,1702321148.952 [Default:CheckIn:D] Stopped
2023-12-11T18:59:08.952Z,1702321148.952 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:59:09.386Z,1702321149.386 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.038167 min 
2023-12-11T18:59:09.386Z,1702321149.386 [Default:CheckIn:E] Stopped
2023-12-11T18:59:09.386Z,1702321149.386 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:59:09.386Z,1702321149.386 [Default:CheckIn] Stopped
2023-12-11T18:59:09.386Z,1702321149.386 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:59:09.386Z,1702321149.386 [Default:CheckIn](INFO): Running loop #19
2023-12-11T18:59:09.386Z,1702321149.386 [Default:CheckIn] Running Loop=19
2023-12-11T18:59:09.387Z,1702321149.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:59:09.387Z,1702321149.387 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:59:11.367Z,1702321151.367 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185910.00,A,3648.13729,N,12147.23168,W,0.078,142.18,111223,,,D*76
2023-12-11T18:59:11.370Z,1702321151.370 [NAL9602](INFO): GPS fix at 20231211T185910: (36.802288, -121.787195)
2023-12-11T18:59:11.418Z,1702321151.418 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:59:11.418Z,1702321151.418 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:59:18.988Z,1702321158.988 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0064.lzma
2023-12-11T18:59:19.990Z,1702321159.990 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0064.lzma.bak
2023-12-11T18:59:19.990Z,1702321159.990 [DataOverHttps](INFO): SBD MOMSN=19100917
2023-12-11T18:59:25.511Z,1702321165.511 [NAL9602](INFO): SBD MO Status=0, MOMSN=34168, MT Status=0, MTMSN=0
2023-12-11T18:59:25.511Z,1702321165.511 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:59:36.396Z,1702321176.396 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20231211T170512/Express0065.lzma
2023-12-11T18:59:37.398Z,1702321177.398 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0065.lzma.bak
2023-12-11T18:59:37.398Z,1702321177.398 [DataOverHttps](INFO): SBD MOMSN=19100920
2023-12-11T18:59:38.467Z,1702321178.467 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:59:38.467Z,1702321178.467 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:59:38.467Z,1702321178.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:59:56.213Z,1702321196.213 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:04:39.083Z,1702321479.083 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:04:39.083Z,1702321479.083 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:04:39.083Z,1702321479.083 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:04:39.083Z,1702321479.083 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:04:39.533Z,1702321479.533 [Default:CheckIn:D] Stopped
2023-12-11T19:04:39.533Z,1702321479.533 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:04:39.897Z,1702321479.897 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.547852 min 
2023-12-11T19:04:39.898Z,1702321479.898 [Default:CheckIn:E] Stopped
2023-12-11T19:04:39.898Z,1702321479.898 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:04:39.898Z,1702321479.898 [Default:CheckIn] Stopped
2023-12-11T19:04:39.898Z,1702321479.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:04:39.898Z,1702321479.898 [Default:CheckIn](INFO): Running loop #20
2023-12-11T19:04:39.898Z,1702321479.898 [Default:CheckIn] Running Loop=20
2023-12-11T19:04:39.898Z,1702321479.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:04:39.898Z,1702321479.898 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:04:41.910Z,1702321481.910 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190441.00,A,3648.14225,N,12147.23133,W,0.058,142.18,111223,,,D*79
2023-12-11T19:04:41.912Z,1702321481.912 [NAL9602](INFO): GPS fix at 20231211T190441: (36.802371, -121.787189)
2023-12-11T19:04:41.924Z,1702321481.924 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:04:41.924Z,1702321481.924 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:04:49.520Z,1702321489.520 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0067.lzma
2023-12-11T19:04:50.522Z,1702321490.522 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0067.lzma.bak
2023-12-11T19:04:50.522Z,1702321490.522 [DataOverHttps](INFO): SBD MOMSN=19100931
2023-12-11T19:04:54.835Z,1702321494.835 [NAL9602](INFO): SBD MO Status=0, MOMSN=34169, MT Status=0, MTMSN=0
2023-12-11T19:04:54.835Z,1702321494.835 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:05:06.912Z,1702321506.912 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20231211T170512/Express0068.lzma
2023-12-11T19:05:07.911Z,1702321507.911 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0068.lzma.bak
2023-12-11T19:05:07.911Z,1702321507.911 [DataOverHttps](INFO): SBD MOMSN=19100934
2023-12-11T19:05:08.984Z,1702321508.984 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:05:08.984Z,1702321508.984 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:05:08.984Z,1702321508.984 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:05:25.534Z,1702321525.534 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:10:09.656Z,1702321809.656 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:10:09.656Z,1702321809.656 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:10:09.656Z,1702321809.656 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:10:09.656Z,1702321809.656 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:10:10.040Z,1702321810.040 [Default:CheckIn:D] Stopped
2023-12-11T19:10:10.040Z,1702321810.040 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:10:10.429Z,1702321810.429 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.056307 min 
2023-12-11T19:10:10.429Z,1702321810.429 [Default:CheckIn:E] Stopped
2023-12-11T19:10:10.430Z,1702321810.430 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:10:10.430Z,1702321810.430 [Default:CheckIn] Stopped
2023-12-11T19:10:10.430Z,1702321810.430 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:10:10.430Z,1702321810.430 [Default:CheckIn](INFO): Running loop #21
2023-12-11T19:10:10.430Z,1702321810.430 [Default:CheckIn] Running Loop=21
2023-12-11T19:10:10.430Z,1702321810.430 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:10:10.430Z,1702321810.430 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:10:12.438Z,1702321812.438 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191011.00,A,3648.14178,N,12147.23057,W,0.058,142.18,111223,,,A*74
2023-12-11T19:10:12.440Z,1702321812.440 [NAL9602](INFO): GPS fix at 20231211T191011: (36.802363, -121.787176)
2023-12-11T19:10:12.452Z,1702321812.452 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:10:12.452Z,1702321812.452 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:10:19.824Z,1702321819.824 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0070.lzma
2023-12-11T19:10:20.827Z,1702321820.827 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0070.lzma.bak
2023-12-11T19:10:20.827Z,1702321820.827 [DataOverHttps](INFO): SBD MOMSN=19100945
2023-12-11T19:10:25.759Z,1702321825.759 [NAL9602](INFO): SBD MO Status=0, MOMSN=34170, MT Status=0, MTMSN=0
2023-12-11T19:10:25.759Z,1702321825.759 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:10:37.282Z,1702321837.282 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20231211T170512/Express0071.lzma
2023-12-11T19:10:38.278Z,1702321838.278 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0071.lzma.bak
2023-12-11T19:10:38.278Z,1702321838.278 [DataOverHttps](INFO): SBD MOMSN=19100948
2023-12-11T19:10:39.528Z,1702321839.528 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:10:39.528Z,1702321839.528 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:10:39.528Z,1702321839.528 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:10:56.469Z,1702321856.469 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:15:40.149Z,1702322140.149 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:15:40.150Z,1702322140.150 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:15:40.150Z,1702322140.150 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:15:40.150Z,1702322140.150 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:15:40.564Z,1702322140.564 [Default:CheckIn:D] Stopped
2023-12-11T19:15:40.564Z,1702322140.564 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:15:40.953Z,1702322140.953 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.564771 min 
2023-12-11T19:15:40.954Z,1702322140.954 [Default:CheckIn:E] Stopped
2023-12-11T19:15:40.954Z,1702322140.954 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:15:40.954Z,1702322140.954 [Default:CheckIn] Stopped
2023-12-11T19:15:40.954Z,1702322140.954 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:15:40.954Z,1702322140.954 [Default:CheckIn](INFO): Running loop #22
2023-12-11T19:15:40.954Z,1702322140.954 [Default:CheckIn] Running Loop=22
2023-12-11T19:15:40.954Z,1702322140.954 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:15:40.954Z,1702322140.954 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:15:42.959Z,1702322142.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191542.00,A,3648.13751,N,12147.23077,W,0.058,176.73,111223,,,A*75
2023-12-11T19:15:42.962Z,1702322142.962 [NAL9602](INFO): GPS fix at 20231211T191542: (36.802292, -121.787179)
2023-12-11T19:15:42.973Z,1702322142.973 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:15:42.973Z,1702322142.973 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:15:50.848Z,1702322150.848 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231211T170512/Courier0073.lzma
2023-12-11T19:15:51.850Z,1702322151.850 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0073.lzma.bak
2023-12-11T19:15:51.850Z,1702322151.850 [DataOverHttps](INFO): SBD MOMSN=19100959
2023-12-11T19:16:00.735Z,1702322160.735 [NAL9602](INFO): SBD MO Status=0, MOMSN=34171, MT Status=0, MTMSN=0
2023-12-11T19:16:00.735Z,1702322160.735 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:16:08.184Z,1702322168.184 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231211T170512/Express0074.lzma
2023-12-11T19:16:09.186Z,1702322169.186 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0074.lzma.bak
2023-12-11T19:16:09.186Z,1702322169.186 [DataOverHttps](INFO): SBD MOMSN=19100962
2023-12-11T19:16:10.445Z,1702322170.445 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:16:10.445Z,1702322170.445 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:16:10.445Z,1702322170.445 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:16:31.493Z,1702322191.493 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:21:11.134Z,1702322471.134 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:21:11.134Z,1702322471.134 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:21:11.134Z,1702322471.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:21:11.134Z,1702322471.134 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:21:11.576Z,1702322471.576 [Default:CheckIn:D] Stopped
2023-12-11T19:21:11.576Z,1702322471.576 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:21:11.939Z,1702322471.939 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.081909 min 
2023-12-11T19:21:11.939Z,1702322471.939 [Default:CheckIn:E] Stopped
2023-12-11T19:21:11.939Z,1702322471.939 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:21:11.939Z,1702322471.939 [Default:CheckIn] Stopped
2023-12-11T19:21:11.939Z,1702322471.939 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:21:11.939Z,1702322471.939 [Default:CheckIn](INFO): Running loop #23
2023-12-11T19:21:11.939Z,1702322471.939 [Default:CheckIn] Running Loop=23
2023-12-11T19:21:11.939Z,1702322471.939 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:21:11.939Z,1702322471.939 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:21:13.961Z,1702322473.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192113.00,A,3648.13591,N,12147.22914,W,0.019,176.73,111223,,,A*70
2023-12-11T19:21:13.963Z,1702322473.963 [NAL9602](INFO): GPS fix at 20231211T192113: (36.802265, -121.787152)
2023-12-11T19:21:13.985Z,1702322473.985 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:21:13.985Z,1702322473.985 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:21:21.631Z,1702322481.631 [NAL9602](INFO): SBD MO Status=0, MOMSN=34172, MT Status=0, MTMSN=0
2023-12-11T19:21:21.631Z,1702322481.631 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:21:22.095Z,1702322482.095 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0076.lzma
2023-12-11T19:21:23.098Z,1702322483.098 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0076.lzma.bak
2023-12-11T19:21:23.098Z,1702322483.098 [DataOverHttps](INFO): SBD MOMSN=19100977
2023-12-11T19:21:39.484Z,1702322499.484 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231211T170512/Express0077.lzma
2023-12-11T19:21:40.486Z,1702322500.486 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0077.lzma.bak
2023-12-11T19:21:40.486Z,1702322500.486 [DataOverHttps](INFO): SBD MOMSN=19100983
2023-12-11T19:21:41.846Z,1702322501.846 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:21:41.846Z,1702322501.846 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:21:41.846Z,1702322501.846 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:21:52.097Z,1702322512.097 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:26:42.646Z,1702322802.646 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:26:42.646Z,1702322802.646 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:26:42.647Z,1702322802.647 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:26:42.647Z,1702322802.647 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:26:43.014Z,1702322803.014 [Default:CheckIn:D] Stopped
2023-12-11T19:26:43.014Z,1702322803.014 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:26:43.433Z,1702322803.433 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.605876 min 
2023-12-11T19:26:43.433Z,1702322803.433 [Default:CheckIn:E] Stopped
2023-12-11T19:26:43.433Z,1702322803.433 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:26:43.433Z,1702322803.433 [Default:CheckIn] Stopped
2023-12-11T19:26:43.433Z,1702322803.433 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:26:43.434Z,1702322803.434 [Default:CheckIn](INFO): Running loop #24
2023-12-11T19:26:43.434Z,1702322803.434 [Default:CheckIn] Running Loop=24
2023-12-11T19:26:43.434Z,1702322803.434 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:26:43.434Z,1702322803.434 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:26:45.399Z,1702322805.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192644.00,A,3648.14929,N,12147.22013,W,0.097,176.73,111223,,,D*70
2023-12-11T19:26:45.402Z,1702322805.402 [NAL9602](INFO): GPS fix at 20231211T192644: (36.802488, -121.787002)
2023-12-11T19:26:45.455Z,1702322805.455 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:26:45.455Z,1702322805.455 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:26:53.059Z,1702322813.059 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20231211T170512/Courier0079.lzma
2023-12-11T19:26:54.062Z,1702322814.062 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0079.lzma.bak
2023-12-11T19:26:54.063Z,1702322814.063 [DataOverHttps](INFO): SBD MOMSN=19101085
2023-12-11T19:27:10.713Z,1702322830.713 [DataOverHttps](INFO): Sending 569 bytes from file Logs/20231211T170512/Express0080.lzma
2023-12-11T19:27:11.714Z,1702322831.714 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0080.lzma.bak
2023-12-11T19:27:11.714Z,1702322831.714 [DataOverHttps](INFO): SBD MOMSN=19101088
2023-12-11T19:27:12.075Z,1702322832.075 [NAL9602](INFO): SBD MO Status=0, MOMSN=34173, MT Status=0, MTMSN=0
2023-12-11T19:27:12.075Z,1702322832.075 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:27:12.879Z,1702322832.879 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-12-11T19:27:12.882Z,1702322832.882 [BPC1](INFO): Received data from all battery sticks.
2023-12-11T19:27:12.911Z,1702322832.911 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:27:12.911Z,1702322832.911 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:27:12.912Z,1702322832.912 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:27:42.773Z,1702322862.773 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:32:13.489Z,1702323133.489 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:32:13.489Z,1702323133.489 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:32:13.490Z,1702323133.490 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:32:13.490Z,1702323133.490 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:32:13.885Z,1702323133.885 [Default:CheckIn:D] Stopped
2023-12-11T19:32:13.885Z,1702323133.885 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:32:14.271Z,1702323134.271 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.120394 min 
2023-12-11T19:32:14.271Z,1702323134.271 [Default:CheckIn:E] Stopped
2023-12-11T19:32:14.271Z,1702323134.271 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:32:14.271Z,1702323134.271 [Default:CheckIn] Stopped
2023-12-11T19:32:14.271Z,1702323134.271 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:32:14.271Z,1702323134.271 [Default:CheckIn](INFO): Running loop #25
2023-12-11T19:32:14.272Z,1702323134.272 [Default:CheckIn] Running Loop=25
2023-12-11T19:32:14.272Z,1702323134.272 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:32:14.272Z,1702323134.272 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:32:16.293Z,1702323136.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193215.00,A,3648.14866,N,12147.21924,W,0.058,176.73,111223,,,D*76
2023-12-11T19:32:16.295Z,1702323136.295 [NAL9602](INFO): GPS fix at 20231211T193215: (36.802478, -121.786987)
2023-12-11T19:32:16.307Z,1702323136.307 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:32:16.307Z,1702323136.307 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:32:23.477Z,1702323143.477 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20231211T170512/Courier0082.lzma
2023-12-11T19:32:24.478Z,1702323144.478 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0082.lzma.bak
2023-12-11T19:32:24.478Z,1702323144.478 [DataOverHttps](INFO): SBD MOMSN=19101123
2023-12-11T19:32:26.787Z,1702323146.787 [NAL9602](INFO): SBD MO Status=0, MOMSN=34174, MT Status=0, MTMSN=0
2023-12-11T19:32:26.787Z,1702323146.787 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:32:41.792Z,1702323161.792 [DataOverHttps](INFO): Sending 317 bytes from file Logs/20231211T170512/Express0083.lzma
2023-12-11T19:32:42.794Z,1702323162.794 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0083.lzma.bak
2023-12-11T19:32:42.794Z,1702323162.794 [DataOverHttps](INFO): SBD MOMSN=19101126
2023-12-11T19:32:44.177Z,1702323164.177 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:32:44.177Z,1702323164.177 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:32:44.177Z,1702323164.177 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:32:57.498Z,1702323177.498 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:37:44.800Z,1702323464.800 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:37:44.800Z,1702323464.800 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:37:44.801Z,1702323464.801 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:37:44.801Z,1702323464.801 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:37:45.205Z,1702323465.205 [Default:CheckIn:D] Stopped
2023-12-11T19:37:45.205Z,1702323465.205 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:37:45.631Z,1702323465.631 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.642383 min 
2023-12-11T19:37:45.631Z,1702323465.631 [Default:CheckIn:E] Stopped
2023-12-11T19:37:45.631Z,1702323465.631 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:37:45.631Z,1702323465.631 [Default:CheckIn] Stopped
2023-12-11T19:37:45.631Z,1702323465.631 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:37:45.631Z,1702323465.631 [Default:CheckIn](INFO): Running loop #26
2023-12-11T19:37:45.632Z,1702323465.632 [Default:CheckIn] Running Loop=26
2023-12-11T19:37:45.632Z,1702323465.632 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:37:45.632Z,1702323465.632 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:37:47.615Z,1702323467.615 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193746.00,A,3648.16619,N,12147.19839,W,0.641,40.11,111223,,,D*49
2023-12-11T19:37:47.617Z,1702323467.617 [NAL9602](INFO): GPS fix at 20231211T193746: (36.802770, -121.786640)
2023-12-11T19:37:47.668Z,1702323467.668 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:37:47.668Z,1702323467.668 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:37:55.648Z,1702323475.648 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0085.lzma
2023-12-11T19:37:56.650Z,1702323476.650 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0085.lzma.bak
2023-12-11T19:37:56.650Z,1702323476.650 [DataOverHttps](INFO): SBD MOMSN=19101138
2023-12-11T19:38:02.991Z,1702323482.991 [NAL9602](INFO): SBD MO Status=0, MOMSN=34175, MT Status=0, MTMSN=0
2023-12-11T19:38:02.991Z,1702323482.991 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:38:17.244Z,1702323497.244 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20231211T170512/Express0086.lzma
2023-12-11T19:38:18.238Z,1702323498.238 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0086.lzma.bak
2023-12-11T19:38:18.238Z,1702323498.238 [DataOverHttps](INFO): SBD MOMSN=19101141
2023-12-11T19:38:19.601Z,1702323499.601 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:38:19.601Z,1702323499.601 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:38:19.601Z,1702323499.601 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:38:33.702Z,1702323513.702 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:43:20.179Z,1702323800.179 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:43:20.179Z,1702323800.179 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:43:20.179Z,1702323800.179 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:43:20.179Z,1702323800.179 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:43:20.589Z,1702323800.589 [Default:CheckIn:D] Stopped
2023-12-11T19:43:20.589Z,1702323800.589 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:43:20.982Z,1702323800.982 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.232129 min 
2023-12-11T19:43:20.982Z,1702323800.982 [Default:CheckIn:E] Stopped
2023-12-11T19:43:20.982Z,1702323800.982 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:43:20.982Z,1702323800.982 [Default:CheckIn] Stopped
2023-12-11T19:43:20.982Z,1702323800.982 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:43:20.982Z,1702323800.982 [Default:CheckIn](INFO): Running loop #27
2023-12-11T19:43:20.982Z,1702323800.982 [Default:CheckIn] Running Loop=27
2023-12-11T19:43:20.983Z,1702323800.983 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:43:20.983Z,1702323800.983 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:43:22.995Z,1702323802.995 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194322.00,A,3648.43160,N,12147.11286,W,3.110,358.85,111223,,,D*70
2023-12-11T19:43:22.998Z,1702323802.998 [NAL9602](INFO): GPS fix at 20231211T194322: (36.807193, -121.785214)
2023-12-11T19:43:23.009Z,1702323803.009 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:43:23.009Z,1702323803.009 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:43:30.560Z,1702323810.560 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0088.lzma
2023-12-11T19:43:31.562Z,1702323811.562 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0088.lzma.bak
2023-12-11T19:43:31.562Z,1702323811.562 [DataOverHttps](INFO): SBD MOMSN=19101152
2023-12-11T19:43:37.547Z,1702323817.547 [NAL9602](INFO): SBD MO Status=0, MOMSN=34176, MT Status=0, MTMSN=0
2023-12-11T19:43:37.547Z,1702323817.547 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:43:47.988Z,1702323827.988 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20231211T170512/Express0089.lzma
2023-12-11T19:43:48.991Z,1702323828.991 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0089.lzma.bak
2023-12-11T19:43:48.991Z,1702323828.991 [DataOverHttps](INFO): SBD MOMSN=19101155
2023-12-11T19:43:50.078Z,1702323830.078 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:43:50.079Z,1702323830.079 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:43:50.079Z,1702323830.079 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:44:08.241Z,1702323848.241 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:48:50.698Z,1702324130.698 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:48:50.698Z,1702324130.698 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:48:50.698Z,1702324130.698 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:48:50.698Z,1702324130.698 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:48:51.101Z,1702324131.101 [Default:CheckIn:D] Stopped
2023-12-11T19:48:51.101Z,1702324131.101 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:48:51.529Z,1702324131.529 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.740641 min 
2023-12-11T19:48:51.529Z,1702324131.529 [Default:CheckIn:E] Stopped
2023-12-11T19:48:51.529Z,1702324131.529 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:48:51.529Z,1702324131.529 [Default:CheckIn] Stopped
2023-12-11T19:48:51.529Z,1702324131.529 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:48:51.529Z,1702324131.529 [Default:CheckIn](INFO): Running loop #28
2023-12-11T19:48:51.529Z,1702324131.529 [Default:CheckIn] Running Loop=28
2023-12-11T19:48:51.529Z,1702324131.529 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:48:51.529Z,1702324131.529 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:48:53.515Z,1702324133.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194852.00,A,3648.31615,N,12147.62624,W,9.097,259.04,111223,,,D*79
2023-12-11T19:48:53.528Z,1702324133.528 [NAL9602](INFO): GPS fix at 20231211T194852: (36.805269, -121.793771)
2023-12-11T19:48:53.560Z,1702324133.560 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:48:53.560Z,1702324133.560 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:49:00.996Z,1702324140.996 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T170512/Courier0091.lzma
2023-12-11T19:49:01.998Z,1702324141.998 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0091.lzma.bak
2023-12-11T19:49:01.998Z,1702324141.998 [DataOverHttps](INFO): SBD MOMSN=19101167
2023-12-11T19:49:02.812Z,1702324142.812 [NAL9602](INFO): SBD MO Status=0, MOMSN=34177, MT Status=0, MTMSN=0
2023-12-11T19:49:02.812Z,1702324142.812 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:49:18.425Z,1702324158.425 [DataOverHttps](INFO): Sending 347 bytes from file Logs/20231211T170512/Express0092.lzma
2023-12-11T19:49:19.444Z,1702324159.444 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0092.lzma.bak
2023-12-11T19:49:19.444Z,1702324159.444 [DataOverHttps](INFO): SBD MOMSN=19101170
2023-12-11T19:49:20.596Z,1702324160.596 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:49:20.596Z,1702324160.596 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:49:20.596Z,1702324160.596 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:49:33.511Z,1702324173.511 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:54:21.303Z,1702324461.303 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:54:21.303Z,1702324461.303 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:54:21.303Z,1702324461.303 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:54:21.303Z,1702324461.303 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:54:21.762Z,1702324461.762 [Default:CheckIn:D] Stopped
2023-12-11T19:54:21.762Z,1702324461.762 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:54:22.069Z,1702324462.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.251660 min 
2023-12-11T19:54:22.069Z,1702324462.069 [Default:CheckIn:E] Stopped
2023-12-11T19:54:22.069Z,1702324462.069 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:54:22.069Z,1702324462.069 [Default:CheckIn] Stopped
2023-12-11T19:54:22.069Z,1702324462.069 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:54:22.070Z,1702324462.070 [Default:CheckIn](INFO): Running loop #29
2023-12-11T19:54:22.070Z,1702324462.070 [Default:CheckIn] Running Loop=29
2023-12-11T19:54:22.070Z,1702324462.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:54:22.070Z,1702324462.070 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:54:24.065Z,1702324464.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195423.00,A,3648.07813,N,12149.13501,W,12.382,258.71,111223,,,D*4D
2023-12-11T19:54:24.067Z,1702324464.067 [NAL9602](INFO): GPS fix at 20231211T195423: (36.801302, -121.818917)
2023-12-11T19:54:24.087Z,1702324464.087 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:54:24.087Z,1702324464.087 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:54:24.927Z,1702324464.927 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:54:24.927Z,1702324464.927 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:54:24.928Z,1702324464.928 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:54:46.319Z,1702324486.319 [NAL9602](INFO): SBD MO Status=0, MOMSN=34178, MT Status=0, MTMSN=0
2023-12-11T19:54:46.319Z,1702324486.319 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:55:17.025Z,1702324517.025 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:58:30.661Z,1702324710.661 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.070889
2023-12-11T19:59:04.804Z,1702324744.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T19:59:25.523Z,1702324765.523 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:59:25.524Z,1702324765.524 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:59:25.524Z,1702324765.524 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:59:25.524Z,1702324765.524 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:59:25.897Z,1702324765.897 [Default:CheckIn:D] Stopped
2023-12-11T19:59:25.897Z,1702324765.897 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:59:26.305Z,1702324766.305 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 170.320589 min 
2023-12-11T19:59:26.305Z,1702324766.305 [Default:CheckIn:E] Stopped
2023-12-11T19:59:26.305Z,1702324766.305 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:59:26.305Z,1702324766.305 [Default:CheckIn] Stopped
2023-12-11T19:59:26.305Z,1702324766.305 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:59:26.305Z,1702324766.305 [Default:CheckIn](INFO): Running loop #30
2023-12-11T19:59:26.305Z,1702324766.305 [Default:CheckIn] Running Loop=30
2023-12-11T19:59:26.305Z,1702324766.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:59:26.305Z,1702324766.305 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:59:28.311Z,1702324768.311 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195927.00,A,3647.89510,N,12150.44449,W,11.702,267.61,111223,,,A*43
2023-12-11T19:59:28.314Z,1702324768.314 [NAL9602](INFO): GPS fix at 20231211T195927: (36.798252, -121.840742)
2023-12-11T19:59:28.326Z,1702324768.326 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:59:28.326Z,1702324768.326 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:59:39.944Z,1702324779.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T19:59:48.567Z,1702324788.567 [NAL9602](INFO): SBD MO Status=1, MOMSN=34179, MT Status=0, MTMSN=0
2023-12-11T19:59:48.616Z,1702324788.616 [NAL9602](INFO): Sent 73 bytes from file Logs/20231211T170512/Courier0094.lzma
2023-12-11T19:59:48.617Z,1702324788.617 [NAL9602](INFO): Packets left to send: 0
2023-12-11T19:59:54.301Z,1702324794.301 [NAL9602](INFO): SBD MO Status=1, MOMSN=34180, MT Status=0, MTMSN=0
2023-12-11T19:59:54.356Z,1702324794.356 [NAL9602](INFO): Sent 73 bytes from file Logs/20231211T170512/Courier0097.lzma
2023-12-11T19:59:54.357Z,1702324794.357 [NAL9602](INFO): Packets left to send: 0
2023-12-11T20:00:07.315Z,1702324807.315 [NAL9602](INFO): SBD MO Status=1, MOMSN=34181, MT Status=0, MTMSN=0
2023-12-11T20:00:07.368Z,1702324807.368 [NAL9602](INFO): Sent 332 bytes from file Logs/20231211T170512/Express0095.lzma
2023-12-11T20:00:07.369Z,1702324807.369 [NAL9602](INFO): Packets left to send: 1
2023-12-11T20:00:13.428Z,1702324813.428 [NAL9602](INFO): SBD MO Status=1, MOMSN=34182, MT Status=0, MTMSN=0
2023-12-11T20:00:13.488Z,1702324813.488 [NAL9602](INFO): Sent 17 bytes from file Logs/20231211T170512/Express0095.lzma
2023-12-11T20:00:13.489Z,1702324813.489 [NAL9602](INFO): Packets left to send: 0
2023-12-11T20:00:15.096Z,1702324815.096 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T20:00:23.603Z,1702324823.603 [NAL9602](INFO): SBD MO Status=1, MOMSN=34183, MT Status=0, MTMSN=0
2023-12-11T20:00:23.652Z,1702324823.652 [NAL9602](INFO): Sent 332 bytes from file Logs/20231211T170512/Express0098.lzma
2023-12-11T20:00:23.653Z,1702324823.653 [NAL9602](INFO): Packets left to send: 1
2023-12-11T20:00:33.431Z,1702324833.431 [NAL9602](INFO): SBD MO Status=1, MOMSN=34184, MT Status=0, MTMSN=0
2023-12-11T20:00:33.488Z,1702324833.488 [NAL9602](INFO): Sent 72 bytes from file Logs/20231211T170512/Express0098.lzma
2023-12-11T20:00:33.489Z,1702324833.489 [NAL9602](INFO): Packets left to send: 0
2023-12-11T20:00:41.915Z,1702324841.915 [NAL9602](INFO): SBD MO Status=0, MOMSN=34185, MT Status=0, MTMSN=0
2023-12-11T20:00:42.016Z,1702324842.016 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T20:00:42.016Z,1702324842.016 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T20:00:42.016Z,1702324842.016 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T20:00:50.236Z,1702324850.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T20:01:12.617Z,1702324872.617 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T20:01:23.893Z,1702324883.893 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003248
2023-12-11T20:03:51.274Z,1702325031.274 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.708134
2023-12-11T20:04:01.115Z,1702325041.115 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005244
2023-12-11T20:05:29.660Z,1702325129.660 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23121112063332,35.0,00,   0.00,10777.40
2023-12-11T20:05:42.581Z,1702325142.581 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T20:05:42.582Z,1702325142.582 [Default:CheckIn:C.Wait] Stopped
2023-12-11T20:05:42.582Z,1702325142.582 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T20:05:42.582Z,1702325142.582 [Default:CheckIn:D] Running Loop=1
2023-12-11T20:05:42.994Z,1702325142.994 [Default:CheckIn:D] Stopped
2023-12-11T20:05:42.994Z,1702325142.994 [Default:CheckIn:E] Running Loop=1
2023-12-11T20:05:43.420Z,1702325143.420 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.605534 min 
2023-12-11T20:05:43.420Z,1702325143.420 [Default:CheckIn:E] Stopped
2023-12-11T20:05:43.420Z,1702325143.420 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T20:05:43.420Z,1702325143.420 [Default:CheckIn] Stopped
2023-12-11T20:05:43.420Z,1702325143.420 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T20:05:43.421Z,1702325143.421 [Default:CheckIn](INFO): Running loop #31
2023-12-11T20:05:43.421Z,1702325143.421 [Default:CheckIn] Running Loop=31
2023-12-11T20:05:43.421Z,1702325143.421 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T20:05:43.421Z,1702325143.421 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T20:05:45.403Z,1702325145.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200544.00,A,3647.76390,N,12150.82312,W,0.369,117.30,111223,,,D*74
2023-12-11T20:05:45.405Z,1702325145.405 [NAL9602](INFO): GPS fix at 20231211T200544: (36.796065, -121.847052)
2023-12-11T20:05:45.459Z,1702325145.459 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T20:05:45.459Z,1702325145.459 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T20:05:54.412Z,1702325154.412 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T170512/Courier0100.lzma
2023-12-11T20:06:00.750Z,1702325160.750 [NAL9602](INFO): SBD MO Status=0, MOMSN=34186, MT Status=0, MTMSN=0
2023-12-11T20:06:00.750Z,1702325160.750 [NAL9602](INFO): No messages in MT queue
2023-12-11T20:06:04.450Z,1702325164.450 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0100.lzma.bak
2023-12-11T20:06:04.450Z,1702325164.450 [DataOverHttps](INFO): SBD MOMSN=19101204
2023-12-11T20:06:25.058Z,1702325185.058 [DataOverHttps](INFO): Sending 1075 bytes from file Logs/20231211T170512/Express0101.lzma
2023-12-11T20:06:26.066Z,1702325186.066 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0101.lzma.bak
2023-12-11T20:06:26.067Z,1702325186.067 [DataOverHttps](INFO): SBD MOMSN=19101207
2023-12-11T20:06:27.125Z,1702325187.125 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T20:06:27.125Z,1702325187.125 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T20:06:27.125Z,1702325187.125 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T20:06:31.497Z,1702325191.497 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T20:11:27.748Z,1702325487.748 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T20:11:27.748Z,1702325487.748 [Default:CheckIn:C.Wait] Stopped
2023-12-11T20:11:27.748Z,1702325487.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T20:11:27.748Z,1702325487.748 [Default:CheckIn:D] Running Loop=1
2023-12-11T20:11:28.128Z,1702325488.128 [Default:CheckIn:D] Stopped
2023-12-11T20:11:28.128Z,1702325488.128 [Default:CheckIn:E] Running Loop=1
2023-12-11T20:11:28.533Z,1702325488.533 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.357780 min 
2023-12-11T20:11:28.533Z,1702325488.533 [Default:CheckIn:E] Stopped
2023-12-11T20:11:28.533Z,1702325488.533 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T20:11:28.533Z,1702325488.533 [Default:CheckIn] Stopped
2023-12-11T20:11:28.533Z,1702325488.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T20:11:28.533Z,1702325488.533 [Default:CheckIn](INFO): Running loop #32
2023-12-11T20:11:28.533Z,1702325488.533 [Default:CheckIn] Running Loop=32
2023-12-11T20:11:28.534Z,1702325488.534 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T20:11:28.534Z,1702325488.534 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T20:11:30.542Z,1702325490.542 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201129.00,A,3647.75597,N,12150.78362,W,0.661,179.26,111223,,,D*78
2023-12-11T20:11:30.544Z,1702325490.544 [NAL9602](INFO): GPS fix at 20231211T201129: (36.795933, -121.846394)
2023-12-11T20:11:30.579Z,1702325490.579 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T20:11:30.579Z,1702325490.579 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T20:11:41.056Z,1702325501.056 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0103.lzma
2023-12-11T20:11:42.058Z,1702325502.058 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0103.lzma.bak
2023-12-11T20:11:42.059Z,1702325502.059 [DataOverHttps](INFO): SBD MOMSN=19101217
2023-12-11T20:11:45.923Z,1702325505.923 [NAL9602](INFO): SBD MO Status=0, MOMSN=34187, MT Status=0, MTMSN=0
2023-12-11T20:11:45.923Z,1702325505.923 [NAL9602](INFO): No messages in MT queue
2023-12-11T20:12:12.792Z,1702325532.792 [DataOverHttps](INFO): Sending 521 bytes from file Logs/20231211T170512/Express0104.lzma
2023-12-11T20:12:13.794Z,1702325533.794 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0104.lzma.bak
2023-12-11T20:12:13.794Z,1702325533.794 [DataOverHttps](INFO): SBD MOMSN=19101228
2023-12-11T20:12:15.029Z,1702325535.029 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T20:12:15.029Z,1702325535.029 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T20:12:15.029Z,1702325535.029 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T20:12:16.626Z,1702325536.626 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T20:15:07.604Z,1702325707.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T20:15:12.625Z,1702325712.625 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255989
2023-12-11T20:16:18.949Z,1702325778.949 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003296
2023-12-11T20:16:53.089Z,1702325813.089 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T20:17:15.696Z,1702325835.696 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T20:17:15.696Z,1702325835.696 [Default:CheckIn:C.Wait] Stopped
2023-12-11T20:17:15.696Z,1702325835.696 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T20:17:15.700Z,1702325835.700 [Default:CheckIn:D] Running Loop=1
2023-12-11T20:17:16.065Z,1702325836.065 [Default:CheckIn:D] Stopped
2023-12-11T20:17:16.065Z,1702325836.065 [Default:CheckIn:E] Running Loop=1
2023-12-11T20:17:16.477Z,1702325836.477 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 188.156722 min 
2023-12-11T20:17:16.477Z,1702325836.477 [Default:CheckIn:E] Stopped
2023-12-11T20:17:16.478Z,1702325836.478 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T20:17:16.478Z,1702325836.478 [Default:CheckIn] Stopped
2023-12-11T20:17:16.478Z,1702325836.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T20:17:16.478Z,1702325836.478 [Default:CheckIn](INFO): Running loop #33
2023-12-11T20:17:16.478Z,1702325836.478 [Default:CheckIn] Running Loop=33
2023-12-11T20:17:16.478Z,1702325836.478 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T20:17:16.478Z,1702325836.478 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T20:17:18.479Z,1702325838.479 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201717.00,A,3647.70988,N,12150.80178,W,2.080,255.20,111223,,,D*7A
2023-12-11T20:17:18.481Z,1702325838.481 [NAL9602](INFO): GPS fix at 20231211T201717: (36.795165, -121.846696)
2023-12-11T20:17:18.494Z,1702325838.494 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T20:17:18.494Z,1702325838.494 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T20:17:19.331Z,1702325839.331 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T20:17:19.331Z,1702325839.331 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T20:17:19.331Z,1702325839.331 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T20:17:29.786Z,1702325849.786 [NAL9602](INFO): SBD MO Status=0, MOMSN=34188, MT Status=0, MTMSN=0
2023-12-11T20:17:29.786Z,1702325849.786 [NAL9602](INFO): No messages in MT queue
2023-12-11T20:17:49.036Z,1702325869.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T20:18:00.493Z,1702325880.493 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T20:18:07.121Z,1702325887.121 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.267410
2023-12-11T20:19:12.605Z,1702325952.605 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003167
2023-12-11T20:22:19.927Z,1702326139.927 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T20:22:19.927Z,1702326139.927 [Default:CheckIn:C.Wait] Stopped
2023-12-11T20:22:19.927Z,1702326139.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T20:22:19.927Z,1702326139.927 [Default:CheckIn:D] Running Loop=1
2023-12-11T20:22:20.334Z,1702326140.334 [Default:CheckIn:D] Stopped
2023-12-11T20:22:20.334Z,1702326140.334 [Default:CheckIn:E] Running Loop=1
2023-12-11T20:22:20.745Z,1702326140.745 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 193.227865 min 
2023-12-11T20:22:20.745Z,1702326140.745 [Default:CheckIn:E] Stopped
2023-12-11T20:22:20.745Z,1702326140.745 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T20:22:20.745Z,1702326140.745 [Default:CheckIn] Stopped
2023-12-11T20:22:20.745Z,1702326140.745 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T20:22:20.745Z,1702326140.745 [Default:CheckIn](INFO): Running loop #34
2023-12-11T20:22:20.745Z,1702326140.745 [Default:CheckIn] Running Loop=34
2023-12-11T20:22:20.745Z,1702326140.745 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T20:22:20.745Z,1702326140.745 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T20:22:22.756Z,1702326142.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202222.00,A,3647.69179,N,12150.80576,W,1.225,172.82,111223,,,D*7E
2023-12-11T20:22:22.758Z,1702326142.758 [NAL9602](INFO): GPS fix at 20231211T202222: (36.794863, -121.846763)
2023-12-11T20:22:22.771Z,1702326142.771 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T20:22:22.771Z,1702326142.771 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T20:22:30.976Z,1702326150.976 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0106.lzma
2023-12-11T20:22:31.979Z,1702326151.979 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0106.lzma.bak
2023-12-11T20:22:31.979Z,1702326151.979 [DataOverHttps](INFO): SBD MOMSN=19101292
2023-12-11T20:22:41.739Z,1702326161.739 [NAL9602](INFO): SBD MO Status=0, MOMSN=34189, MT Status=0, MTMSN=0
2023-12-11T20:22:41.739Z,1702326161.739 [NAL9602](INFO): No messages in MT queue
2023-12-11T20:22:48.344Z,1702326168.344 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T170512/Courier0109.lzma
2023-12-11T20:22:49.346Z,1702326169.346 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0109.lzma.bak
2023-12-11T20:22:49.347Z,1702326169.347 [DataOverHttps](INFO): SBD MOMSN=19101295
2023-12-11T20:23:05.994Z,1702326185.994 [DataOverHttps](INFO): Sending 1139 bytes from file Logs/20231211T170512/Express0107.lzma
2023-12-11T20:23:06.995Z,1702326186.995 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0107.lzma.bak
2023-12-11T20:23:06.995Z,1702326186.995 [DataOverHttps](INFO): SBD MOMSN=19101298
2023-12-11T20:23:12.437Z,1702326192.437 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T20:23:23.448Z,1702326203.448 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20231211T170512/Express0110.lzma
2023-12-11T20:23:24.450Z,1702326204.450 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0110.lzma.bak
2023-12-11T20:23:24.450Z,1702326204.450 [DataOverHttps](INFO): SBD MOMSN=19101301
2023-12-11T20:23:25.801Z,1702326205.801 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T20:23:25.801Z,1702326205.801 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T20:23:25.801Z,1702326205.801 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T20:28:26.539Z,1702326506.539 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T20:28:26.539Z,1702326506.539 [Default:CheckIn:C.Wait] Stopped
2023-12-11T20:28:26.539Z,1702326506.539 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T20:28:26.539Z,1702326506.539 [Default:CheckIn:D] Running Loop=1
2023-12-11T20:28:26.829Z,1702326506.829 [Default:CheckIn:D] Stopped
2023-12-11T20:28:26.830Z,1702326506.830 [Default:CheckIn:E] Running Loop=1
2023-12-11T20:28:27.227Z,1702326507.227 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 199.336133 min 
2023-12-11T20:28:27.227Z,1702326507.227 [Default:CheckIn:E] Stopped
2023-12-11T20:28:27.227Z,1702326507.227 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T20:28:27.227Z,1702326507.227 [Default:CheckIn] Stopped
2023-12-11T20:28:27.227Z,1702326507.227 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T20:28:27.238Z,1702326507.238 [Default:CheckIn](INFO): Running loop #35
2023-12-11T20:28:27.238Z,1702326507.238 [Default:CheckIn] Running Loop=35
2023-12-11T20:28:27.238Z,1702326507.238 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T20:28:27.239Z,1702326507.239 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T20:28:29.246Z,1702326509.246 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202828.00,A,3647.66817,N,12150.80759,W,1.711,82.43,111223,,,D*4E
2023-12-11T20:28:29.253Z,1702326509.253 [NAL9602](INFO): GPS fix at 20231211T202828: (36.794469, -121.846793)
2023-12-11T20:28:29.303Z,1702326509.303 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T20:28:29.303Z,1702326509.303 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T20:28:30.054Z,1702326510.054 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T20:28:30.054Z,1702326510.054 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T20:28:30.054Z,1702326510.054 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T20:28:51.858Z,1702326531.858 [NAL9602](INFO): SBD MO Status=2, MOMSN=34190, MT Status=2, MTMSN=0
2023-12-11T20:28:51.858Z,1702326531.858 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T20:29:01.983Z,1702326541.983 [NAL9602](INFO): SBD MO Status=0, MOMSN=34190, MT Status=0, MTMSN=0
2023-12-11T20:29:01.983Z,1702326541.983 [NAL9602](INFO): No messages in MT queue
2023-12-11T20:29:32.665Z,1702326572.665 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T20:30:49.205Z,1702326649.205 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.279327
2023-12-11T20:31:15.169Z,1702326675.169 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003181
2023-12-11T20:33:30.664Z,1702326810.664 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T20:33:30.665Z,1702326810.665 [Default:CheckIn:C.Wait] Stopped
2023-12-11T20:33:30.665Z,1702326810.665 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T20:33:30.665Z,1702326810.665 [Default:CheckIn:D] Running Loop=1
2023-12-11T20:33:31.080Z,1702326811.080 [Default:CheckIn:D] Stopped
2023-12-11T20:33:31.080Z,1702326811.080 [Default:CheckIn:E] Running Loop=1
2023-12-11T20:33:31.570Z,1702326811.570 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 204.406966 min 
2023-12-11T20:33:31.570Z,1702326811.570 [Default:CheckIn:E] Stopped
2023-12-11T20:33:31.570Z,1702326811.570 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T20:33:31.570Z,1702326811.570 [Default:CheckIn] Stopped
2023-12-11T20:33:31.570Z,1702326811.570 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T20:33:31.571Z,1702326811.571 [Default:CheckIn](INFO): Running loop #36
2023-12-11T20:33:31.571Z,1702326811.571 [Default:CheckIn] Running Loop=36
2023-12-11T20:33:31.571Z,1702326811.571 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T20:33:31.571Z,1702326811.571 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T20:33:33.486Z,1702326813.486 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203332.00,A,3647.67283,N,12150.79441,W,0.389,136.26,111223,,,D*7C
2023-12-11T20:33:33.489Z,1702326813.489 [NAL9602](INFO): GPS fix at 20231211T203332: (36.794547, -121.846574)
2023-12-11T20:33:33.520Z,1702326813.520 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T20:33:33.520Z,1702326813.520 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T20:33:41.640Z,1702326821.640 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T170512/Courier0112.lzma
2023-12-11T20:33:42.642Z,1702326822.642 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0112.lzma.bak
2023-12-11T20:33:42.642Z,1702326822.642 [DataOverHttps](INFO): SBD MOMSN=19101444
2023-12-11T20:33:53.322Z,1702326833.322 [NAL9602](INFO): SBD MO Status=0, MOMSN=34191, MT Status=0, MTMSN=0
2023-12-11T20:33:53.322Z,1702326833.322 [NAL9602](INFO): No messages in MT queue
2023-12-11T20:34:02.848Z,1702326842.848 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T170512/Courier0115.lzma
2023-12-11T20:34:03.850Z,1702326843.850 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0115.lzma.bak
2023-12-11T20:34:03.850Z,1702326843.850 [DataOverHttps](INFO): SBD MOMSN=19101447
2023-12-11T20:34:20.205Z,1702326860.205 [DataOverHttps](INFO): Sending 702 bytes from file Logs/20231211T170512/Express0113.lzma
2023-12-11T20:34:21.206Z,1702326861.206 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0113.lzma.bak
2023-12-11T20:34:21.207Z,1702326861.207 [DataOverHttps](INFO): SBD MOMSN=19101450
2023-12-11T20:34:23.762Z,1702326863.762 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T20:34:38.237Z,1702326878.237 [DataOverHttps](INFO): Sending 373 bytes from file Logs/20231211T170512/Express0116.lzma
2023-12-11T20:34:39.129Z,1702326879.129 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-12-11T20:34:39.132Z,1702326879.132 [BPC1](INFO): Received data from all battery sticks.
2023-12-11T20:34:39.243Z,1702326879.243 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0116.lzma.bak
2023-12-11T20:34:39.243Z,1702326879.243 [DataOverHttps](INFO): SBD MOMSN=19101453
2023-12-11T20:34:40.334Z,1702326880.334 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T20:34:40.334Z,1702326880.334 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T20:34:40.334Z,1702326880.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T20:39:40.996Z,1702327180.996 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T20:39:40.996Z,1702327180.996 [Default:CheckIn:C.Wait] Stopped
2023-12-11T20:39:40.996Z,1702327180.996 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T20:39:40.996Z,1702327180.996 [Default:CheckIn:D] Running Loop=1
2023-12-11T20:39:41.442Z,1702327181.442 [Default:CheckIn:D] Stopped
2023-12-11T20:39:41.442Z,1702327181.442 [Default:CheckIn:E] Running Loop=1
2023-12-11T20:39:41.808Z,1702327181.808 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 210.579655 min 
2023-12-11T20:39:41.808Z,1702327181.808 [Default:CheckIn:E] Stopped
2023-12-11T20:39:41.808Z,1702327181.808 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T20:39:41.808Z,1702327181.808 [Default:CheckIn] Stopped
2023-12-11T20:39:41.808Z,1702327181.808 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T20:39:41.808Z,1702327181.808 [Default:CheckIn](INFO): Running loop #37
2023-12-11T20:39:41.808Z,1702327181.808 [Default:CheckIn] Running Loop=37
2023-12-11T20:39:41.809Z,1702327181.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T20:39:41.809Z,1702327181.809 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T20:39:43.800Z,1702327183.800 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203943.00,A,3647.64375,N,12150.68901,W,0.389,107.08,111223,,,D*7C
2023-12-11T20:39:43.802Z,1702327183.802 [NAL9602](INFO): GPS fix at 20231211T203943: (36.794063, -121.844817)
2023-12-11T20:39:43.814Z,1702327183.814 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T20:39:43.814Z,1702327183.814 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T20:39:51.696Z,1702327191.696 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20231211T170512/Courier0118.lzma
2023-12-11T20:39:52.698Z,1702327192.698 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Courier0118.lzma.bak
2023-12-11T20:39:52.699Z,1702327192.699 [DataOverHttps](INFO): SBD MOMSN=19101458
2023-12-11T20:39:59.963Z,1702327199.963 [NAL9602](INFO): SBD MO Status=0, MOMSN=34192, MT Status=0, MTMSN=0
2023-12-11T20:39:59.963Z,1702327199.963 [NAL9602](INFO): No messages in MT queue
2023-12-11T20:40:09.113Z,1702327209.113 [DataOverHttps](INFO): Sending 853 bytes from file Logs/20231211T170512/Express0119.lzma
2023-12-11T20:40:10.114Z,1702327210.114 [DataOverHttps](INFO): Moved sent file to Logs/20231211T170512/Express0119.lzma.bak
2023-12-11T20:40:10.115Z,1702327210.115 [DataOverHttps](INFO): SBD MOMSN=19101461
2023-12-11T20:40:11.339Z,1702327211.339 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T20:40:11.339Z,1702327211.339 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T20:40:11.339Z,1702327211.339 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T20:40:30.664Z,1702327230.664 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T20:40:54.777Z,1702327254.777 [DataOverHttps](IMPORTANT): SBD MTMSN=20231211T204053
2023-12-11T20:41:02.288Z,1702327262.288 [DataOverHttps](INFO): Received command: restart logs