2023-07-10T14:58:31.905Z,1689001111.905 [Supervisor](DEBUG): Initializing supervisor.
2023-07-10T14:58:31.910Z,1689001111.910 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-07-10T14:58:31.910Z,1689001111.910 [SyncHandler](INFO): Protected caller Thread ID is 1026
2023-07-10T14:58:31.911Z,1689001111.911 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-07-10T14:58:31.912Z,1689001111.912 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-07-10T14:58:31.912Z,1689001111.912 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1027
2023-07-10T14:58:31.916Z,1689001111.916 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-07-10T14:58:31.934Z,1689001111.934 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-07-10T14:58:31.935Z,1689001111.935 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-07-10T14:58:31.935Z,1689001111.935 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1028
2023-07-10T14:58:31.940Z,1689001111.940 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-07-10T14:58:31.941Z,1689001111.941 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-07-10T14:58:31.941Z,1689001111.941 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1029
2023-07-10T14:58:31.943Z,1689001111.943 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-07-10T14:58:31.944Z,1689001111.944 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-07-10T14:58:31.945Z,1689001111.945 [logger ThreadHandler](INFO): Protected caller Thread ID is 1030
2023-07-10T14:58:31.948Z,1689001111.948 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-07-10T14:58:31.949Z,1689001111.949 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-07-10T14:58:31.950Z,1689001111.950 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-07-10T14:58:32.049Z,1689001112.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-07-10T14:58:32.050Z,1689001112.050 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-07-10T14:58:32.775Z,1689001112.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-07-10T14:58:32.775Z,1689001112.775 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-07-10T14:58:32.999Z,1689001112.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-07-10T14:58:32.000Z,1689001113.000 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-07-10T14:58:33.079Z,1689001113.079 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-07-10T14:58:33.211Z,1689001113.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-07-10T14:58:33.212Z,1689001113.212 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-07-10T14:58:33.293Z,1689001113.293 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-07-10T14:58:33.402Z,1689001113.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-07-10T14:58:33.403Z,1689001113.403 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-07-10T14:58:33.792Z,1689001113.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-07-10T14:58:33.793Z,1689001113.793 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-07-10T14:58:34.289Z,1689001114.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-07-10T14:58:34.290Z,1689001114.290 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-07-10T14:58:34.509Z,1689001114.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-07-10T14:58:34.509Z,1689001114.509 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-07-10T14:58:34.705Z,1689001114.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-07-10T14:58:34.705Z,1689001114.705 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-07-10T14:58:35.228Z,1689001115.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-07-10T14:58:35.228Z,1689001115.228 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-07-10T14:58:35.519Z,1689001115.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-07-10T14:58:35.519Z,1689001115.519 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-07-10T14:58:35.741Z,1689001115.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-07-10T14:58:35.742Z,1689001115.742 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-07-10T14:58:36.127Z,1689001116.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-07-10T14:58:36.128Z,1689001116.128 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-07-10T14:58:36.608Z,1689001116.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-07-10T14:58:36.610Z,1689001116.610 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-07-10T14:58:36.611Z,1689001116.611 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-07-10T14:58:36.810Z,1689001116.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-07-10T14:58:37.307Z,1689001117.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-07-10T14:58:37.450Z,1689001117.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-07-10T14:58:37.631Z,1689001117.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-07-10T14:58:37.726Z,1689001117.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-07-10T14:58:37.840Z,1689001117.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-07-10T14:58:38.111Z,1689001118.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-07-10T14:58:38.112Z,1689001118.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-07-10T14:58:38.208Z,1689001118.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-07-10T14:58:38.308Z,1689001118.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-07-10T14:58:38.453Z,1689001118.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-07-10T14:58:38.555Z,1689001118.555 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-07-10T14:58:38.556Z,1689001118.556 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-07-10T14:58:38.563Z,1689001118.563 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-07-10T14:58:38.670Z,1689001118.670 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-07-10T14:58:38.670Z,1689001118.670 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-07-10T14:58:38.724Z,1689001118.724 [VerticalControl](DEBUG): Construct VerticalControl.
2023-07-10T14:58:38.786Z,1689001118.786 [VerticalControl] Loaded
2023-07-10T14:58:38.786Z,1689001118.786 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-07-10T14:58:38.789Z,1689001118.789 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-07-10T14:58:38.831Z,1689001118.831 [HorizontalControl] Loaded
2023-07-10T14:58:38.832Z,1689001118.832 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-07-10T14:58:38.834Z,1689001118.834 [SpeedControl](DEBUG): Construct SpeedControl.
2023-07-10T14:58:38.837Z,1689001118.837 [SpeedControl] Loaded
2023-07-10T14:58:38.838Z,1689001118.838 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-07-10T14:58:38.840Z,1689001118.840 [LoopControl](DEBUG): Construct LoopControl.
2023-07-10T14:58:38.841Z,1689001118.841 [LoopControl] Loaded
2023-07-10T14:58:38.841Z,1689001118.841 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-07-10T14:58:38.842Z,1689001118.842 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-07-10T14:58:38.842Z,1689001118.842 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-07-10T14:58:38.893Z,1689001118.893 [DepthRateCalculator] Loaded
2023-07-10T14:58:38.894Z,1689001118.894 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-07-10T14:58:38.899Z,1689001118.899 [PitchRateCalculator] Loaded
2023-07-10T14:58:38.899Z,1689001118.899 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-07-10T14:58:38.909Z,1689001118.909 [SpeedCalculator] Loaded
2023-07-10T14:58:38.909Z,1689001118.909 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-07-10T14:58:38.914Z,1689001118.914 [YawRateCalculator] Loaded
2023-07-10T14:58:38.914Z,1689001118.914 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-07-10T14:58:38.933Z,1689001118.933 [ElevatorOffsetCalculator] Loaded
2023-07-10T14:58:38.933Z,1689001118.933 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-07-10T14:58:38.934Z,1689001118.934 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-07-10T14:58:38.934Z,1689001118.934 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-07-10T14:58:38.961Z,1689001118.961 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-07-10T14:58:38.962Z,1689001118.962 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-07-10T14:58:39.021Z,1689001119.021 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-07-10T14:58:39.021Z,1689001119.021 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-07-10T14:58:39.349Z,1689001119.349 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-07-10T14:58:39.350Z,1689001119.350 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-07-10T14:58:39.507Z,1689001119.507 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-07-10T14:58:39.508Z,1689001119.508 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-07-10T14:58:39.974Z,1689001119.974 [AHRS_M2] Loaded
2023-07-10T14:58:39.974Z,1689001119.974 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-07-10T14:58:40.009Z,1689001120.009 [BackseatComponent] Loaded
2023-07-10T14:58:40.010Z,1689001120.010 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-07-10T14:58:40.011Z,1689001120.011 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4098D4E0
2023-07-10T14:58:40.011Z,1689001120.011 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1112
2023-07-10T14:58:40.014Z,1689001120.014 [LcmUniversalReporter] Loaded
2023-07-10T14:58:40.014Z,1689001120.014 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-07-10T14:58:40.845Z,1689001120.845 [BPC1] Loaded
2023-07-10T14:58:40.845Z,1689001120.845 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-07-10T14:58:40.918Z,1689001120.918 [DataOverHttps] Loaded
2023-07-10T14:58:40.918Z,1689001120.918 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-07-10T14:58:40.919Z,1689001120.919 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409BD4E0
2023-07-10T14:58:40.920Z,1689001120.920 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1113
2023-07-10T14:58:40.941Z,1689001120.941 [Depth_Keller] Loaded
2023-07-10T14:58:40.941Z,1689001120.941 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-07-10T14:58:40.946Z,1689001120.946 [DropWeight] Loaded
2023-07-10T14:58:40.946Z,1689001120.946 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-07-10T14:58:41.010Z,1689001121.010 [NAL9602] Loaded
2023-07-10T14:58:41.011Z,1689001121.011 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-07-10T14:58:41.041Z,1689001121.041 [Onboard] Loaded
2023-07-10T14:58:41.041Z,1689001121.041 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-07-10T14:58:41.042Z,1689001121.042 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409ED4E0
2023-07-10T14:58:41.043Z,1689001121.043 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1114
2023-07-10T14:58:41.049Z,1689001121.049 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2023-07-10T14:58:41.060Z,1689001121.060 [PowerOnly] Loaded
2023-07-10T14:58:41.060Z,1689001121.060 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-07-10T14:58:41.072Z,1689001121.072 [Power24vConverter] Loaded
2023-07-10T14:58:41.073Z,1689001121.073 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-07-10T14:58:41.086Z,1689001121.086 [Radio_Surface] Loaded
2023-07-10T14:58:41.086Z,1689001121.086 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-07-10T14:58:41.087Z,1689001121.087 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1D4E0
2023-07-10T14:58:41.088Z,1689001121.088 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1115
2023-07-10T14:58:41.133Z,1689001121.133 [RDI_Pathfinder] Loaded
2023-07-10T14:58:41.133Z,1689001121.133 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-07-10T14:58:41.227Z,1689001121.227 [DAT] Loaded
2023-07-10T14:58:41.227Z,1689001121.227 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-07-10T14:58:41.228Z,1689001121.228 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A4D4E0
2023-07-10T14:58:41.229Z,1689001121.229 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1116
2023-07-10T14:58:41.229Z,1689001121.229 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-07-10T14:58:41.230Z,1689001121.230 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-07-10T14:58:41.345Z,1689001121.345 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-07-10T14:58:41.345Z,1689001121.345 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-07-10T14:58:41.359Z,1689001121.359 [NavChart] Loaded
2023-07-10T14:58:41.360Z,1689001121.360 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-07-10T14:58:41.366Z,1689001121.366 [UniversalFixResidualReporter] Loaded
2023-07-10T14:58:41.366Z,1689001121.366 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-07-10T14:58:41.366Z,1689001121.366 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-07-10T14:58:41.367Z,1689001121.367 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-07-10T14:58:41.517Z,1689001121.517 [SBIT](DEBUG): Construct Startup Built In Test.
2023-07-10T14:58:41.526Z,1689001121.526 [SBIT] Loaded
2023-07-10T14:58:41.526Z,1689001121.526 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-07-10T14:58:41.529Z,1689001121.529 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-07-10T14:58:41.542Z,1689001121.542 [IBIT] Loaded
2023-07-10T14:58:41.543Z,1689001121.543 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-07-10T14:58:41.549Z,1689001121.549 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-07-10T14:58:41.656Z,1689001121.656 [CBIT] Loaded
2023-07-10T14:58:41.657Z,1689001121.657 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-07-10T14:58:41.657Z,1689001121.657 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-07-10T14:58:41.657Z,1689001121.657 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-07-10T14:58:42.037Z,1689001122.037 [BuoyancyServo] Loaded
2023-07-10T14:58:42.037Z,1689001122.037 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-07-10T14:58:42.058Z,1689001122.058 [ElevatorServo] Loaded
2023-07-10T14:58:42.059Z,1689001122.059 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-07-10T14:58:42.079Z,1689001122.079 [MassServo] Loaded
2023-07-10T14:58:42.079Z,1689001122.079 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-07-10T14:58:42.098Z,1689001122.098 [RudderServo] Loaded
2023-07-10T14:58:42.098Z,1689001122.098 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-07-10T14:58:42.116Z,1689001122.116 [ThrusterServo] Loaded
2023-07-10T14:58:42.117Z,1689001122.117 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-07-10T14:58:42.117Z,1689001122.117 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-07-10T14:58:42.118Z,1689001122.118 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-07-10T14:58:42.129Z,1689001122.129 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-07-10T14:58:42.129Z,1689001122.129 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-07-10T14:58:42.317Z,1689001122.317 [CTD_Seabird] Loaded
2023-07-10T14:58:42.317Z,1689001122.317 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-07-10T14:58:42.318Z,1689001122.318 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B954E0
2023-07-10T14:58:42.319Z,1689001122.319 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1117
2023-07-10T14:58:42.339Z,1689001122.339 [PAR_Licor] Loaded
2023-07-10T14:58:42.340Z,1689001122.340 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-07-10T14:58:42.372Z,1689001122.372 [WetLabsBB2FL] Loaded
2023-07-10T14:58:42.373Z,1689001122.373 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-07-10T14:58:42.374Z,1689001122.374 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BC54E0
2023-07-10T14:58:42.374Z,1689001122.374 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1118
2023-07-10T14:58:42.395Z,1689001122.395 [WetLabsUBAT] Loaded
2023-07-10T14:58:42.395Z,1689001122.395 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-07-10T14:58:42.396Z,1689001122.396 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BF54E0
2023-07-10T14:58:42.397Z,1689001122.397 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1119
2023-07-10T14:58:42.397Z,1689001122.397 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-07-10T14:58:42.403Z,1689001122.403 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-07-10T14:58:42.406Z,1689001122.406 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-07-10T14:58:42.418Z,1689001122.418 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-07-10T14:58:42.419Z,1689001122.419 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C254E0
2023-07-10T14:58:42.419Z,1689001122.419 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1120
2023-07-10T14:58:42.424Z,1689001122.424 [Supervisor](INFO): Main Thread ID is 830
2023-07-10T14:58:42.424Z,1689001122.424 [Supervisor](DEBUG): Running supervisor.
2023-07-10T14:58:42.425Z,1689001122.425 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1121
2023-07-10T14:58:42.425Z,1689001122.425 [CommandExec](INFO): Initializing the command executive.
2023-07-10T14:58:42.427Z,1689001122.427 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1122
2023-07-10T14:58:42.430Z,1689001122.430 [controlThread ThreadHandler](INFO): Handler Thread ID is 1123
2023-07-10T14:58:42.430Z,1689001122.430 [controlThread](DEBUG): Initializing ControlThread
2023-07-10T14:58:42.431Z,1689001122.431 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-07-10T14:58:42.433Z,1689001122.433 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-07-10T14:58:42.434Z,1689001122.434 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-07-10T14:58:42.434Z,1689001122.434 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-07-10T14:58:42.434Z,1689001122.434 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-07-10T14:58:42.435Z,1689001122.435 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-07-10T14:58:42.435Z,1689001122.435 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-07-10T14:58:42.436Z,1689001122.436 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-07-10T14:58:42.436Z,1689001122.436 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-07-10T14:58:42.443Z,1689001122.443 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-07-10T14:58:42.443Z,1689001122.443 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-07-10T14:58:42.443Z,1689001122.443 [SBIT](INFO): Initialize SBIT Component.
2023-07-10T14:58:42.444Z,1689001122.444 [SBIT](IMPORTANT): git: 2023-06-26B
2023-07-10T14:58:42.444Z,1689001122.444 [SBIT](INFO): git hash: f75d2ab6bc2685676400d6978afc6d6cf710d518
2023-07-10T14:58:42.444Z,1689001122.444 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-07-10T14:58:42.446Z,1689001122.446 [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-07-10T14:58:42.447Z,1689001122.447 [SBIT](INFO): Beginning SBIT in 22.000000 seconds.
2023-07-10T14:58:42.447Z,1689001122.447 [IBIT](INFO): Initialize IBIT Component.
2023-07-10T14:58:42.449Z,1689001122.449 [CBIT](DEBUG): Initialize CBIT Component.
2023-07-10T14:58:42.450Z,1689001122.450 [logger ThreadHandler](INFO): Handler Thread ID is 1124
2023-07-10T14:58:42.460Z,1689001122.460 [CBIT](DEBUG): Initialized mux pins.
2023-07-10T14:58:42.460Z,1689001122.460 [CBIT](DEBUG): Initializing the watchdog timer.
2023-07-10T14:58:42.468Z,1689001122.468 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1125
2023-07-10T14:58:42.480Z,1689001122.480 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1126
2023-07-10T14:58:42.481Z,1689001122.481 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-07-10T14:58:42.484Z,1689001122.484 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-07-10T14:58:42.485Z,1689001122.485 [CBIT](DEBUG): Initializing heartbeat.
2023-07-10T14:58:42.492Z,1689001122.492 [Onboard ThreadHandler](INFO): Handler Thread ID is 1127
2023-07-10T14:58:42.510Z,1689001122.510 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1128
2023-07-10T14:58:42.529Z,1689001122.529 [DAT ThreadHandler](INFO): Handler Thread ID is 1129
2023-07-10T14:58:42.530Z,1689001122.530 [DAT](INFO): Powering up
2023-07-10T14:58:42.530Z,1689001122.530 [DAT](DEBUG): Initializing DAT.
2023-07-10T14:58:42.533Z,1689001122.533 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1131
2023-07-10T14:58:42.534Z,1689001122.534 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-07-10T14:58:42.537Z,1689001122.537 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1133
2023-07-10T14:58:42.540Z,1689001122.540 [WetLabsBB2FL](INFO): Powering up
2023-07-10T14:58:42.542Z,1689001122.542 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1135
2023-07-10T14:58:42.545Z,1689001122.545 [WetLabsUBAT](INFO): Powering up
2023-07-10T14:58:42.546Z,1689001122.546 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1137
2023-07-10T14:58:42.549Z,1689001122.549 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-07-10T14:58:42.549Z,1689001122.549 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-07-10T14:58:42.550Z,1689001122.550 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-07-10T14:58:42.550Z,1689001122.550 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-07-10T14:58:42.550Z,1689001122.550 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-07-10T14:58:42.550Z,1689001122.550 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-07-10T14:58:42.550Z,1689001122.550 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-07-10T14:58:42.550Z,1689001122.550 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-07-10T14:58:42.556Z,1689001122.556 [CBIT](DEBUG): Deactivating GF circuits.
2023-07-10T14:58:42.556Z,1689001122.556 [CBIT](DEBUG): Deactivating emergency mode.
2023-07-10T14:58:42.596Z,1689001122.596 [CBIT](DEBUG): Backplane powered.
2023-07-10T14:58:42.601Z,1689001122.601 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-07-10T14:58:42.622Z,1689001122.622 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-07-10T14:58:42.654Z,1689001122.654 [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-07-10T14:58:42.655Z,1689001122.655 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-07-10T14:58:42.742Z,1689001122.742 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-07-10T14:58:42.743Z,1689001122.743 [Default:A.Wait](DEBUG): Construct Wait.
2023-07-10T14:58:42.746Z,1689001122.746 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-07-10T14:58:42.777Z,1689001122.777 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-07-10T14:58:42.779Z,1689001122.779 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-07-10T14:58:42.809Z,1689001122.809 [Default:E.Execute](DEBUG): Construct Execute.
2023-07-10T14:58:42.829Z,1689001122.829 [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-07-10T14:58:42.834Z,1689001122.834 [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-07-10T14:58:42.856Z,1689001122.856 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-07-10T14:58:42.916Z,1689001122.916 [Radio_Surface](INFO): Powering up
2023-07-10T14:58:42.925Z,1689001122.925 [Depth_Keller](ERROR): Pressure or depth reading out of range: 824.648376 decibar, 0.000000 m
2023-07-10T14:58:42.927Z,1689001122.927 [PowerOnly](INFO): Powering up loadControl
2023-07-10T14:58:42.945Z,1689001122.945 [Power24vConverter](INFO): Powering up.
2023-07-10T14:58:42.946Z,1689001122.946 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-07-10T14:58:43.024Z,1689001123.024 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-07-10T14:58:43.051Z,1689001123.051 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-07-10T14:58:43.052Z,1689001123.052 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-07-10T14:58:43.054Z,1689001123.054 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-07-10T14:58:43.060Z,1689001123.060 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-07-10T14:58:43.061Z,1689001123.061 [MassServo](DEBUG): Initializing EZServoServo.
2023-07-10T14:58:43.068Z,1689001123.068 [MassServo](DEBUG): Initializing MassServo.
2023-07-10T14:58:43.069Z,1689001123.069 [RudderServo](DEBUG): Initializing EZServoServo.
2023-07-10T14:58:43.076Z,1689001123.076 [RudderServo](DEBUG): Initializing RudderServo.
2023-07-10T14:58:43.077Z,1689001123.077 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-07-10T14:58:43.084Z,1689001123.084 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-07-10T14:58:45.152Z,1689001125.152 [WetLabsBB2FL](INFO): Powering down
2023-07-10T14:58:51.792Z,1689001131.792 [WetLabsUBAT](INFO): Powering down
2023-07-10T14:58:54.629Z,1689001134.629 [DAT](INFO): DAT read: 

2023-07-10T14:58:54.630Z,1689001134.630 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-07-10T14:58:56.393Z,1689001136.393 [DAT](INFO): DAT read: MF Frequency Band

2023-07-10T14:58:56.394Z,1689001136.394 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-07-10T14:58:56.395Z,1689001136.395 [DAT](INFO): DAT read: Jul 10 2023 14:58:50 

2023-07-10T14:58:57.401Z,1689001137.401 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-07-10T14:58:57.402Z,1689001137.402 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-07-10T14:58:57.403Z,1689001137.403 [DAT](INFO): commRate: 800
2023-07-10T14:58:59.468Z,1689001139.468 [DAT](INFO): entering command mode
2023-07-10T14:58:59.669Z,1689001139.669 [DAT](INFO): DAT read: 
user:1>

2023-07-10T14:58:59.669Z,1689001139.669 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:58:59.921Z,1689001139.921 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:00.172Z,1689001140.172 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:00.424Z,1689001140.424 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:00.677Z,1689001140.677 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:00.929Z,1689001140.929 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:01.180Z,1689001141.180 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:01.433Z,1689001141.433 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:01.685Z,1689001141.685 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:01.937Z,1689001141.937 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:02.188Z,1689001142.188 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:02.442Z,1689001142.442 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:02.693Z,1689001142.693 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:02.944Z,1689001142.944 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:03.197Z,1689001143.197 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:03.448Z,1689001143.448 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:03.700Z,1689001143.700 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:03.953Z,1689001143.953 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:04.204Z,1689001144.204 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:04.458Z,1689001144.458 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:04.708Z,1689001144.708 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:04.961Z,1689001144.961 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:05.135Z,1689001145.135 [SBIT](IMPORTANT): Beginning Startup BIT
2023-07-10T14:59:05.139Z,1689001145.139 [CBIT](IMPORTANT): Beginning ground fault scan
2023-07-10T14:59:05.213Z,1689001145.213 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:05.464Z,1689001145.464 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:05.717Z,1689001145.717 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:05.968Z,1689001145.968 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:06.221Z,1689001146.221 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:06.474Z,1689001146.474 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:06.725Z,1689001146.725 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:06.977Z,1689001146.977 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:07.232Z,1689001147.232 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:07.480Z,1689001147.480 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:07.732Z,1689001147.732 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:07.992Z,1689001147.992 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:08.245Z,1689001148.245 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:08.498Z,1689001148.498 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:08.749Z,1689001148.749 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:09.001Z,1689001149.001 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:09.252Z,1689001149.252 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:09.504Z,1689001149.504 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:09.757Z,1689001149.757 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:10.008Z,1689001150.008 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:10.260Z,1689001150.260 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:10.513Z,1689001150.513 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:10.764Z,1689001150.764 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:11.017Z,1689001151.017 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:11.150Z,1689001151.150 [NAL9602](INFO): Powering up NAL9602
2023-07-10T14:59:11.269Z,1689001151.269 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:11.521Z,1689001151.521 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:11.772Z,1689001151.772 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:12.025Z,1689001152.025 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:12.277Z,1689001152.277 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:12.529Z,1689001152.529 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:12.780Z,1689001152.780 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:13.033Z,1689001153.033 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:13.285Z,1689001153.285 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:13.536Z,1689001153.536 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:13.789Z,1689001153.789 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:14.040Z,1689001154.040 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:14.292Z,1689001154.292 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:14.545Z,1689001154.545 [DAT](DEBUG): checking for command mode acknowledgment
2023-07-10T14:59:14.545Z,1689001154.545 [DAT](FAULT): failed to enter command mode
2023-07-10T14:59:14.849Z,1689001154.849 [DAT](INFO): entering command mode
2023-07-10T14:59:15.049Z,1689001155.049 [DAT](INFO): DAT read: user:1>

2023-07-10T14:59:15.050Z,1689001155.050 [DAT](INFO): DAT read: Command '+++' not found

2023-07-10T14:59:15.051Z,1689001155.051 [DAT](INFO): DAT read: Error

2023-07-10T14:59:15.051Z,1689001155.051 [DAT](INFO): setting verbose to 3
2023-07-10T14:59:15.301Z,1689001155.301 [DAT](INFO): DAT read: user:2>

2023-07-10T14:59:15.302Z,1689001155.302 [DAT](INFO): DAT read: Verbose         | 3           

2023-07-10T14:59:15.302Z,1689001155.302 [DAT](INFO): set verbose to 3
2023-07-10T14:59:15.302Z,1689001155.302 [DAT](INFO): setting DatVerbose to 27440
2023-07-10T14:59:15.553Z,1689001155.553 [DAT](INFO): DAT read: user:3>

2023-07-10T14:59:15.554Z,1689001155.554 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-07-10T14:59:15.554Z,1689001155.554 [DAT](INFO): set DatVerbose to 27440
2023-07-10T14:59:15.554Z,1689001155.554 [DAT](INFO): setting transmit power to 8
2023-07-10T14:59:15.805Z,1689001155.805 [DAT](INFO): DAT read: user:4>

2023-07-10T14:59:15.806Z,1689001155.806 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-07-10T14:59:15.806Z,1689001155.806 [DAT](INFO): set transmit power to 8
2023-07-10T14:59:15.806Z,1689001155.806 [DAT](INFO): setting local address to 10
2023-07-10T14:59:16.057Z,1689001156.057 [DAT](INFO): DAT read: user:5>

2023-07-10T14:59:16.058Z,1689001156.058 [DAT](INFO): DAT read: LocalAddr       | 10          

2023-07-10T14:59:16.058Z,1689001156.058 [DAT](INFO): set local address to 10
2023-07-10T14:59:16.059Z,1689001156.059 [DAT](INFO): Setting time to: 14:59:16 And date to:7/10/2023
2023-07-10T14:59:16.132Z,1689001156.132 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006010
CHAN A1 (24V): 0.008020
CHAN A2 (12V): -0.003871
CHAN A3 (5V): -0.000873
CHAN B0 (3.3V): 0.001217
CHAN B1 (3.15aV): 0.000672
CHAN B2 (3.15bV): 0.000919
CHAN B3 (GND): 0.001756
OPEN: 0.005418
Full Scale: +/- 1 mA
2023-07-10T14:59:16.309Z,1689001156.309 [DAT](INFO): DAT read: user:6>

2023-07-10T14:59:16.310Z,1689001156.310 [DAT](INFO): DAT read: Mon Jul 10, 2023  14:59:16  

2023-07-10T14:59:16.310Z,1689001156.310 [DAT](INFO): Local DAT time set to Mon Jul 10, 2023  14:59:16  

2023-07-10T14:59:22.086Z,1689001162.086 [NAL9602](INFO): NAL9602 initialized
2023-07-10T14:59:52.807Z,1689001192.807 [NAL9602](INFO): SBD MO Status=2, MOMSN=27576, MT Status=2, MTMSN=0
2023-07-10T14:59:52.807Z,1689001192.807 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T14:59:58.911Z,1689001198.911 [SBIT](IMPORTANT): SBIT PASSED
2023-07-10T14:59:58.911Z,1689001198.911 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-07-10T14:59:58.912Z,1689001198.912 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-07-10T14:59:58.916Z,1689001198.916 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-07-10T14:59:58.916Z,1689001198.916 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-07-10T14:59:58.916Z,1689001198.916 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=309.767475 cubic_centimeter;

2023-07-10T14:59:58.916Z,1689001198.916 [SBIT](IMPORTANT): VerticalControl.massDefault=-0.677429 millimeter;

2023-07-10T14:59:58.917Z,1689001198.917 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-07-10T14:59:59.279Z,1689001199.279 [MissionManager](IMPORTANT): Started mission Startup
2023-07-10T14:59:59.279Z,1689001199.279 [Startup] Running Loop=1
2023-07-10T14:59:59.279Z,1689001199.279 [Startup](DEBUG): Aggregate::initialize Startup
2023-07-10T14:59:59.279Z,1689001199.279 [Startup:A.GoToSurface] Running Loop=1
2023-07-10T14:59:59.279Z,1689001199.279 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-07-10T14:59:59.284Z,1689001199.284 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-07-10T14:59:59.285Z,1689001199.285 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-07-10T14:59:59.285Z,1689001199.285 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-07-10T14:59:59.285Z,1689001199.285 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-07-10T14:59:59.286Z,1689001199.286 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-07-10T14:59:59.287Z,1689001199.287 [Startup:StartupSatComms] Running Loop=1
2023-07-10T14:59:59.287Z,1689001199.287 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-07-10T14:59:59.287Z,1689001199.287 [Startup:StartupSatComms:A] Running Loop=1
2023-07-10T14:59:59.689Z,1689001199.689 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-07-10T15:00:08.554Z,1689001208.554 [NAL9602](INFO): SBD MO Status=2, MOMSN=27576, MT Status=2, MTMSN=0
2023-07-10T15:00:08.554Z,1689001208.554 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T15:00:27.943Z,1689001227.943 [NAL9602](INFO): SBD MO Status=0, MOMSN=27576, MT Status=0, MTMSN=0
2023-07-10T15:00:27.943Z,1689001227.943 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:00:29.215Z,1689001229.215 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150027.00,A,3648.14122,N,12147.22913,W,0.019,0.00,100723,,,A*75
2023-07-10T15:00:29.218Z,1689001229.218 [NAL9602](INFO): GPS fix at 20230710T150027: (36.802354, -121.787152)
2023-07-10T15:00:29.259Z,1689001229.259 [Startup:StartupSatComms:A] Stopped
2023-07-10T15:00:29.259Z,1689001229.259 [Startup:StartupSatComms:B] Running Loop=1
2023-07-10T15:00:29.658Z,1689001229.658 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-07-10T15:00:47.419Z,1689001247.419 [NAL9602](INFO): SBD MO Status=1, MOMSN=27577, MT Status=0, MTMSN=0
2023-07-10T15:00:47.473Z,1689001247.473 [NAL9602](INFO): Sent 39 bytes from file Logs/20230710T143425/Courier0013.lzma
2023-07-10T15:00:47.473Z,1689001247.473 [NAL9602](INFO): Packets left to send: 0
2023-07-10T15:00:59.627Z,1689001259.627 [NAL9602](INFO): SBD MO Status=1, MOMSN=27578, MT Status=0, MTMSN=0
2023-07-10T15:00:59.684Z,1689001259.684 [NAL9602](INFO): Sent 269 bytes from file Logs/20230710T145831/Courier0000.lzma
2023-07-10T15:00:59.684Z,1689001259.684 [NAL9602](INFO): Packets left to send: 0
2023-07-10T15:01:10.683Z,1689001270.683 [NAL9602](INFO): SBD MO Status=1, MOMSN=27579, MT Status=0, MTMSN=0
2023-07-10T15:01:10.732Z,1689001270.732 [NAL9602](INFO): Sent 111 bytes from file Logs/20230710T143425/Express0014.lzma
2023-07-10T15:01:10.732Z,1689001270.732 [NAL9602](INFO): Packets left to send: 0
2023-07-10T15:01:20.019Z,1689001280.019 [NAL9602](INFO): SBD MO Status=1, MOMSN=27580, MT Status=0, MTMSN=0
2023-07-10T15:01:20.080Z,1689001280.080 [NAL9602](INFO): Sent 332 bytes from file Logs/20230710T145831/Express0001.lzma
2023-07-10T15:01:20.080Z,1689001280.080 [NAL9602](INFO): Packets left to send: 2
2023-07-10T15:01:28.951Z,1689001288.951 [NAL9602](INFO): SBD MO Status=1, MOMSN=27581, MT Status=0, MTMSN=0
2023-07-10T15:01:29.013Z,1689001289.013 [NAL9602](INFO): Sent 332 bytes from file Logs/20230710T145831/Express0001.lzma
2023-07-10T15:01:29.013Z,1689001289.013 [NAL9602](INFO): Packets left to send: 1
2023-07-10T15:01:29.462Z,1689001289.462 [Startup:StartupSatComms:B](INFO): Timed out from 2023-07-10T15:00:29.3Z
2023-07-10T15:01:29.462Z,1689001289.462 [Startup:StartupSatComms:B] Stopped
2023-07-10T15:01:29.462Z,1689001289.462 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-07-10T15:01:29.462Z,1689001289.462 [Startup:StartupSatComms] Stopped
2023-07-10T15:01:29.462Z,1689001289.462 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-07-10T15:01:29.463Z,1689001289.463 [Startup](INFO): Completed Startup
2023-07-10T15:01:29.463Z,1689001289.463 [MissionManager](INFO): Startup is completed.
2023-07-10T15:01:29.463Z,1689001289.463 [MissionManager](INFO): Uninitializing Mission Startup
2023-07-10T15:01:29.463Z,1689001289.463 [Startup] Stopped
2023-07-10T15:01:29.463Z,1689001289.463 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-07-10T15:01:29.463Z,1689001289.463 [Startup:A.GoToSurface] Stopped
2023-07-10T15:01:29.463Z,1689001289.463 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-07-10T15:01:31.810Z,1689001291.810 [MissionManager](IMPORTANT): Started mission Default
2023-07-10T15:01:31.810Z,1689001291.810 [Default] Running Loop=1
2023-07-10T15:01:31.811Z,1689001291.811 [Default](DEBUG): Aggregate::initialize Default
2023-07-10T15:01:31.811Z,1689001291.811 [Default:B.GoToSurface] Running Loop=1
2023-07-10T15:01:31.811Z,1689001291.811 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-07-10T15:01:31.811Z,1689001291.811 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-07-10T15:01:31.811Z,1689001291.811 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-07-10T15:01:31.812Z,1689001291.812 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-07-10T15:01:31.812Z,1689001291.812 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-07-10T15:01:31.812Z,1689001291.812 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-07-10T15:01:31.813Z,1689001291.813 [Default:A.Wait] Running Loop=1
2023-07-10T15:01:31.813Z,1689001291.813 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:01:40.731Z,1689001300.731 [NAL9602](INFO): SBD MO Status=1, MOMSN=27582, MT Status=0, MTMSN=0
2023-07-10T15:01:40.780Z,1689001300.780 [NAL9602](INFO): Sent 218 bytes from file Logs/20230710T145831/Express0001.lzma
2023-07-10T15:01:40.780Z,1689001300.780 [NAL9602](INFO): Packets left to send: 0
2023-07-10T15:01:45.201Z,1689001305.201 [Default:A.Wait](INFO): Done Waiting.
2023-07-10T15:01:45.201Z,1689001305.201 [Default:A.Wait] Stopped
2023-07-10T15:01:45.201Z,1689001305.201 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:01:45.588Z,1689001305.588 [Default:CheckIn] Running Loop=1
2023-07-10T15:01:45.589Z,1689001305.589 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:01:45.589Z,1689001305.589 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:01:45.997Z,1689001305.997 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-07-10T15:01:47.600Z,1689001307.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150145.00,A,3648.14305,N,12147.22872,W,0.039,0.00,100723,,,A*73
2023-07-10T15:01:47.602Z,1689001307.602 [NAL9602](INFO): GPS fix at 20230710T150145: (36.802384, -121.787145)
2023-07-10T15:01:47.614Z,1689001307.614 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:01:47.614Z,1689001307.614 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:01:48.026Z,1689001308.026 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-07-10T15:02:06.178Z,1689001326.178 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004429
2023-07-10T15:02:12.350Z,1689001332.350 [NAL9602](INFO): SBD MO Status=1, MOMSN=27583, MT Status=0, MTMSN=0
2023-07-10T15:02:12.418Z,1689001332.418 [NAL9602](INFO): Sent 73 bytes from file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:02:12.419Z,1689001332.419 [NAL9602](INFO): Packets left to send: 0
2023-07-10T15:02:12.526Z,1689001332.526 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:02:12.526Z,1689001332.526 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:02:12.526Z,1689001332.526 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:02:13.906Z,1689001333.906 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:02:13.906Z,1689001333.906 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:02:25.878Z,1689001345.878 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:02:25.878Z,1689001345.878 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:02:43.121Z,1689001363.121 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:02:57.826Z,1689001377.826 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:02:57.826Z,1689001377.826 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:03:33.562Z,1689001413.562 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:03:33.562Z,1689001413.562 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:04:09.582Z,1689001449.582 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:04:09.582Z,1689001449.582 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:04:45.243Z,1689001485.243 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:04:45.243Z,1689001485.243 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:04:45.942Z,1689001485.942 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-07-10T15:05:06.907Z,1689001506.907 [CommandExec](IMPORTANT): got command configSet PowerOnly.sampleTime1 1 second
2023-07-10T15:05:07.346Z,1689001507.346 [PowerOnly](INFO): Powering down loadControl
2023-07-10T15:05:21.046Z,1689001521.046 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:05:21.046Z,1689001521.046 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:05:21.362Z,1689001521.362 [CommandExec](IMPORTANT): got command configSet PowerOnly.sampleTime1 0 second
2023-07-10T15:05:21.490Z,1689001521.490 [PowerOnly](INFO): Powering up loadControl
2023-07-10T15:05:30.444Z,1689001530.444 [CommandExec](IMPORTANT): got command show variable poweronly
2023-07-10T15:05:30.518Z,1689001530.518 [CommandExec](IMPORTANT): PowerOnly.loadAtStartup (bool)
2023-07-10T15:05:30.518Z,1689001530.518 [CommandExec](IMPORTANT): PowerOnly.simulateHardware (bool)
2023-07-10T15:05:30.519Z,1689001530.519 [CommandExec](IMPORTANT): PowerOnly.sampleTime (second)
2023-07-10T15:05:30.519Z,1689001530.519 [CommandExec](IMPORTANT): PowerOnly.sampleTime1 (second)
2023-07-10T15:05:30.519Z,1689001530.519 [CommandExec](IMPORTANT): PowerOnly.sampleTime2 (second)
2023-07-10T15:05:30.520Z,1689001530.520 [CommandExec](IMPORTANT): PowerOnly.sampleTime3 (second)
2023-07-10T15:05:30.628Z,1689001530.628 [CommandExec](IMPORTANT): PowerOnly.loadControl (none)
2023-07-10T15:05:30.628Z,1689001530.628 [CommandExec](IMPORTANT): PowerOnly.loadControl2 (none)
2023-07-10T15:05:30.629Z,1689001530.629 [CommandExec](IMPORTANT): PowerOnly.loadControl3 (none)
2023-07-10T15:05:30.730Z,1689001530.730 [CommandExec](IMPORTANT): PowerOnly.enableBroadcast (bool)
2023-07-10T15:05:30.730Z,1689001530.730 [CommandExec](IMPORTANT): PowerOnly.samplePowerOnly (bool)
2023-07-10T15:05:30.731Z,1689001530.731 [CommandExec](IMPORTANT): PowerOnly.component_voltage_loadControl (volt)
2023-07-10T15:05:30.731Z,1689001530.731 [CommandExec](IMPORTANT): PowerOnly.component_avgVoltage_loadControl (volt)
2023-07-10T15:05:30.732Z,1689001530.732 [CommandExec](IMPORTANT): PowerOnly.component_current_loadControl (milliampere)
2023-07-10T15:05:30.792Z,1689001530.792 [CommandExec](IMPORTANT): PowerOnly.component_avgCurrent_loadControl (milliampere)
2023-07-10T15:05:30.793Z,1689001530.793 [CommandExec](IMPORTANT): PowerOnly.sampleLoad1 (bool)
2023-07-10T15:05:45.493Z,1689001545.493 [CommandExec](IMPORTANT): got command get PowerOnly.component_avgCurrent_loadControl
2023-07-10T15:05:45.493Z,1689001545.493 [CommandExec](IMPORTANT): PowerOnly.component_avgCurrent_loadControl 7.340000 mA
2023-07-10T15:05:59.430Z,1689001559.430 [CommandExec](IMPORTANT): got command get PowerOnly.component_current_loadControl
2023-07-10T15:05:59.431Z,1689001559.431 [CommandExec](IMPORTANT): PowerOnly.component_current_loadControl 7.340000 mA
2023-07-10T15:05:59.546Z,1689001559.546 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:05:59.546Z,1689001559.546 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:06:05.129Z,1689001565.129 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2023-07-10T15:06:05.132Z,1689001565.132 [BPC1](INFO): Received data from all battery sticks.
2023-07-10T15:06:35.206Z,1689001595.206 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:06:35.206Z,1689001595.206 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:06:58.576Z,1689001618.576 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2023-07-10T15:07:08.050Z,1689001628.050 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:07:08.050Z,1689001628.050 [DataOverHttps](FAULT): Could not open file Logs/20230710T145831/Courier0004.lzma
2023-07-10T15:07:13.033Z,1689001633.033 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:07:13.033Z,1689001633.033 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:07:13.033Z,1689001633.033 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:07:13.033Z,1689001633.033 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:07:13.437Z,1689001633.437 [Default:CheckIn:D] Stopped
2023-07-10T15:07:13.437Z,1689001633.437 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:07:13.837Z,1689001633.837 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.693768 min 
2023-07-10T15:07:13.837Z,1689001633.837 [Default:CheckIn:E] Stopped
2023-07-10T15:07:13.838Z,1689001633.838 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:07:13.838Z,1689001633.838 [Default:CheckIn] Stopped
2023-07-10T15:07:13.838Z,1689001633.838 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:07:13.838Z,1689001633.838 [Default:CheckIn](INFO): Running loop #2
2023-07-10T15:07:13.838Z,1689001633.838 [Default:CheckIn] Running Loop=2
2023-07-10T15:07:13.838Z,1689001633.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:07:13.838Z,1689001633.838 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:07:15.851Z,1689001635.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150715.00,A,3648.14147,N,12147.22670,W,0.019,0.00,100723,,,A*7A
2023-07-10T15:07:15.854Z,1689001635.854 [NAL9602](INFO): GPS fix at 20230710T150715: (36.802358, -121.787112)
2023-07-10T15:07:15.897Z,1689001635.897 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:07:15.897Z,1689001635.897 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:07:23.816Z,1689001643.816 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230710T145831/Courier0007.lzma
2023-07-10T15:07:24.818Z,1689001644.818 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0007.lzma.bak
2023-07-10T15:07:24.818Z,1689001644.818 [DataOverHttps](INFO): SBD MOMSN=18458217
2023-07-10T15:07:34.843Z,1689001654.843 [NAL9602](INFO): SBD MO Status=0, MOMSN=27584, MT Status=0, MTMSN=0
2023-07-10T15:07:34.844Z,1689001654.844 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:07:41.204Z,1689001661.204 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20230710T145831/Express0005.lzma
2023-07-10T15:07:42.206Z,1689001662.206 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0005.lzma.bak
2023-07-10T15:07:42.206Z,1689001662.206 [DataOverHttps](INFO): SBD MOMSN=18458220
2023-07-10T15:07:58.441Z,1689001678.441 [DataOverHttps](INFO): Sending 657 bytes from file Logs/20230710T145831/Express0008.lzma
2023-07-10T15:07:59.442Z,1689001679.442 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0008.lzma.bak
2023-07-10T15:07:59.442Z,1689001679.442 [DataOverHttps](INFO): SBD MOMSN=18458223
2023-07-10T15:08:00.739Z,1689001680.739 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:08:00.739Z,1689001680.739 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:08:00.739Z,1689001680.739 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:08:05.537Z,1689001685.537 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:13:01.373Z,1689001981.373 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:13:01.373Z,1689001981.373 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:13:01.373Z,1689001981.373 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:13:01.373Z,1689001981.373 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:13:01.788Z,1689001981.788 [Default:CheckIn:D] Stopped
2023-07-10T15:13:01.788Z,1689001981.788 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:13:02.176Z,1689001982.176 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.499630 min 
2023-07-10T15:13:02.176Z,1689001982.176 [Default:CheckIn:E] Stopped
2023-07-10T15:13:02.176Z,1689001982.176 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:13:02.177Z,1689001982.177 [Default:CheckIn] Stopped
2023-07-10T15:13:02.177Z,1689001982.177 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:13:02.177Z,1689001982.177 [Default:CheckIn](INFO): Running loop #3
2023-07-10T15:13:02.177Z,1689001982.177 [Default:CheckIn] Running Loop=3
2023-07-10T15:13:02.177Z,1689001982.177 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:13:02.177Z,1689001982.177 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:13:04.187Z,1689001984.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151303.00,A,3648.14303,N,12147.22760,W,0.019,0.00,100723,,,A*7A
2023-07-10T15:13:04.199Z,1689001984.199 [NAL9602](INFO): GPS fix at 20230710T151303: (36.802384, -121.787127)
2023-07-10T15:13:04.211Z,1689001984.211 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:13:04.211Z,1689001984.211 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:13:11.395Z,1689001991.395 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0010.lzma
2023-07-10T15:13:12.398Z,1689001992.398 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0010.lzma.bak
2023-07-10T15:13:12.398Z,1689001992.398 [DataOverHttps](INFO): SBD MOMSN=18458270
2023-07-10T15:13:28.438Z,1689002008.438 [NAL9602](INFO): SBD MO Status=0, MOMSN=27585, MT Status=0, MTMSN=0
2023-07-10T15:13:28.439Z,1689002008.439 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:13:28.732Z,1689002008.732 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0011.lzma
2023-07-10T15:13:29.734Z,1689002009.734 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0011.lzma.bak
2023-07-10T15:13:29.734Z,1689002009.734 [DataOverHttps](INFO): SBD MOMSN=18458275
2023-07-10T15:13:30.878Z,1689002010.878 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:13:30.878Z,1689002010.878 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:13:30.878Z,1689002010.878 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:13:59.135Z,1689002039.135 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:16:48.844Z,1689002208.844 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range.
2023-07-10T15:17:18.741Z,1689002238.741 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range.
2023-07-10T15:18:31.453Z,1689002311.453 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:18:31.453Z,1689002311.453 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:18:31.453Z,1689002311.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:18:31.453Z,1689002311.453 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:18:31.860Z,1689002311.860 [Default:CheckIn:D] Stopped
2023-07-10T15:18:31.860Z,1689002311.860 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:18:32.252Z,1689002312.252 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.000823 min 
2023-07-10T15:18:32.252Z,1689002312.252 [Default:CheckIn:E] Stopped
2023-07-10T15:18:32.252Z,1689002312.252 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:18:32.252Z,1689002312.252 [Default:CheckIn] Stopped
2023-07-10T15:18:32.253Z,1689002312.253 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:18:32.253Z,1689002312.253 [Default:CheckIn](INFO): Running loop #4
2023-07-10T15:18:32.253Z,1689002312.253 [Default:CheckIn] Running Loop=4
2023-07-10T15:18:32.253Z,1689002312.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:18:32.253Z,1689002312.253 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:18:34.259Z,1689002314.259 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151833.00,A,3648.14395,N,12147.22728,W,0.000,0.00,100723,,,A*79
2023-07-10T15:18:34.261Z,1689002314.261 [NAL9602](INFO): GPS fix at 20230710T151833: (36.802399, -121.787121)
2023-07-10T15:18:34.300Z,1689002314.300 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:18:34.300Z,1689002314.300 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:18:41.863Z,1689002321.863 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0013.lzma
2023-07-10T15:18:42.866Z,1689002322.866 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0013.lzma.bak
2023-07-10T15:18:42.866Z,1689002322.866 [DataOverHttps](INFO): SBD MOMSN=18458309
2023-07-10T15:18:58.100Z,1689002338.100 [NAL9602](INFO): SBD MO Status=0, MOMSN=27586, MT Status=0, MTMSN=0
2023-07-10T15:18:58.108Z,1689002338.108 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:18:59.255Z,1689002339.255 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230710T145831/Express0014.lzma
2023-07-10T15:19:00.258Z,1689002340.258 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0014.lzma.bak
2023-07-10T15:19:00.258Z,1689002340.258 [DataOverHttps](INFO): SBD MOMSN=18458312
2023-07-10T15:19:01.348Z,1689002341.348 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:19:01.348Z,1689002341.348 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:19:01.348Z,1689002341.348 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:19:28.802Z,1689002368.802 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:24:01.993Z,1689002641.993 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:24:01.993Z,1689002641.993 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:24:01.993Z,1689002641.993 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:24:01.993Z,1689002641.993 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:24:02.396Z,1689002642.396 [Default:CheckIn:D] Stopped
2023-07-10T15:24:02.396Z,1689002642.396 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:24:02.819Z,1689002642.819 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.509764 min 
2023-07-10T15:24:02.819Z,1689002642.819 [Default:CheckIn:E] Stopped
2023-07-10T15:24:02.819Z,1689002642.819 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:24:02.819Z,1689002642.819 [Default:CheckIn] Stopped
2023-07-10T15:24:02.819Z,1689002642.819 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:24:02.819Z,1689002642.819 [Default:CheckIn](INFO): Running loop #5
2023-07-10T15:24:02.819Z,1689002642.819 [Default:CheckIn] Running Loop=5
2023-07-10T15:24:02.820Z,1689002642.820 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:24:02.820Z,1689002642.820 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:24:04.800Z,1689002644.800 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152404.00,A,3648.14262,N,12147.22847,W,0.019,0.00,100723,,,A*75
2023-07-10T15:24:04.802Z,1689002644.802 [NAL9602](INFO): GPS fix at 20230710T152404: (36.802377, -121.787141)
2023-07-10T15:24:04.833Z,1689002644.833 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:24:04.834Z,1689002644.834 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:24:16.156Z,1689002656.156 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T145831/Courier0016.lzma
2023-07-10T15:24:17.158Z,1689002657.158 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0016.lzma.bak
2023-07-10T15:24:17.158Z,1689002657.158 [DataOverHttps](INFO): SBD MOMSN=18458334
2023-07-10T15:24:19.451Z,1689002659.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=27587, MT Status=0, MTMSN=0
2023-07-10T15:24:19.451Z,1689002659.451 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:24:33.544Z,1689002673.544 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0017.lzma
2023-07-10T15:24:34.546Z,1689002674.546 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0017.lzma.bak
2023-07-10T15:24:34.546Z,1689002674.546 [DataOverHttps](INFO): SBD MOMSN=18458337
2023-07-10T15:24:35.629Z,1689002675.629 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:24:35.629Z,1689002675.629 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:24:35.629Z,1689002675.629 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:24:50.155Z,1689002690.155 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:29:36.245Z,1689002976.245 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:29:36.245Z,1689002976.245 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:29:36.245Z,1689002976.245 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:29:36.245Z,1689002976.245 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:29:36.675Z,1689002976.675 [Default:CheckIn:D] Stopped
2023-07-10T15:29:36.675Z,1689002976.675 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:29:37.057Z,1689002977.057 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.081077 min 
2023-07-10T15:29:37.057Z,1689002977.057 [Default:CheckIn:E] Stopped
2023-07-10T15:29:37.057Z,1689002977.057 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:29:37.057Z,1689002977.057 [Default:CheckIn] Stopped
2023-07-10T15:29:37.057Z,1689002977.057 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:29:37.057Z,1689002977.057 [Default:CheckIn](INFO): Running loop #6
2023-07-10T15:29:37.058Z,1689002977.058 [Default:CheckIn] Running Loop=6
2023-07-10T15:29:37.058Z,1689002977.058 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:29:37.058Z,1689002977.058 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:29:39.067Z,1689002979.067 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152938.00,A,3648.14176,N,12147.23004,W,0.000,0.00,100723,,,A*77
2023-07-10T15:29:39.069Z,1689002979.069 [NAL9602](INFO): GPS fix at 20230710T152938: (36.802363, -121.787167)
2023-07-10T15:29:39.080Z,1689002979.080 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:29:39.080Z,1689002979.080 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:29:47.091Z,1689002987.091 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T145831/Courier0019.lzma
2023-07-10T15:29:48.094Z,1689002988.094 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0019.lzma.bak
2023-07-10T15:29:48.094Z,1689002988.094 [DataOverHttps](INFO): SBD MOMSN=18458341
2023-07-10T15:29:54.819Z,1689002994.819 [NAL9602](INFO): SBD MO Status=0, MOMSN=27588, MT Status=0, MTMSN=0
2023-07-10T15:29:54.819Z,1689002994.819 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:30:04.372Z,1689003004.372 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230710T145831/Express0020.lzma
2023-07-10T15:30:05.374Z,1689003005.374 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0020.lzma.bak
2023-07-10T15:30:05.374Z,1689003005.374 [DataOverHttps](INFO): SBD MOMSN=18458344
2023-07-10T15:30:06.604Z,1689003006.604 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:30:06.604Z,1689003006.604 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:30:06.605Z,1689003006.605 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:30:25.595Z,1689003025.595 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:35:07.241Z,1689003307.241 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:35:07.242Z,1689003307.242 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:35:07.242Z,1689003307.242 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:35:07.243Z,1689003307.243 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:35:07.649Z,1689003307.649 [Default:CheckIn:D] Stopped
2023-07-10T15:35:07.649Z,1689003307.649 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:35:08.054Z,1689003308.054 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.597302 min 
2023-07-10T15:35:08.054Z,1689003308.054 [Default:CheckIn:E] Stopped
2023-07-10T15:35:08.054Z,1689003308.054 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:35:08.054Z,1689003308.054 [Default:CheckIn] Stopped
2023-07-10T15:35:08.054Z,1689003308.054 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:35:08.055Z,1689003308.055 [Default:CheckIn](INFO): Running loop #7
2023-07-10T15:35:08.055Z,1689003308.055 [Default:CheckIn] Running Loop=7
2023-07-10T15:35:08.055Z,1689003308.055 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:35:08.055Z,1689003308.055 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:35:10.055Z,1689003310.055 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153509.00,A,3648.14344,N,12147.22821,W,0.117,0.00,100723,,,A*72
2023-07-10T15:35:10.057Z,1689003310.057 [NAL9602](INFO): GPS fix at 20230710T153509: (36.802391, -121.787137)
2023-07-10T15:35:10.069Z,1689003310.069 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:35:10.069Z,1689003310.069 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:35:17.403Z,1689003317.403 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0022.lzma
2023-07-10T15:35:18.406Z,1689003318.406 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0022.lzma.bak
2023-07-10T15:35:18.406Z,1689003318.406 [DataOverHttps](INFO): SBD MOMSN=18458357
2023-07-10T15:35:34.680Z,1689003334.680 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230710T145831/Express0023.lzma
2023-07-10T15:35:35.682Z,1689003335.682 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0023.lzma.bak
2023-07-10T15:35:35.682Z,1689003335.682 [DataOverHttps](INFO): SBD MOMSN=18458360
2023-07-10T15:35:36.756Z,1689003336.756 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:35:36.756Z,1689003336.756 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:35:36.756Z,1689003336.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:38:06.206Z,1689003486.206 [NAL9602](INFO): SBD MO Status=0, MOMSN=27589, MT Status=0, MTMSN=0
2023-07-10T15:38:06.206Z,1689003486.206 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:38:36.913Z,1689003516.913 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:40:37.408Z,1689003637.408 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:40:37.428Z,1689003637.428 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:40:37.428Z,1689003637.428 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:40:37.428Z,1689003637.428 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:40:37.805Z,1689003637.805 [Default:CheckIn:D] Stopped
2023-07-10T15:40:37.805Z,1689003637.805 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:40:38.212Z,1689003638.212 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.099906 min 
2023-07-10T15:40:38.212Z,1689003638.212 [Default:CheckIn:E] Stopped
2023-07-10T15:40:38.212Z,1689003638.212 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:40:38.212Z,1689003638.212 [Default:CheckIn] Stopped
2023-07-10T15:40:38.213Z,1689003638.213 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:40:38.213Z,1689003638.213 [Default:CheckIn](INFO): Running loop #8
2023-07-10T15:40:38.213Z,1689003638.213 [Default:CheckIn] Running Loop=8
2023-07-10T15:40:38.213Z,1689003638.213 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:40:38.213Z,1689003638.213 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:40:40.215Z,1689003640.215 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154039.00,A,3648.14105,N,12147.22862,W,0.156,0.00,100723,,,A*76
2023-07-10T15:40:40.217Z,1689003640.217 [NAL9602](INFO): GPS fix at 20230710T154039: (36.802351, -121.787144)
2023-07-10T15:40:40.249Z,1689003640.249 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:40:40.249Z,1689003640.249 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:40:47.327Z,1689003647.327 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0025.lzma
2023-07-10T15:40:48.330Z,1689003648.330 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0025.lzma.bak
2023-07-10T15:40:48.330Z,1689003648.330 [DataOverHttps](INFO): SBD MOMSN=18458364
2023-07-10T15:40:55.971Z,1689003655.971 [NAL9602](INFO): SBD MO Status=0, MOMSN=27590, MT Status=0, MTMSN=0
2023-07-10T15:40:55.971Z,1689003655.971 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:41:04.659Z,1689003664.659 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230710T145831/Express0026.lzma
2023-07-10T15:41:05.662Z,1689003665.662 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0026.lzma.bak
2023-07-10T15:41:05.662Z,1689003665.662 [DataOverHttps](INFO): SBD MOMSN=18458367
2023-07-10T15:41:06.901Z,1689003666.901 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:41:06.901Z,1689003666.901 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:41:06.902Z,1689003666.902 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:41:26.674Z,1689003686.674 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:46:07.537Z,1689003967.537 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:46:07.537Z,1689003967.537 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:46:07.537Z,1689003967.537 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:46:07.537Z,1689003967.537 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:46:07.937Z,1689003967.937 [Default:CheckIn:D] Stopped
2023-07-10T15:46:07.937Z,1689003967.937 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:46:08.349Z,1689003968.349 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.602104 min 
2023-07-10T15:46:08.349Z,1689003968.349 [Default:CheckIn:E] Stopped
2023-07-10T15:46:08.349Z,1689003968.349 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:46:08.349Z,1689003968.349 [Default:CheckIn] Stopped
2023-07-10T15:46:08.349Z,1689003968.349 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:46:08.350Z,1689003968.350 [Default:CheckIn](INFO): Running loop #9
2023-07-10T15:46:08.350Z,1689003968.350 [Default:CheckIn] Running Loop=9
2023-07-10T15:46:08.350Z,1689003968.350 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:46:08.350Z,1689003968.350 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:46:10.347Z,1689003970.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154609.00,A,3648.14189,N,12147.22841,W,0.000,0.00,100723,,,A*74
2023-07-10T15:46:10.350Z,1689003970.350 [NAL9602](INFO): GPS fix at 20230710T154609: (36.802365, -121.787140)
2023-07-10T15:46:10.382Z,1689003970.382 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:46:10.382Z,1689003970.382 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:46:21.696Z,1689003981.696 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0028.lzma
2023-07-10T15:46:22.698Z,1689003982.698 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0028.lzma.bak
2023-07-10T15:46:22.698Z,1689003982.698 [DataOverHttps](INFO): SBD MOMSN=18458425
2023-07-10T15:46:31.415Z,1689003991.415 [NAL9602](INFO): SBD MO Status=0, MOMSN=27591, MT Status=0, MTMSN=0
2023-07-10T15:46:31.415Z,1689003991.415 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:46:39.132Z,1689003999.132 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0029.lzma
2023-07-10T15:46:40.134Z,1689004000.134 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0029.lzma.bak
2023-07-10T15:46:40.134Z,1689004000.134 [DataOverHttps](INFO): SBD MOMSN=18458428
2023-07-10T15:46:41.522Z,1689004001.522 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:46:41.522Z,1689004001.522 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:46:41.522Z,1689004001.522 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:47:02.117Z,1689004022.117 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:51:42.114Z,1689004302.114 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:51:42.114Z,1689004302.114 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:51:42.114Z,1689004302.114 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:51:42.115Z,1689004302.115 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:51:42.544Z,1689004302.544 [Default:CheckIn:D] Stopped
2023-07-10T15:51:42.544Z,1689004302.544 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:51:42.912Z,1689004302.912 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.178894 min 
2023-07-10T15:51:42.912Z,1689004302.912 [Default:CheckIn:E] Stopped
2023-07-10T15:51:42.912Z,1689004302.912 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:51:42.912Z,1689004302.912 [Default:CheckIn] Stopped
2023-07-10T15:51:42.912Z,1689004302.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:51:42.913Z,1689004302.913 [Default:CheckIn](INFO): Running loop #10
2023-07-10T15:51:42.913Z,1689004302.913 [Default:CheckIn] Running Loop=10
2023-07-10T15:51:42.913Z,1689004302.913 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:51:42.913Z,1689004302.913 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:51:44.914Z,1689004304.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155144.00,A,3648.14241,N,12147.22689,W,0.019,0.00,100723,,,A*7E
2023-07-10T15:51:44.927Z,1689004304.927 [NAL9602](INFO): GPS fix at 20230710T155144: (36.802374, -121.787115)
2023-07-10T15:51:44.943Z,1689004304.943 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:51:44.943Z,1689004304.943 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:51:52.511Z,1689004312.511 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T145831/Courier0031.lzma
2023-07-10T15:51:53.514Z,1689004313.514 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0031.lzma.bak
2023-07-10T15:51:53.514Z,1689004313.514 [DataOverHttps](INFO): SBD MOMSN=18458439
2023-07-10T15:51:59.863Z,1689004319.863 [NAL9602](INFO): SBD MO Status=0, MOMSN=27592, MT Status=0, MTMSN=0
2023-07-10T15:51:59.863Z,1689004319.863 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:52:09.844Z,1689004329.844 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0032.lzma
2023-07-10T15:52:10.846Z,1689004330.846 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0032.lzma.bak
2023-07-10T15:52:10.846Z,1689004330.846 [DataOverHttps](INFO): SBD MOMSN=18458442
2023-07-10T15:52:11.997Z,1689004331.997 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:52:11.997Z,1689004331.997 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:52:11.997Z,1689004331.997 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:52:30.570Z,1689004350.570 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:54:56.826Z,1689004496.826 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2023-07-10T15:57:12.649Z,1689004632.649 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:57:12.649Z,1689004632.649 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:57:12.649Z,1689004632.649 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:57:12.649Z,1689004632.649 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:57:12.994Z,1689004632.994 [Default:CheckIn:D] Stopped
2023-07-10T15:57:12.994Z,1689004632.994 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:57:13.406Z,1689004633.406 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.686389 min 
2023-07-10T15:57:13.406Z,1689004633.406 [Default:CheckIn:E] Stopped
2023-07-10T15:57:13.406Z,1689004633.406 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:57:13.406Z,1689004633.406 [Default:CheckIn] Stopped
2023-07-10T15:57:13.406Z,1689004633.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:57:13.406Z,1689004633.406 [Default:CheckIn](INFO): Running loop #11
2023-07-10T15:57:13.406Z,1689004633.406 [Default:CheckIn] Running Loop=11
2023-07-10T15:57:13.406Z,1689004633.406 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:57:13.407Z,1689004633.407 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:57:15.411Z,1689004635.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155714.00,A,3648.14949,N,12147.22191,W,0.039,18.66,100723,,,A*4B
2023-07-10T15:57:15.413Z,1689004635.413 [NAL9602](INFO): GPS fix at 20230710T155714: (36.802492, -121.787032)
2023-07-10T15:57:15.449Z,1689004635.449 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:57:15.449Z,1689004635.449 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:57:23.471Z,1689004643.471 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0034.lzma
2023-07-10T15:57:24.474Z,1689004644.474 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0034.lzma.bak
2023-07-10T15:57:24.474Z,1689004644.474 [DataOverHttps](INFO): SBD MOMSN=18458464
2023-07-10T15:57:27.143Z,1689004647.143 [NAL9602](INFO): SBD MO Status=0, MOMSN=27593, MT Status=0, MTMSN=0
2023-07-10T15:57:27.143Z,1689004647.143 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:57:40.768Z,1689004660.768 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230710T145831/Express0035.lzma
2023-07-10T15:57:41.770Z,1689004661.770 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0035.lzma.bak
2023-07-10T15:57:41.770Z,1689004661.770 [DataOverHttps](INFO): SBD MOMSN=18458467
2023-07-10T15:57:42.915Z,1689004662.915 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:57:42.915Z,1689004662.915 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:57:42.915Z,1689004662.915 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:57:57.845Z,1689004677.845 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:02:43.560Z,1689004963.560 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:02:43.561Z,1689004963.561 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:02:43.561Z,1689004963.561 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:02:43.561Z,1689004963.561 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:02:43.957Z,1689004963.957 [Default:CheckIn:D] Stopped
2023-07-10T16:02:43.957Z,1689004963.957 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:02:44.358Z,1689004964.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.202437 min 
2023-07-10T16:02:44.358Z,1689004964.358 [Default:CheckIn:E] Stopped
2023-07-10T16:02:44.358Z,1689004964.358 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:02:44.358Z,1689004964.358 [Default:CheckIn] Stopped
2023-07-10T16:02:44.358Z,1689004964.358 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:02:44.358Z,1689004964.358 [Default:CheckIn](INFO): Running loop #12
2023-07-10T16:02:44.359Z,1689004964.359 [Default:CheckIn] Running Loop=12
2023-07-10T16:02:44.359Z,1689004964.359 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:02:44.359Z,1689004964.359 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:02:46.366Z,1689004966.366 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160245.00,A,3648.14965,N,12147.22246,W,0.019,18.66,100723,,,D*4C
2023-07-10T16:02:46.369Z,1689004966.369 [NAL9602](INFO): GPS fix at 20230710T160245: (36.802494, -121.787041)
2023-07-10T16:02:46.422Z,1689004966.422 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:02:46.422Z,1689004966.422 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:02:54.515Z,1689004974.515 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0037.lzma
2023-07-10T16:02:55.518Z,1689004975.518 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0037.lzma.bak
2023-07-10T16:02:55.518Z,1689004975.518 [DataOverHttps](INFO): SBD MOMSN=18458478
2023-07-10T16:03:11.983Z,1689004991.983 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0038.lzma
2023-07-10T16:03:12.986Z,1689004992.986 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0038.lzma.bak
2023-07-10T16:03:12.986Z,1689004992.986 [DataOverHttps](INFO): SBD MOMSN=18458481
2023-07-10T16:03:14.261Z,1689004994.261 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:03:14.261Z,1689004994.261 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:03:14.262Z,1689004994.262 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:03:37.275Z,1689005017.275 [NAL9602](INFO): SBD MO Status=0, MOMSN=27594, MT Status=0, MTMSN=0
2023-07-10T16:03:37.275Z,1689005017.275 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:04:07.978Z,1689005047.978 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:08:14.860Z,1689005294.860 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:08:14.860Z,1689005294.860 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:08:14.860Z,1689005294.860 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:08:14.860Z,1689005294.860 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:08:15.237Z,1689005295.237 [Default:CheckIn:D] Stopped
2023-07-10T16:08:15.237Z,1689005295.237 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:08:15.641Z,1689005295.641 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.723779 min 
2023-07-10T16:08:15.641Z,1689005295.641 [Default:CheckIn:E] Stopped
2023-07-10T16:08:15.642Z,1689005295.642 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:08:15.642Z,1689005295.642 [Default:CheckIn] Stopped
2023-07-10T16:08:15.642Z,1689005295.642 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:08:15.642Z,1689005295.642 [Default:CheckIn](INFO): Running loop #13
2023-07-10T16:08:15.642Z,1689005295.642 [Default:CheckIn] Running Loop=13
2023-07-10T16:08:15.642Z,1689005295.642 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:08:15.642Z,1689005295.642 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:08:17.655Z,1689005297.655 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160816.00,A,3648.14985,N,12147.22207,W,0.019,18.66,100723,,,D*4B
2023-07-10T16:08:17.657Z,1689005297.657 [NAL9602](INFO): GPS fix at 20230710T160816: (36.802498, -121.787035)
2023-07-10T16:08:17.690Z,1689005297.690 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:08:17.690Z,1689005297.690 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:08:26.579Z,1689005306.579 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T145831/Courier0040.lzma
2023-07-10T16:08:27.582Z,1689005307.582 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0040.lzma.bak
2023-07-10T16:08:27.583Z,1689005307.583 [DataOverHttps](INFO): SBD MOMSN=18458498
2023-07-10T16:08:47.003Z,1689005327.003 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0041.lzma
2023-07-10T16:08:55.393Z,1689005335.393 [NAL9602](INFO): SBD MO Status=0, MOMSN=27595, MT Status=0, MTMSN=0
2023-07-10T16:08:55.393Z,1689005335.393 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:08:55.816Z,1689005335.816 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0041.lzma
2023-07-10T16:08:56.818Z,1689005336.818 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0041.lzma.bak
2023-07-10T16:08:56.818Z,1689005336.818 [DataOverHttps](INFO): SBD MOMSN=18458501
2023-07-10T16:08:57.848Z,1689005337.848 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:08:57.848Z,1689005337.848 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:08:57.848Z,1689005337.848 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:09:26.089Z,1689005366.089 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:13:34.575Z,1689005614.575 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-07-10T16:13:34.578Z,1689005614.578 [BPC1](INFO): Received data from all battery sticks.
2023-07-10T16:13:58.480Z,1689005638.480 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:13:58.480Z,1689005638.480 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:13:58.481Z,1689005638.481 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:13:58.481Z,1689005638.481 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:13:58.864Z,1689005638.864 [Default:CheckIn:D] Stopped
2023-07-10T16:13:58.864Z,1689005638.864 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:13:59.257Z,1689005639.257 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.450887 min 
2023-07-10T16:13:59.257Z,1689005639.257 [Default:CheckIn:E] Stopped
2023-07-10T16:13:59.257Z,1689005639.257 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:13:59.257Z,1689005639.257 [Default:CheckIn] Stopped
2023-07-10T16:13:59.258Z,1689005639.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:13:59.258Z,1689005639.258 [Default:CheckIn](INFO): Running loop #14
2023-07-10T16:13:59.258Z,1689005639.258 [Default:CheckIn] Running Loop=14
2023-07-10T16:13:59.258Z,1689005639.258 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:13:59.258Z,1689005639.258 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:14:01.239Z,1689005641.239 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161400.00,A,3648.14978,N,12147.22270,W,0.039,18.66,100723,,,D*41
2023-07-10T16:14:01.241Z,1689005641.241 [NAL9602](INFO): GPS fix at 20230710T161400: (36.802496, -121.787045)
2023-07-10T16:14:01.254Z,1689005641.254 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:14:01.254Z,1689005641.254 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:14:09.035Z,1689005649.035 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20230710T145831/Courier0043.lzma
2023-07-10T16:14:10.038Z,1689005650.038 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0043.lzma.bak
2023-07-10T16:14:10.038Z,1689005650.038 [DataOverHttps](INFO): SBD MOMSN=18458512
2023-07-10T16:14:24.675Z,1689005664.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=27596, MT Status=0, MTMSN=0
2023-07-10T16:14:24.675Z,1689005664.675 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:14:26.263Z,1689005666.263 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0044.lzma
2023-07-10T16:14:27.266Z,1689005667.266 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0044.lzma.bak
2023-07-10T16:14:27.266Z,1689005667.266 [DataOverHttps](INFO): SBD MOMSN=18458515
2023-07-10T16:14:28.322Z,1689005668.322 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:14:28.322Z,1689005668.322 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:14:28.322Z,1689005668.322 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:14:55.369Z,1689005695.369 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:19:29.020Z,1689005969.020 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:19:29.020Z,1689005969.020 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:19:29.020Z,1689005969.020 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:19:29.020Z,1689005969.020 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:19:29.424Z,1689005969.424 [Default:CheckIn:D] Stopped
2023-07-10T16:19:29.424Z,1689005969.424 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:19:29.828Z,1689005969.828 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.960229 min 
2023-07-10T16:19:29.828Z,1689005969.828 [Default:CheckIn:E] Stopped
2023-07-10T16:19:29.828Z,1689005969.828 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:19:29.829Z,1689005969.829 [Default:CheckIn] Stopped
2023-07-10T16:19:29.829Z,1689005969.829 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:19:29.829Z,1689005969.829 [Default:CheckIn](INFO): Running loop #15
2023-07-10T16:19:29.829Z,1689005969.829 [Default:CheckIn] Running Loop=15
2023-07-10T16:19:29.829Z,1689005969.829 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:19:29.829Z,1689005969.829 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:19:31.835Z,1689005971.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161931.00,A,3648.14990,N,12147.22204,W,0.078,18.66,100723,,,D*4E
2023-07-10T16:19:31.837Z,1689005971.837 [NAL9602](INFO): GPS fix at 20230710T161931: (36.802498, -121.787034)
2023-07-10T16:19:31.869Z,1689005971.869 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:19:31.869Z,1689005971.869 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:19:39.883Z,1689005979.883 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0046.lzma
2023-07-10T16:19:40.886Z,1689005980.886 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0046.lzma.bak
2023-07-10T16:19:40.886Z,1689005980.886 [DataOverHttps](INFO): SBD MOMSN=18458535
2023-07-10T16:19:57.116Z,1689005997.116 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0047.lzma
2023-07-10T16:19:58.118Z,1689005998.118 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0047.lzma.bak
2023-07-10T16:19:58.118Z,1689005998.118 [DataOverHttps](INFO): SBD MOMSN=18458538
2023-07-10T16:19:59.382Z,1689005999.382 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:19:59.382Z,1689005999.382 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:19:59.382Z,1689005999.382 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:20:24.450Z,1689006024.450 [NAL9602](INFO): SBD MO Status=2, MOMSN=27597, MT Status=2, MTMSN=0
2023-07-10T16:20:24.451Z,1689006024.451 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T16:20:52.323Z,1689006052.323 [NAL9602](INFO): SBD MO Status=0, MOMSN=27597, MT Status=0, MTMSN=0
2023-07-10T16:20:52.323Z,1689006052.323 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:21:23.021Z,1689006083.021 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:24:59.985Z,1689006299.985 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:24:59.985Z,1689006299.985 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:24:59.986Z,1689006299.986 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:24:59.986Z,1689006299.986 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:25:00.407Z,1689006300.407 [Default:CheckIn:D] Stopped
2023-07-10T16:25:00.407Z,1689006300.407 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:25:00.823Z,1689006300.823 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.476595 min 
2023-07-10T16:25:00.823Z,1689006300.823 [Default:CheckIn:E] Stopped
2023-07-10T16:25:00.823Z,1689006300.823 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:25:00.823Z,1689006300.823 [Default:CheckIn] Stopped
2023-07-10T16:25:00.823Z,1689006300.823 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:25:00.824Z,1689006300.824 [Default:CheckIn](INFO): Running loop #16
2023-07-10T16:25:00.824Z,1689006300.824 [Default:CheckIn] Running Loop=16
2023-07-10T16:25:00.824Z,1689006300.824 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:25:00.824Z,1689006300.824 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:25:02.802Z,1689006302.802 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162502.00,A,3648.15025,N,12147.22287,W,0.019,18.66,100723,,,D*4B
2023-07-10T16:25:02.805Z,1689006302.805 [NAL9602](INFO): GPS fix at 20230710T162502: (36.802504, -121.787048)
2023-07-10T16:25:02.865Z,1689006302.865 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:25:02.865Z,1689006302.865 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:25:10.719Z,1689006310.719 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0049.lzma
2023-07-10T16:25:11.722Z,1689006311.722 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0049.lzma.bak
2023-07-10T16:25:11.722Z,1689006311.722 [DataOverHttps](INFO): SBD MOMSN=18458549
2023-07-10T16:25:25.023Z,1689006325.023 [NAL9602](INFO): SBD MO Status=0, MOMSN=27598, MT Status=0, MTMSN=0
2023-07-10T16:25:25.023Z,1689006325.023 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:25:28.196Z,1689006328.196 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0050.lzma
2023-07-10T16:25:29.198Z,1689006329.198 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0050.lzma.bak
2023-07-10T16:25:29.198Z,1689006329.198 [DataOverHttps](INFO): SBD MOMSN=18458552
2023-07-10T16:25:30.292Z,1689006330.292 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:25:30.292Z,1689006330.292 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:25:30.292Z,1689006330.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:25:55.726Z,1689006355.726 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:30:30.867Z,1689006630.867 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:30:30.868Z,1689006630.868 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:30:30.868Z,1689006630.868 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:30:30.868Z,1689006630.868 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:30:31.277Z,1689006631.277 [Default:CheckIn:D] Stopped
2023-07-10T16:30:31.277Z,1689006631.277 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:30:31.684Z,1689006631.684 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.991113 min 
2023-07-10T16:30:31.684Z,1689006631.684 [Default:CheckIn:E] Stopped
2023-07-10T16:30:31.685Z,1689006631.685 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:30:31.685Z,1689006631.685 [Default:CheckIn] Stopped
2023-07-10T16:30:31.685Z,1689006631.685 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:30:31.685Z,1689006631.685 [Default:CheckIn](INFO): Running loop #17
2023-07-10T16:30:31.685Z,1689006631.685 [Default:CheckIn] Running Loop=17
2023-07-10T16:30:31.685Z,1689006631.685 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:30:31.685Z,1689006631.685 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:30:33.693Z,1689006633.693 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163032.00,A,3648.15022,N,12147.22409,W,0.019,0.00,100723,,,D*72
2023-07-10T16:30:33.695Z,1689006633.695 [NAL9602](INFO): GPS fix at 20230710T163032: (36.802504, -121.787068)
2023-07-10T16:30:33.707Z,1689006633.707 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:30:33.707Z,1689006633.707 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:30:44.438Z,1689006644.438 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T145831/Courier0052.lzma
2023-07-10T16:30:45.427Z,1689006645.427 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0052.lzma.bak
2023-07-10T16:30:45.427Z,1689006645.427 [DataOverHttps](INFO): SBD MOMSN=18458573
2023-07-10T16:30:51.146Z,1689006651.146 [NAL9602](INFO): SBD MO Status=0, MOMSN=27599, MT Status=0, MTMSN=0
2023-07-10T16:30:51.147Z,1689006651.147 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:31:01.804Z,1689006661.804 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230710T145831/Express0053.lzma
2023-07-10T16:31:02.806Z,1689006662.806 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0053.lzma.bak
2023-07-10T16:31:02.806Z,1689006662.806 [DataOverHttps](INFO): SBD MOMSN=18458576
2023-07-10T16:31:04.097Z,1689006664.097 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:31:04.097Z,1689006664.097 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:31:04.097Z,1689006664.097 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:31:21.848Z,1689006681.848 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:36:04.744Z,1689006964.744 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:36:04.744Z,1689006964.744 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:36:04.744Z,1689006964.744 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:36:04.744Z,1689006964.744 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:36:05.120Z,1689006965.120 [Default:CheckIn:D] Stopped
2023-07-10T16:36:05.120Z,1689006965.120 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:36:05.533Z,1689006965.533 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.555151 min 
2023-07-10T16:36:05.533Z,1689006965.533 [Default:CheckIn:E] Stopped
2023-07-10T16:36:05.533Z,1689006965.533 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:36:05.533Z,1689006965.533 [Default:CheckIn] Stopped
2023-07-10T16:36:05.534Z,1689006965.534 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:36:05.534Z,1689006965.534 [Default:CheckIn](INFO): Running loop #18
2023-07-10T16:36:05.534Z,1689006965.534 [Default:CheckIn] Running Loop=18
2023-07-10T16:36:05.534Z,1689006965.534 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:36:05.534Z,1689006965.534 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:36:07.541Z,1689006967.541 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163606.00,A,3648.14852,N,12147.22305,W,0.039,0.00,100723,,,D*74
2023-07-10T16:36:07.561Z,1689006967.561 [NAL9602](INFO): GPS fix at 20230710T163606: (36.802475, -121.787051)
2023-07-10T16:36:07.597Z,1689006967.597 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:36:07.597Z,1689006967.597 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:36:14.971Z,1689006974.971 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T145831/Courier0055.lzma
2023-07-10T16:36:15.974Z,1689006975.974 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0055.lzma.bak
2023-07-10T16:36:15.974Z,1689006975.974 [DataOverHttps](INFO): SBD MOMSN=18458587
2023-07-10T16:36:32.156Z,1689006992.156 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230710T145831/Express0056.lzma
2023-07-10T16:36:33.158Z,1689006993.158 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0056.lzma.bak
2023-07-10T16:36:33.158Z,1689006993.158 [DataOverHttps](INFO): SBD MOMSN=18458590
2023-07-10T16:36:34.205Z,1689006994.205 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:36:34.206Z,1689006994.206 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:36:34.206Z,1689006994.206 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:36:35.812Z,1689006995.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=27600, MT Status=2, MTMSN=0
2023-07-10T16:36:35.812Z,1689006995.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T16:37:25.111Z,1689007045.111 [NAL9602](INFO): SBD MO Status=0, MOMSN=27600, MT Status=0, MTMSN=0
2023-07-10T16:37:25.111Z,1689007045.111 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:37:55.833Z,1689007075.833 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:41:34.839Z,1689007294.839 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:41:34.839Z,1689007294.839 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:41:34.839Z,1689007294.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:41:34.839Z,1689007294.839 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:41:35.216Z,1689007295.216 [Default:CheckIn:D] Stopped
2023-07-10T16:41:35.216Z,1689007295.216 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:41:35.617Z,1689007295.617 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.056755 min 
2023-07-10T16:41:35.617Z,1689007295.617 [Default:CheckIn:E] Stopped
2023-07-10T16:41:35.617Z,1689007295.617 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:41:35.617Z,1689007295.617 [Default:CheckIn] Stopped
2023-07-10T16:41:35.617Z,1689007295.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:41:35.617Z,1689007295.617 [Default:CheckIn](INFO): Running loop #19
2023-07-10T16:41:35.617Z,1689007295.617 [Default:CheckIn] Running Loop=19
2023-07-10T16:41:35.617Z,1689007295.617 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:41:35.617Z,1689007295.617 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:41:37.639Z,1689007297.639 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164136.00,A,3648.14800,N,12147.22186,W,0.097,0.00,100723,,,D*7D
2023-07-10T16:41:37.641Z,1689007297.641 [NAL9602](INFO): GPS fix at 20230710T164136: (36.802467, -121.787031)
2023-07-10T16:41:37.653Z,1689007297.653 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:41:37.653Z,1689007297.653 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:41:45.215Z,1689007305.215 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T145831/Courier0058.lzma
2023-07-10T16:41:46.218Z,1689007306.218 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0058.lzma.bak
2023-07-10T16:41:46.218Z,1689007306.218 [DataOverHttps](INFO): SBD MOMSN=18458610
2023-07-10T16:41:55.419Z,1689007315.419 [NAL9602](INFO): SBD MO Status=0, MOMSN=27601, MT Status=0, MTMSN=0
2023-07-10T16:41:55.419Z,1689007315.419 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:42:02.712Z,1689007322.712 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230710T145831/Express0059.lzma
2023-07-10T16:42:03.714Z,1689007323.714 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0059.lzma.bak
2023-07-10T16:42:03.714Z,1689007323.714 [DataOverHttps](INFO): SBD MOMSN=18458613
2023-07-10T16:42:04.760Z,1689007324.760 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:42:04.761Z,1689007324.761 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:42:04.761Z,1689007324.761 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:42:26.115Z,1689007346.115 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:47:05.300Z,1689007625.300 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:47:05.300Z,1689007625.300 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:47:05.300Z,1689007625.300 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:47:05.300Z,1689007625.300 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:47:05.692Z,1689007625.692 [Default:CheckIn:D] Stopped
2023-07-10T16:47:05.692Z,1689007625.692 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:47:06.094Z,1689007626.094 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.564689 min 
2023-07-10T16:47:06.094Z,1689007626.094 [Default:CheckIn:E] Stopped
2023-07-10T16:47:06.094Z,1689007626.094 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:47:06.094Z,1689007626.094 [Default:CheckIn] Stopped
2023-07-10T16:47:06.094Z,1689007626.094 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:47:06.095Z,1689007626.095 [Default:CheckIn](INFO): Running loop #20
2023-07-10T16:47:06.095Z,1689007626.095 [Default:CheckIn] Running Loop=20
2023-07-10T16:47:06.095Z,1689007626.095 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:47:06.095Z,1689007626.095 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:47:08.120Z,1689007628.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164707.00,A,3648.15049,N,12147.22253,W,0.039,0.00,100723,,,D*72
2023-07-10T16:47:08.122Z,1689007628.122 [NAL9602](INFO): GPS fix at 20230710T164707: (36.802508, -121.787042)
2023-07-10T16:47:08.134Z,1689007628.134 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:47:08.134Z,1689007628.134 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:47:15.915Z,1689007635.915 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T145831/Courier0061.lzma
2023-07-10T16:47:16.918Z,1689007636.918 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0061.lzma.bak
2023-07-10T16:47:16.918Z,1689007636.918 [DataOverHttps](INFO): SBD MOMSN=18458624
2023-07-10T16:47:21.435Z,1689007641.435 [NAL9602](INFO): SBD MO Status=0, MOMSN=27602, MT Status=0, MTMSN=0
2023-07-10T16:47:21.435Z,1689007641.435 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:47:33.171Z,1689007653.171 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230710T145831/Express0062.lzma
2023-07-10T16:47:34.174Z,1689007654.174 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0062.lzma.bak
2023-07-10T16:47:34.174Z,1689007654.174 [DataOverHttps](INFO): SBD MOMSN=18458627
2023-07-10T16:47:35.212Z,1689007655.212 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:47:35.212Z,1689007655.212 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:47:35.212Z,1689007655.212 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:47:52.136Z,1689007672.136 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:52:35.896Z,1689007955.896 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:52:35.897Z,1689007955.897 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:52:35.897Z,1689007955.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:52:35.897Z,1689007955.897 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:52:36.257Z,1689007956.257 [Default:CheckIn:D] Stopped
2023-07-10T16:52:36.257Z,1689007956.257 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:52:36.679Z,1689007956.679 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.074105 min 
2023-07-10T16:52:36.679Z,1689007956.679 [Default:CheckIn:E] Stopped
2023-07-10T16:52:36.679Z,1689007956.679 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:52:36.679Z,1689007956.679 [Default:CheckIn] Stopped
2023-07-10T16:52:36.679Z,1689007956.679 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:52:36.679Z,1689007956.679 [Default:CheckIn](INFO): Running loop #21
2023-07-10T16:52:36.679Z,1689007956.679 [Default:CheckIn] Running Loop=21
2023-07-10T16:52:36.679Z,1689007956.679 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:52:36.679Z,1689007956.679 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:52:38.663Z,1689007958.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165237.00,A,3648.15074,N,12147.22132,W,0.136,0.00,100723,,,D*71
2023-07-10T16:52:38.665Z,1689007958.665 [NAL9602](INFO): GPS fix at 20230710T165237: (36.802512, -121.787022)
2023-07-10T16:52:38.716Z,1689007958.716 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:52:38.716Z,1689007958.716 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:52:45.867Z,1689007965.867 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0064.lzma
2023-07-10T16:52:46.870Z,1689007966.870 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0064.lzma.bak
2023-07-10T16:52:46.870Z,1689007966.870 [DataOverHttps](INFO): SBD MOMSN=18458639
2023-07-10T16:53:00.079Z,1689007980.079 [NAL9602](INFO): SBD MO Status=2, MOMSN=27603, MT Status=2, MTMSN=0
2023-07-10T16:53:00.079Z,1689007980.079 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T16:53:03.196Z,1689007983.196 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230710T145831/Express0065.lzma
2023-07-10T16:53:04.198Z,1689007984.198 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0065.lzma.bak
2023-07-10T16:53:04.198Z,1689007984.198 [DataOverHttps](INFO): SBD MOMSN=18458642
2023-07-10T16:53:05.331Z,1689007985.331 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:53:05.332Z,1689007985.332 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:53:05.332Z,1689007985.332 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:53:17.443Z,1689007997.443 [NAL9602](INFO): SBD MO Status=0, MOMSN=27603, MT Status=0, MTMSN=0
2023-07-10T16:53:17.443Z,1689007997.443 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:53:48.144Z,1689008028.144 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:58:05.936Z,1689008285.936 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:58:05.936Z,1689008285.936 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:58:05.936Z,1689008285.936 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:58:05.936Z,1689008285.936 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:58:06.337Z,1689008286.337 [Default:CheckIn:D] Stopped
2023-07-10T16:58:06.337Z,1689008286.337 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:58:06.770Z,1689008286.770 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.575448 min 
2023-07-10T16:58:06.770Z,1689008286.770 [Default:CheckIn:E] Stopped
2023-07-10T16:58:06.770Z,1689008286.770 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:58:06.770Z,1689008286.770 [Default:CheckIn] Stopped
2023-07-10T16:58:06.770Z,1689008286.770 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:58:06.770Z,1689008286.770 [Default:CheckIn](INFO): Running loop #22
2023-07-10T16:58:06.770Z,1689008286.770 [Default:CheckIn] Running Loop=22
2023-07-10T16:58:06.770Z,1689008286.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:58:06.770Z,1689008286.770 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:58:08.735Z,1689008288.735 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165808.00,A,3648.15025,N,12147.22121,W,0.097,0.00,100723,,,D*7B
2023-07-10T16:58:08.737Z,1689008288.737 [NAL9602](INFO): GPS fix at 20230710T165808: (36.802504, -121.787020)
2023-07-10T16:58:08.768Z,1689008288.768 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:58:08.768Z,1689008288.768 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:58:16.567Z,1689008296.567 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230710T145831/Courier0067.lzma
2023-07-10T16:58:17.570Z,1689008297.570 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0067.lzma.bak
2023-07-10T16:58:17.570Z,1689008297.570 [DataOverHttps](INFO): SBD MOMSN=18458653
2023-07-10T16:58:35.408Z,1689008315.408 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230710T145831/Express0068.lzma
2023-07-10T16:58:36.414Z,1689008316.414 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0068.lzma.bak
2023-07-10T16:58:36.415Z,1689008316.415 [DataOverHttps](INFO): SBD MOMSN=18458656
2023-07-10T16:58:37.473Z,1689008317.473 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:58:37.473Z,1689008317.473 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:58:37.473Z,1689008317.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:58:39.873Z,1689008319.873 [NAL9602](INFO): SBD MO Status=0, MOMSN=27604, MT Status=0, MTMSN=0
2023-07-10T16:58:39.873Z,1689008319.873 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:59:10.577Z,1689008350.577 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:03:38.117Z,1689008618.117 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:03:38.118Z,1689008618.118 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:03:38.118Z,1689008618.118 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:03:38.118Z,1689008618.118 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:03:38.542Z,1689008618.542 [Default:CheckIn:D] Stopped
2023-07-10T17:03:38.542Z,1689008618.542 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:03:38.952Z,1689008618.952 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.112191 min 
2023-07-10T17:03:38.952Z,1689008618.952 [Default:CheckIn:E] Stopped
2023-07-10T17:03:38.952Z,1689008618.952 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:03:38.952Z,1689008618.952 [Default:CheckIn] Stopped
2023-07-10T17:03:38.952Z,1689008618.952 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:03:38.953Z,1689008618.953 [Default:CheckIn](INFO): Running loop #23
2023-07-10T17:03:38.953Z,1689008618.953 [Default:CheckIn] Running Loop=23
2023-07-10T17:03:38.953Z,1689008618.953 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:03:38.953Z,1689008618.953 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:03:40.932Z,1689008620.932 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170340.00,A,3648.15053,N,12147.22251,W,0.019,0.00,100723,,,D*7B
2023-07-10T17:03:40.934Z,1689008620.934 [NAL9602](INFO): GPS fix at 20230710T170340: (36.802509, -121.787042)
2023-07-10T17:03:40.953Z,1689008620.953 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:03:40.953Z,1689008620.953 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:03:48.983Z,1689008628.983 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T145831/Courier0070.lzma
2023-07-10T17:03:49.986Z,1689008629.986 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0070.lzma.bak
2023-07-10T17:03:49.986Z,1689008629.986 [DataOverHttps](INFO): SBD MOMSN=18458667
2023-07-10T17:04:04.366Z,1689008644.366 [NAL9602](INFO): SBD MO Status=2, MOMSN=27605, MT Status=2, MTMSN=0
2023-07-10T17:04:04.367Z,1689008644.367 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T17:04:06.335Z,1689008646.335 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230710T145831/Express0071.lzma
2023-07-10T17:04:07.338Z,1689008647.338 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0071.lzma.bak
2023-07-10T17:04:07.339Z,1689008647.339 [DataOverHttps](INFO): SBD MOMSN=18458670
2023-07-10T17:04:08.466Z,1689008648.466 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:04:08.466Z,1689008648.466 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:04:08.467Z,1689008648.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:04:25.775Z,1689008665.775 [NAL9602](INFO): SBD MO Status=0, MOMSN=27605, MT Status=0, MTMSN=0
2023-07-10T17:04:25.775Z,1689008665.775 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:04:56.526Z,1689008696.526 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:08:42.781Z,1689008922.781 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: -32768
2023-07-10T17:09:09.036Z,1689008949.036 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:09:09.037Z,1689008949.037 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:09:09.037Z,1689008949.037 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:09:09.037Z,1689008949.037 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:09:09.464Z,1689008949.464 [Default:CheckIn:D] Stopped
2023-07-10T17:09:09.464Z,1689008949.464 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:09:09.851Z,1689008949.851 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.627490 min 
2023-07-10T17:09:09.851Z,1689008949.851 [Default:CheckIn:E] Stopped
2023-07-10T17:09:09.851Z,1689008949.851 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:09:09.851Z,1689008949.851 [Default:CheckIn] Stopped
2023-07-10T17:09:09.851Z,1689008949.851 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:09:09.851Z,1689008949.851 [Default:CheckIn](INFO): Running loop #24
2023-07-10T17:09:09.851Z,1689008949.851 [Default:CheckIn] Running Loop=24
2023-07-10T17:09:09.852Z,1689008949.852 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:09:09.852Z,1689008949.852 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:09:11.859Z,1689008951.859 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170911.00,A,3648.15061,N,12147.22177,W,0.058,0.00,100723,,,D*76
2023-07-10T17:09:11.862Z,1689008951.862 [NAL9602](INFO): GPS fix at 20230710T170911: (36.802510, -121.787030)
2023-07-10T17:09:11.874Z,1689008951.874 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:09:11.874Z,1689008951.874 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:09:19.183Z,1689008959.183 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0073.lzma
2023-07-10T17:09:20.187Z,1689008960.187 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0073.lzma.bak
2023-07-10T17:09:20.187Z,1689008960.187 [DataOverHttps](INFO): SBD MOMSN=18458681
2023-07-10T17:09:32.493Z,1689008972.493 [NAL9602](INFO): SBD MO Status=2, MOMSN=27606, MT Status=2, MTMSN=0
2023-07-10T17:09:32.493Z,1689008972.493 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T17:09:36.477Z,1689008976.477 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T145831/Express0074.lzma
2023-07-10T17:09:37.479Z,1689008977.479 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0074.lzma.bak
2023-07-10T17:09:37.479Z,1689008977.479 [DataOverHttps](INFO): SBD MOMSN=18458684
2023-07-10T17:09:38.557Z,1689008978.557 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:09:38.557Z,1689008978.557 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:09:38.557Z,1689008978.557 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:09:44.175Z,1689008984.175 [NAL9602](INFO): SBD MO Status=0, MOMSN=27606, MT Status=0, MTMSN=0
2023-07-10T17:09:44.175Z,1689008984.175 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:10:14.877Z,1689009014.877 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:14:39.188Z,1689009279.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:14:39.204Z,1689009279.204 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:14:39.205Z,1689009279.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:14:39.205Z,1689009279.205 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:14:39.597Z,1689009279.597 [Default:CheckIn:D] Stopped
2023-07-10T17:14:39.597Z,1689009279.597 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:14:40.013Z,1689009280.013 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.129777 min 
2023-07-10T17:14:40.013Z,1689009280.013 [Default:CheckIn:E] Stopped
2023-07-10T17:14:40.014Z,1689009280.014 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:14:40.014Z,1689009280.014 [Default:CheckIn] Stopped
2023-07-10T17:14:40.014Z,1689009280.014 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:14:40.014Z,1689009280.014 [Default:CheckIn](INFO): Running loop #25
2023-07-10T17:14:40.014Z,1689009280.014 [Default:CheckIn] Running Loop=25
2023-07-10T17:14:40.014Z,1689009280.014 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:14:40.014Z,1689009280.014 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:14:42.003Z,1689009282.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171441.00,A,3648.15068,N,12147.22233,W,0.039,0.00,100723,,,D*72
2023-07-10T17:14:42.005Z,1689009282.005 [NAL9602](INFO): GPS fix at 20230710T171441: (36.802511, -121.787039)
2023-07-10T17:14:42.033Z,1689009282.033 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:14:42.034Z,1689009282.034 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:14:49.299Z,1689009289.299 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0076.lzma
2023-07-10T17:14:50.302Z,1689009290.302 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0076.lzma.bak
2023-07-10T17:14:50.302Z,1689009290.302 [DataOverHttps](INFO): SBD MOMSN=18458695
2023-07-10T17:14:57.480Z,1689009297.480 [NAL9602](INFO): SBD MO Status=0, MOMSN=27607, MT Status=0, MTMSN=0
2023-07-10T17:14:57.480Z,1689009297.480 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:15:07.135Z,1689009307.135 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230710T145831/Express0077.lzma
2023-07-10T17:15:08.138Z,1689009308.138 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0077.lzma.bak
2023-07-10T17:15:08.138Z,1689009308.138 [DataOverHttps](INFO): SBD MOMSN=18458698
2023-07-10T17:15:09.231Z,1689009309.231 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:15:09.231Z,1689009309.231 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:15:09.231Z,1689009309.231 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:15:28.125Z,1689009328.125 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:20:09.831Z,1689009609.831 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:20:09.831Z,1689009609.831 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:20:09.831Z,1689009609.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:20:09.832Z,1689009609.832 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:20:10.196Z,1689009610.196 [Default:CheckIn:D] Stopped
2023-07-10T17:20:10.196Z,1689009610.196 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:20:10.638Z,1689009610.638 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.639746 min 
2023-07-10T17:20:10.638Z,1689009610.638 [Default:CheckIn:E] Stopped
2023-07-10T17:20:10.638Z,1689009610.638 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:20:10.639Z,1689009610.639 [Default:CheckIn] Stopped
2023-07-10T17:20:10.639Z,1689009610.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:20:10.639Z,1689009610.639 [Default:CheckIn](INFO): Running loop #26
2023-07-10T17:20:10.639Z,1689009610.639 [Default:CheckIn] Running Loop=26
2023-07-10T17:20:10.639Z,1689009610.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:20:10.639Z,1689009610.639 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:20:12.617Z,1689009612.617 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172011.00,A,3648.28288,N,12147.12070,W,3.499,8.20,100723,,,D*73
2023-07-10T17:20:12.619Z,1689009612.619 [NAL9602](INFO): GPS fix at 20230710T172011: (36.804715, -121.785345)
2023-07-10T17:20:12.667Z,1689009612.667 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:20:12.667Z,1689009612.667 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:20:20.739Z,1689009620.739 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0079.lzma
2023-07-10T17:20:21.742Z,1689009621.742 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0079.lzma.bak
2023-07-10T17:20:21.742Z,1689009621.742 [DataOverHttps](INFO): SBD MOMSN=18458709
2023-07-10T17:20:38.176Z,1689009638.176 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230710T145831/Express0080.lzma
2023-07-10T17:20:39.178Z,1689009639.178 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0080.lzma.bak
2023-07-10T17:20:39.178Z,1689009639.178 [DataOverHttps](INFO): SBD MOMSN=18458712
2023-07-10T17:20:40.548Z,1689009640.548 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:20:40.548Z,1689009640.548 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:20:40.552Z,1689009640.552 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:21:03.517Z,1689009663.517 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-07-10T17:21:03.520Z,1689009663.520 [BPC1](INFO): Received data from all battery sticks.
2023-07-10T17:21:18.079Z,1689009678.079 [NAL9602](INFO): SBD MO Status=2, MOMSN=27608, MT Status=2, MTMSN=0
2023-07-10T17:21:18.079Z,1689009678.079 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T17:22:00.151Z,1689009720.151 [NAL9602](INFO): SBD MO Status=0, MOMSN=27608, MT Status=0, MTMSN=0
2023-07-10T17:22:00.151Z,1689009720.151 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:22:30.825Z,1689009750.825 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:25:00.756Z,1689009900.756 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2023-07-10T17:25:00.756Z,1689009900.756 [RDI_Pathfinder] Communications Fault, FailCount= 1
2023-07-10T17:25:00.756Z,1689009900.756 [RDI_Pathfinder](ERROR): Communications Fault
2023-07-10T17:25:00.806Z,1689009900.806 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2023-07-10T17:25:01.184Z,1689009901.184 [RDI_Pathfinder](INFO): Powering down
2023-07-10T17:25:01.949Z,1689009901.949 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2023-07-10T17:25:01.949Z,1689009901.949 [RDI_Pathfinder] No Fault, FailCount= 1
2023-07-10T17:25:02.330Z,1689009902.330 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-07-10T17:25:41.132Z,1689009941.132 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:25:41.132Z,1689009941.132 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:25:41.132Z,1689009941.132 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:25:41.132Z,1689009941.132 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:25:41.539Z,1689009941.539 [Default:CheckIn:D] Stopped
2023-07-10T17:25:41.539Z,1689009941.539 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:25:41.959Z,1689009941.959 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.162142 min 
2023-07-10T17:25:41.959Z,1689009941.959 [Default:CheckIn:E] Stopped
2023-07-10T17:25:41.960Z,1689009941.960 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:25:41.960Z,1689009941.960 [Default:CheckIn] Stopped
2023-07-10T17:25:41.960Z,1689009941.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:25:41.960Z,1689009941.960 [Default:CheckIn](INFO): Running loop #27
2023-07-10T17:25:41.960Z,1689009941.960 [Default:CheckIn] Running Loop=27
2023-07-10T17:25:41.960Z,1689009941.960 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:25:41.960Z,1689009941.960 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:25:43.939Z,1689009943.939 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172543.00,A,3648.49767,N,12147.22046,W,3.946,235.37,100723,,,D*71
2023-07-10T17:25:43.941Z,1689009943.941 [NAL9602](INFO): GPS fix at 20230710T172543: (36.808295, -121.787008)
2023-07-10T17:25:43.992Z,1689009943.992 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:25:43.992Z,1689009943.992 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:25:51.487Z,1689009951.487 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230710T145831/Courier0082.lzma
2023-07-10T17:25:52.490Z,1689009952.490 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0082.lzma.bak
2023-07-10T17:25:52.490Z,1689009952.490 [DataOverHttps](INFO): SBD MOMSN=18458728
2023-07-10T17:25:59.727Z,1689009959.727 [NAL9602](INFO): SBD MO Status=0, MOMSN=27609, MT Status=0, MTMSN=0
2023-07-10T17:25:59.728Z,1689009959.728 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:26:08.888Z,1689009968.888 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20230710T145831/Express0083.lzma
2023-07-10T17:26:09.890Z,1689009969.890 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0083.lzma.bak
2023-07-10T17:26:09.890Z,1689009969.890 [DataOverHttps](INFO): SBD MOMSN=18458731
2023-07-10T17:26:11.020Z,1689009971.020 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:26:11.020Z,1689009971.020 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:26:11.020Z,1689009971.020 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:26:30.401Z,1689009990.401 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:31:11.683Z,1689010271.683 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:31:11.683Z,1689010271.683 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:31:11.683Z,1689010271.683 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:31:11.684Z,1689010271.684 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:31:12.105Z,1689010272.105 [Default:CheckIn:D] Stopped
2023-07-10T17:31:12.105Z,1689010272.105 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:31:12.470Z,1689010272.470 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.671582 min 
2023-07-10T17:31:12.470Z,1689010272.470 [Default:CheckIn:E] Stopped
2023-07-10T17:31:12.470Z,1689010272.470 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:31:12.471Z,1689010272.471 [Default:CheckIn] Stopped
2023-07-10T17:31:12.471Z,1689010272.471 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:31:12.471Z,1689010272.471 [Default:CheckIn](INFO): Running loop #28
2023-07-10T17:31:12.471Z,1689010272.471 [Default:CheckIn] Running Loop=28
2023-07-10T17:31:12.471Z,1689010272.471 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:31:12.471Z,1689010272.471 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:31:14.471Z,1689010274.471 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173113.00,A,3648.26510,N,12148.10856,W,13.879,254.08,100723,,,D*4A
2023-07-10T17:31:14.473Z,1689010274.473 [NAL9602](INFO): GPS fix at 20230710T173113: (36.804418, -121.801809)
2023-07-10T17:31:14.505Z,1689010274.505 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:31:14.505Z,1689010274.505 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:31:21.763Z,1689010281.763 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0085.lzma
2023-07-10T17:31:22.766Z,1689010282.766 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0085.lzma.bak
2023-07-10T17:31:22.766Z,1689010282.766 [DataOverHttps](INFO): SBD MOMSN=18458746
2023-07-10T17:31:28.211Z,1689010288.211 [NAL9602](INFO): SBD MO Status=0, MOMSN=27610, MT Status=0, MTMSN=0
2023-07-10T17:31:28.211Z,1689010288.211 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:31:39.104Z,1689010299.104 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230710T145831/Express0086.lzma
2023-07-10T17:31:40.107Z,1689010300.107 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0086.lzma.bak
2023-07-10T17:31:40.107Z,1689010300.107 [DataOverHttps](INFO): SBD MOMSN=18458749
2023-07-10T17:31:41.159Z,1689010301.159 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:31:41.159Z,1689010301.159 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:31:41.159Z,1689010301.159 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:31:58.904Z,1689010318.904 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:36:41.812Z,1689010601.812 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:36:41.812Z,1689010601.812 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:36:41.812Z,1689010601.812 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:36:41.812Z,1689010601.812 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:36:42.189Z,1689010602.189 [Default:CheckIn:D] Stopped
2023-07-10T17:36:42.189Z,1689010602.189 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:36:42.592Z,1689010602.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.172982 min 
2023-07-10T17:36:42.592Z,1689010602.592 [Default:CheckIn:E] Stopped
2023-07-10T17:36:42.593Z,1689010602.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:36:42.593Z,1689010602.593 [Default:CheckIn] Stopped
2023-07-10T17:36:42.593Z,1689010602.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:36:42.593Z,1689010602.593 [Default:CheckIn](INFO): Running loop #29
2023-07-10T17:36:42.593Z,1689010602.593 [Default:CheckIn] Running Loop=29
2023-07-10T17:36:42.593Z,1689010602.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:36:42.593Z,1689010602.593 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:36:44.600Z,1689010604.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173643.00,A,3647.96267,N,12149.66322,W,16.639,258.10,100723,,,D*49
2023-07-10T17:36:44.603Z,1689010604.603 [NAL9602](INFO): GPS fix at 20230710T173643: (36.799378, -121.827720)
2023-07-10T17:36:44.615Z,1689010604.615 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:36:44.615Z,1689010604.615 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:36:56.779Z,1689010616.779 [NAL9602](INFO): SBD MO Status=0, MOMSN=27611, MT Status=0, MTMSN=0
2023-07-10T17:36:56.779Z,1689010616.779 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:36:59.220Z,1689010619.220 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0088.lzma
2023-07-10T17:37:00.222Z,1689010620.222 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0088.lzma.bak
2023-07-10T17:37:00.222Z,1689010620.222 [DataOverHttps](INFO): SBD MOMSN=18458760
2023-07-10T17:37:17.658Z,1689010637.658 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20230710T145831/Express0089.lzma
2023-07-10T17:37:18.654Z,1689010638.654 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0089.lzma.bak
2023-07-10T17:37:18.654Z,1689010638.654 [DataOverHttps](INFO): SBD MOMSN=18458763
2023-07-10T17:37:19.838Z,1689010639.838 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:37:19.838Z,1689010639.838 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:37:19.838Z,1689010639.838 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:37:27.477Z,1689010647.477 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:42:20.589Z,1689010940.589 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:42:20.589Z,1689010940.589 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:42:20.589Z,1689010940.589 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:42:20.589Z,1689010940.589 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:42:20.988Z,1689010940.988 [Default:CheckIn:D] Stopped
2023-07-10T17:42:20.988Z,1689010940.988 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:42:21.385Z,1689010941.385 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.819629 min 
2023-07-10T17:42:21.385Z,1689010941.385 [Default:CheckIn:E] Stopped
2023-07-10T17:42:21.385Z,1689010941.385 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:42:21.385Z,1689010941.385 [Default:CheckIn] Stopped
2023-07-10T17:42:21.385Z,1689010941.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:42:21.385Z,1689010941.385 [Default:CheckIn](INFO): Running loop #30
2023-07-10T17:42:21.385Z,1689010941.385 [Default:CheckIn] Running Loop=30
2023-07-10T17:42:21.385Z,1689010941.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:42:21.385Z,1689010941.385 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:42:23.395Z,1689010943.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174222.00,A,3647.58904,N,12151.53797,W,16.484,265.46,100723,,,D*4D
2023-07-10T17:42:23.397Z,1689010943.397 [NAL9602](INFO): GPS fix at 20230710T174222: (36.793151, -121.858966)
2023-07-10T17:42:23.409Z,1689010943.409 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:42:23.409Z,1689010943.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:42:31.039Z,1689010951.039 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230710T145831/Courier0091.lzma
2023-07-10T17:42:32.043Z,1689010952.043 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Courier0091.lzma.bak
2023-07-10T17:42:32.043Z,1689010952.043 [DataOverHttps](INFO): SBD MOMSN=18458776
2023-07-10T17:42:38.339Z,1689010958.339 [NAL9602](INFO): SBD MO Status=2, MOMSN=27612, MT Status=2, MTMSN=0
2023-07-10T17:42:38.339Z,1689010958.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T17:42:48.904Z,1689010968.904 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20230710T145831/Express0092.lzma
2023-07-10T17:42:49.907Z,1689010969.907 [DataOverHttps](INFO): Moved sent file to Logs/20230710T145831/Express0092.lzma.bak
2023-07-10T17:42:49.907Z,1689010969.907 [DataOverHttps](INFO): SBD MOMSN=18458779
2023-07-10T17:42:51.287Z,1689010971.287 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:42:51.287Z,1689010971.287 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:42:51.287Z,1689010971.287 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:42:58.139Z,1689010978.139 [NAL9602](INFO): SBD MO Status=0, MOMSN=27612, MT Status=0, MTMSN=0
2023-07-10T17:42:58.139Z,1689010978.139 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:43:28.840Z,1689011008.840 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:47:51.999Z,1689011271.999 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:47:51.999Z,1689011271.999 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:47:51.999Z,1689011271.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:47:51.999Z,1689011271.999 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:47:52.376Z,1689011272.376 [Default:CheckIn:D] Stopped
2023-07-10T17:47:52.376Z,1689011272.376 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:47:52.778Z,1689011272.778 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.342757 min 
2023-07-10T17:47:52.778Z,1689011272.778 [Default:CheckIn:E] Stopped
2023-07-10T17:47:52.778Z,1689011272.778 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:47:52.778Z,1689011272.778 [Default:CheckIn] Stopped
2023-07-10T17:47:52.778Z,1689011272.778 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:47:52.778Z,1689011272.778 [Default:CheckIn](INFO): Running loop #31
2023-07-10T17:47:52.779Z,1689011272.779 [Default:CheckIn] Running Loop=31
2023-07-10T17:47:52.779Z,1689011272.779 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:47:52.779Z,1689011272.779 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:47:54.796Z,1689011274.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174754.00,A,3647.53193,N,12153.37360,W,7.114,268.05,100723,,,D*7E
2023-07-10T17:47:54.798Z,1689011274.798 [NAL9602](INFO): GPS fix at 20230710T174754: (36.792199, -121.889560)
2023-07-10T17:47:54.810Z,1689011274.810 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:47:54.811Z,1689011274.811 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:48:06.252Z,1689011286.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:48:08.527Z,1689011288.527 [NAL9602](INFO): SBD MO Status=0, MOMSN=27613, MT Status=0, MTMSN=0
2023-07-10T17:48:08.527Z,1689011288.527 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:48:10.269Z,1689011290.269 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243446
2023-07-10T17:48:29.263Z,1689011309.263 [NAL9602](INFO): SBD MO Status=2, MOMSN=27614, MT Status=2, MTMSN=0
2023-07-10T17:48:29.263Z,1689011309.263 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T17:48:41.392Z,1689011321.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:48:55.198Z,1689011335.198 [NAL9602](INFO): SBD MO Status=2, MOMSN=27614, MT Status=2, MTMSN=0
2023-07-10T17:48:55.199Z,1689011335.199 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T17:49:16.542Z,1689011356.542 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:49:19.936Z,1689011359.936 [NAL9602](INFO): SBD MO Status=1, MOMSN=27614, MT Status=0, MTMSN=0
2023-07-10T17:49:19.986Z,1689011359.986 [NAL9602](INFO): Sent 74 bytes from file Logs/20230710T145831/Courier0094.lzma
2023-07-10T17:49:19.986Z,1689011359.986 [NAL9602](INFO): Packets left to send: 0
2023-07-10T17:49:38.575Z,1689011378.575 [NAL9602](INFO): SBD MO Status=1, MOMSN=27615, MT Status=0, MTMSN=0
2023-07-10T17:49:38.624Z,1689011378.624 [NAL9602](INFO): Sent 140 bytes from file Logs/20230710T145831/Express0095.lzma
2023-07-10T17:49:38.624Z,1689011378.624 [NAL9602](INFO): Packets left to send: 0
2023-07-10T17:49:48.267Z,1689011388.267 [NAL9602](INFO): SBD MO Status=0, MOMSN=27616, MT Status=0, MTMSN=0
2023-07-10T17:49:48.345Z,1689011388.345 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:49:48.345Z,1689011388.345 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:49:48.345Z,1689011388.345 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:49:51.701Z,1689011391.701 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:50:15.277Z,1689011415.277 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003208
2023-07-10T17:50:18.973Z,1689011418.973 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:51:16.086Z,1689011476.086 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.812801
2023-07-10T17:52:49.088Z,1689011569.088 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:53:22.909Z,1689011602.909 [DataOverHttps](IMPORTANT): SBD MTMSN=20230710T175322
2023-07-10T17:53:22.913Z,1689011602.913 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003444
2023-07-10T17:53:36.680Z,1689011616.680 [DataOverHttps](INFO): Received command: restart logs