2023-08-16T14:42:23.955Z,1692196943.955 [Supervisor](DEBUG): Initializing supervisor.
2023-08-16T14:42:23.960Z,1692196943.960 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-16T14:42:23.960Z,1692196943.960 [SyncHandler](INFO): Protected caller Thread ID is 836
2023-08-16T14:42:23.961Z,1692196943.961 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-16T14:42:23.962Z,1692196943.962 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-16T14:42:23.962Z,1692196943.962 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2023-08-16T14:42:23.966Z,1692196943.966 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-16T14:42:23.984Z,1692196943.984 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-16T14:42:23.985Z,1692196943.985 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-16T14:42:23.985Z,1692196943.985 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2023-08-16T14:42:23.989Z,1692196943.989 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-16T14:42:23.990Z,1692196943.990 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-16T14:42:23.991Z,1692196943.991 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2023-08-16T14:42:23.993Z,1692196943.993 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-16T14:42:23.994Z,1692196943.994 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-16T14:42:23.994Z,1692196943.994 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2023-08-16T14:42:23.998Z,1692196943.998 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-16T14:42:23.998Z,1692196943.998 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-16T14:42:24.002Z,1692196944.002 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-16T14:42:24.476Z,1692196944.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-16T14:42:24.477Z,1692196944.477 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-16T14:42:24.685Z,1692196944.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-16T14:42:24.687Z,1692196944.687 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-16T14:42:24.768Z,1692196944.768 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-16T14:42:24.986Z,1692196944.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-16T14:42:24.988Z,1692196944.988 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-16T14:42:25.068Z,1692196945.068 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-16T14:42:25.165Z,1692196945.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-16T14:42:25.167Z,1692196945.167 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-16T14:42:25.523Z,1692196945.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-16T14:42:25.524Z,1692196945.524 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-16T14:42:25.655Z,1692196945.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-16T14:42:25.655Z,1692196945.655 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-16T14:42:26.142Z,1692196946.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-16T14:42:26.143Z,1692196946.143 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-16T14:42:26.338Z,1692196946.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-16T14:42:26.339Z,1692196946.339 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-16T14:42:26.755Z,1692196946.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-16T14:42:26.757Z,1692196946.757 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-16T14:42:27.049Z,1692196947.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-16T14:42:27.051Z,1692196947.051 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-16T14:42:27.161Z,1692196947.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-16T14:42:27.161Z,1692196947.161 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-16T14:42:28.262Z,1692196948.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-16T14:42:28.264Z,1692196948.264 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-16T14:42:28.595Z,1692196948.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-16T14:42:28.597Z,1692196948.597 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-16T14:42:28.825Z,1692196948.825 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-16T14:42:28.827Z,1692196948.827 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-08-16T14:42:28.830Z,1692196948.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-08-16T14:42:28.956Z,1692196948.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-08-16T14:42:29.040Z,1692196949.040 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-08-16T14:42:29.181Z,1692196949.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-08-16T14:42:29.267Z,1692196949.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-08-16T14:42:29.536Z,1692196949.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-16T14:42:29.537Z,1692196949.537 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-08-16T14:42:29.655Z,1692196949.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-08-16T14:42:29.762Z,1692196949.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-08-16T14:42:29.881Z,1692196949.881 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-08-16T14:42:30.032Z,1692196950.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-08-16T14:42:30.133Z,1692196950.133 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-08-16T14:42:30.232Z,1692196950.232 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-08-16T14:42:30.249Z,1692196950.249 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-16T14:42:30.265Z,1692196950.265 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-16T14:42:30.267Z,1692196950.267 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-16T14:42:30.390Z,1692196950.390 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-16T14:42:30.391Z,1692196950.391 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-16T14:42:30.460Z,1692196950.460 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-16T14:42:30.522Z,1692196950.522 [VerticalControl] Loaded
2023-08-16T14:42:30.523Z,1692196950.523 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-16T14:42:30.525Z,1692196950.525 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-16T14:42:30.568Z,1692196950.568 [HorizontalControl] Loaded
2023-08-16T14:42:30.568Z,1692196950.568 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-16T14:42:30.570Z,1692196950.570 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-16T14:42:30.574Z,1692196950.574 [SpeedControl] Loaded
2023-08-16T14:42:30.574Z,1692196950.574 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-16T14:42:30.577Z,1692196950.577 [LoopControl](DEBUG): Construct LoopControl.
2023-08-16T14:42:30.577Z,1692196950.577 [LoopControl] Loaded
2023-08-16T14:42:30.577Z,1692196950.577 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-16T14:42:30.578Z,1692196950.578 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-16T14:42:30.578Z,1692196950.578 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-16T14:42:30.765Z,1692196950.765 [BuoyancyServo] Loaded
2023-08-16T14:42:30.765Z,1692196950.765 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-16T14:42:30.785Z,1692196950.785 [ElevatorServo] Loaded
2023-08-16T14:42:30.785Z,1692196950.785 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-16T14:42:30.805Z,1692196950.805 [MassServo] Loaded
2023-08-16T14:42:30.805Z,1692196950.805 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-16T14:42:30.823Z,1692196950.823 [RudderServo] Loaded
2023-08-16T14:42:30.824Z,1692196950.824 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-16T14:42:30.838Z,1692196950.838 [ThrusterHE] Loaded
2023-08-16T14:42:30.839Z,1692196950.839 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-08-16T14:42:30.839Z,1692196950.839 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-16T14:42:30.840Z,1692196950.840 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-16T14:42:30.909Z,1692196950.909 [DepthRateCalculator] Loaded
2023-08-16T14:42:30.909Z,1692196950.909 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-16T14:42:30.914Z,1692196950.914 [PitchRateCalculator] Loaded
2023-08-16T14:42:30.914Z,1692196950.914 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-16T14:42:30.924Z,1692196950.924 [SpeedCalculator] Loaded
2023-08-16T14:42:30.924Z,1692196950.924 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-16T14:42:30.928Z,1692196950.928 [YawRateCalculator] Loaded
2023-08-16T14:42:30.929Z,1692196950.929 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-16T14:42:30.947Z,1692196950.947 [ElevatorOffsetCalculator] Loaded
2023-08-16T14:42:30.948Z,1692196950.948 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-16T14:42:30.948Z,1692196950.948 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-16T14:42:30.949Z,1692196950.949 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-16T14:42:31.087Z,1692196951.087 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-08-16T14:42:31.088Z,1692196951.088 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-08-16T14:42:31.102Z,1692196951.102 [NavChart] Loaded
2023-08-16T14:42:31.102Z,1692196951.102 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-16T14:42:31.107Z,1692196951.107 [UniversalFixResidualReporter] Loaded
2023-08-16T14:42:31.108Z,1692196951.108 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-16T14:42:31.108Z,1692196951.108 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-16T14:42:31.109Z,1692196951.109 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-16T14:42:31.848Z,1692196951.848 [AHRS_M2] Loaded
2023-08-16T14:42:31.848Z,1692196951.848 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-16T14:42:32.098Z,1692196952.098 [BackseatComponent] Loaded
2023-08-16T14:42:32.098Z,1692196952.098 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-08-16T14:42:32.099Z,1692196952.099 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E64E0
2023-08-16T14:42:32.099Z,1692196952.099 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922
2023-08-16T14:42:32.102Z,1692196952.102 [LcmUniversalReporter] Loaded
2023-08-16T14:42:32.103Z,1692196952.103 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-08-16T14:42:32.109Z,1692196952.109 [BioacousticsDataBridge] Loaded
2023-08-16T14:42:32.109Z,1692196952.109 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2023-08-16T14:42:32.942Z,1692196952.942 [BPC1] Loaded
2023-08-16T14:42:32.942Z,1692196952.942 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-16T14:42:33.085Z,1692196953.085 [DataOverHttps] Loaded
2023-08-16T14:42:33.086Z,1692196953.086 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-16T14:42:33.087Z,1692196953.087 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409164E0
2023-08-16T14:42:33.087Z,1692196953.087 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923
2023-08-16T14:42:33.108Z,1692196953.108 [Depth_Keller] Loaded
2023-08-16T14:42:33.108Z,1692196953.108 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-16T14:42:33.113Z,1692196953.113 [DropWeight] Loaded
2023-08-16T14:42:33.114Z,1692196953.114 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-16T14:42:33.191Z,1692196953.191 [NAL9602] Loaded
2023-08-16T14:42:33.192Z,1692196953.192 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-16T14:42:33.252Z,1692196953.252 [Onboard] Loaded
2023-08-16T14:42:33.261Z,1692196953.261 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-16T14:42:33.262Z,1692196953.262 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409464E0
2023-08-16T14:42:33.262Z,1692196953.262 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924
2023-08-16T14:42:33.310Z,1692196953.310 [Power24vConverter] Loaded
2023-08-16T14:42:33.311Z,1692196953.311 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-16T14:42:33.368Z,1692196953.368 [Radio_Surface] Loaded
2023-08-16T14:42:33.369Z,1692196953.369 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-16T14:42:33.385Z,1692196953.385 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409764E0
2023-08-16T14:42:33.385Z,1692196953.385 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925
2023-08-16T14:42:33.414Z,1692196953.414 [Sonardyne_Nano] Loaded
2023-08-16T14:42:33.415Z,1692196953.415 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-08-16T14:42:33.415Z,1692196953.415 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-16T14:42:33.416Z,1692196953.416 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-16T14:42:34.063Z,1692196954.063 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-16T14:42:34.063Z,1692196954.063 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-16T14:42:34.246Z,1692196954.246 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-16T14:42:34.247Z,1692196954.247 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-16T14:42:34.289Z,1692196954.289 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-16T14:42:34.290Z,1692196954.290 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-16T14:42:34.510Z,1692196954.510 [CTD_Seabird] Loaded
2023-08-16T14:42:34.510Z,1692196954.510 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-16T14:42:34.511Z,1692196954.511 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0
2023-08-16T14:42:34.512Z,1692196954.512 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926
2023-08-16T14:42:34.532Z,1692196954.532 [PAR_Licor] Loaded
2023-08-16T14:42:34.532Z,1692196954.532 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-16T14:42:34.573Z,1692196954.573 [WetLabsBB2FL] Loaded
2023-08-16T14:42:34.573Z,1692196954.573 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-16T14:42:34.574Z,1692196954.574 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ADD4E0
2023-08-16T14:42:34.575Z,1692196954.575 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927
2023-08-16T14:42:34.575Z,1692196954.575 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-16T14:42:34.576Z,1692196954.576 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-16T14:42:34.930Z,1692196954.930 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-16T14:42:34.932Z,1692196954.932 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-16T14:42:35.100Z,1692196955.100 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-16T14:42:35.109Z,1692196955.109 [SBIT] Loaded
2023-08-16T14:42:35.109Z,1692196955.109 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-16T14:42:35.112Z,1692196955.112 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-16T14:42:35.126Z,1692196955.126 [IBIT] Loaded
2023-08-16T14:42:35.126Z,1692196955.126 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-16T14:42:35.132Z,1692196955.132 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-16T14:42:35.238Z,1692196955.238 [CBIT] Loaded
2023-08-16T14:42:35.238Z,1692196955.238 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-16T14:42:35.238Z,1692196955.238 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-16T14:42:35.244Z,1692196955.244 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-16T14:42:35.247Z,1692196955.247 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-16T14:42:35.259Z,1692196955.259 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-16T14:42:35.260Z,1692196955.260 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC94E0
2023-08-16T14:42:35.260Z,1692196955.260 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2023-08-16T14:42:35.265Z,1692196955.265 [Supervisor](INFO): Main Thread ID is 829
2023-08-16T14:42:35.265Z,1692196955.265 [Supervisor](DEBUG): Running supervisor.
2023-08-16T14:42:35.265Z,1692196955.265 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2023-08-16T14:42:35.266Z,1692196955.266 [CommandExec](INFO): Initializing the command executive.
2023-08-16T14:42:35.267Z,1692196955.267 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2023-08-16T14:42:35.269Z,1692196955.269 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2023-08-16T14:42:35.270Z,1692196955.270 [controlThread](DEBUG): Initializing ControlThread
2023-08-16T14:42:35.271Z,1692196955.271 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-16T14:42:35.272Z,1692196955.272 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-16T14:42:35.273Z,1692196955.273 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-16T14:42:35.274Z,1692196955.274 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-16T14:42:35.275Z,1692196955.275 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-16T14:42:35.275Z,1692196955.275 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-16T14:42:35.276Z,1692196955.276 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-16T14:42:35.276Z,1692196955.276 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-16T14:42:35.277Z,1692196955.277 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-16T14:42:35.278Z,1692196955.278 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-16T14:42:35.278Z,1692196955.278 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-16T14:42:35.285Z,1692196955.285 [SBIT](INFO): Initialize SBIT Component.
2023-08-16T14:42:35.286Z,1692196955.286 [SBIT](IMPORTANT): git: 2023-08-02
2023-08-16T14:42:35.286Z,1692196955.286 [SBIT](INFO): git hash: 04bff54855c04b79675eb3036915d66bd94ee3d9
2023-08-16T14:42:35.286Z,1692196955.286 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-16T14:42:35.287Z,1692196955.287 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-08-16T14:42:35.288Z,1692196955.288 [SBIT](INFO): Beginning SBIT in 116.000000 seconds.
2023-08-16T14:42:35.289Z,1692196955.289 [IBIT](INFO): Initialize IBIT Component.
2023-08-16T14:42:35.290Z,1692196955.290 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-16T14:42:35.291Z,1692196955.291 [logger ThreadHandler](INFO): Handler Thread ID is 932
2023-08-16T14:42:35.301Z,1692196955.301 [CBIT](DEBUG): Initialized mux pins.
2023-08-16T14:42:35.301Z,1692196955.301 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-16T14:42:35.309Z,1692196955.309 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933
2023-08-16T14:42:35.321Z,1692196955.321 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2023-08-16T14:42:35.322Z,1692196955.322 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-16T14:42:35.325Z,1692196955.325 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-16T14:42:35.325Z,1692196955.325 [CBIT](DEBUG): Initializing heartbeat.
2023-08-16T14:42:35.333Z,1692196955.333 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2023-08-16T14:42:35.351Z,1692196955.351 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2023-08-16T14:42:35.370Z,1692196955.370 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 937
2023-08-16T14:42:35.371Z,1692196955.371 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-16T14:42:35.374Z,1692196955.374 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 939
2023-08-16T14:42:35.377Z,1692196955.377 [WetLabsBB2FL](INFO): Powering up
2023-08-16T14:42:35.379Z,1692196955.379 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941
2023-08-16T14:42:35.387Z,1692196955.387 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-08-16T14:42:35.388Z,1692196955.388 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-08-16T14:42:35.388Z,1692196955.388 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-08-16T14:42:35.388Z,1692196955.388 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-08-16T14:42:35.388Z,1692196955.388 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-08-16T14:42:35.388Z,1692196955.388 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-08-16T14:42:35.388Z,1692196955.388 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-08-16T14:42:35.389Z,1692196955.389 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-08-16T14:42:35.389Z,1692196955.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-08-16T14:42:35.389Z,1692196955.389 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-08-16T14:42:35.390Z,1692196955.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-08-16T14:42:35.390Z,1692196955.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-08-16T14:42:35.390Z,1692196955.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000
2023-08-16T14:42:35.390Z,1692196955.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000
2023-08-16T14:42:35.390Z,1692196955.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000
2023-08-16T14:42:35.390Z,1692196955.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000
2023-08-16T14:42:35.391Z,1692196955.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-08-16T14:42:35.391Z,1692196955.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-08-16T14:42:35.391Z,1692196955.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-08-16T14:42:35.391Z,1692196955.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-08-16T14:42:35.391Z,1692196955.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-08-16T14:42:35.391Z,1692196955.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-08-16T14:42:35.392Z,1692196955.392 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-08-16T14:42:35.392Z,1692196955.392 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-08-16T14:42:35.392Z,1692196955.392 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-08-16T14:42:35.392Z,1692196955.392 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-08-16T14:42:35.397Z,1692196955.397 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-16T14:42:35.398Z,1692196955.398 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-16T14:42:35.437Z,1692196955.437 [CBIT](DEBUG): Backplane powered.
2023-08-16T14:42:35.438Z,1692196955.438 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-16T14:42:35.468Z,1692196955.468 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-16T14:42:35.499Z,1692196955.499 [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-08-16T14:42:35.500Z,1692196955.500 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-16T14:42:35.598Z,1692196955.598 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-16T14:42:35.599Z,1692196955.599 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-16T14:42:35.601Z,1692196955.601 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-16T14:42:35.632Z,1692196955.632 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-16T14:42:35.651Z,1692196955.651 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-16T14:42:35.669Z,1692196955.669 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-16T14:42:35.673Z,1692196955.673 [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-08-16T14:42:35.693Z,1692196955.693 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-08-16T14:42:35.699Z,1692196955.699 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-16T14:42:35.757Z,1692196955.757 [Radio_Surface](INFO): Powering up
2023-08-16T14:42:35.892Z,1692196955.892 [Power24vConverter](INFO): Powering up.
2023-08-16T14:42:35.893Z,1692196955.893 [Sonardyne_Nano](INFO): Initializing.
2023-08-16T14:42:35.963Z,1692196955.963 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-16T14:42:35.969Z,1692196955.969 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-16T14:42:35.971Z,1692196955.971 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-16T14:42:35.977Z,1692196955.977 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-16T14:42:35.978Z,1692196955.978 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-16T14:42:35.985Z,1692196955.985 [MassServo](DEBUG): Initializing MassServo.
2023-08-16T14:42:35.986Z,1692196955.986 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-16T14:42:35.994Z,1692196955.994 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-16T14:42:35.995Z,1692196955.995 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-16T14:42:36.001Z,1692196956.001 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-16T14:42:37.485Z,1692196957.485 [WetLabsBB2FL](INFO): Powering down
2023-08-16T14:42:38.127Z,1692196958.127 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-08-16T14:42:39.366Z,1692196959.366 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2201
2023-08-16T14:42:41.622Z,1692196961.622 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-08-16T14:43:03.587Z,1692196983.587 [NAL9602](INFO): Powering up NAL9602
2023-08-16T14:43:14.499Z,1692196994.499 [NAL9602](INFO): NAL9602 initialized
2023-08-16T14:44:17.923Z,1692197057.923 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-16T14:44:32.076Z,1692197072.076 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-16T14:44:32.080Z,1692197072.080 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-16T14:44:43.032Z,1692197083.032 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006125
CHAN A1 (24V): -0.007262
CHAN A2 (12V): -0.004470
CHAN A3 (5V): -0.002748
CHAN B0 (3.3V): -0.001004
CHAN B1 (3.15aV): -0.000867
CHAN B2 (3.15bV): -0.001084
CHAN B3 (GND): -0.000168
OPEN: -0.004570
Full Scale: +/- 1 mA
2023-08-16T14:45:13.273Z,1692197113.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144503.00,A,4313.61912,N,08620.36516,W,1.419,0.00,160823,,,A*7B
2023-08-16T14:45:13.276Z,1692197113.276 [NAL9602](INFO): GPS fix at 20230816T144503: (43.226985, -86.339419)
2023-08-16T14:45:26.237Z,1692197126.237 [SBIT](IMPORTANT): SBIT PASSED
2023-08-16T14:45:26.238Z,1692197126.238 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-08-16T14:45:26.239Z,1692197126.239 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-08-16T14:45:26.239Z,1692197126.239 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-08-16T14:45:26.239Z,1692197126.239 [SBIT](IMPORTANT): Express linearApproximation BackseatComponent.handled_message 1.000000 bool;

2023-08-16T14:45:26.239Z,1692197126.239 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius;

2023-08-16T14:45:26.239Z,1692197126.239 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-08-16T14:45:26.239Z,1692197126.239 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-08-16T14:45:26.239Z,1692197126.239 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter;

2023-08-16T14:45:26.239Z,1692197126.239 [SBIT](IMPORTANT): Express none platform_roll_angle;

2023-08-16T14:45:26.240Z,1692197126.240 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2023-08-16T14:45:26.240Z,1692197126.240 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=1 minute;

2023-08-16T14:45:26.240Z,1692197126.240 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-08-16T14:45:26.240Z,1692197126.240 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=427.503748 cubic_centimeter;

2023-08-16T14:45:26.240Z,1692197126.240 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-08-16T14:45:26.240Z,1692197126.240 [SBIT](IMPORTANT): VerticalControl.massDefault=24.156915 millimeter;

2023-08-16T14:45:26.612Z,1692197126.612 [MissionManager](IMPORTANT): Started mission Startup
2023-08-16T14:45:26.613Z,1692197126.613 [Startup] Running Loop=1
2023-08-16T14:45:26.613Z,1692197126.613 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-16T14:45:26.613Z,1692197126.613 [Startup:A.GoToSurface] Running Loop=1
2023-08-16T14:45:26.613Z,1692197126.613 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-16T14:45:26.614Z,1692197126.614 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-16T14:45:26.614Z,1692197126.614 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-16T14:45:26.614Z,1692197126.614 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-16T14:45:26.615Z,1692197126.615 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-16T14:45:26.615Z,1692197126.615 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-16T14:45:26.616Z,1692197126.616 [Startup:StartupSatComms] Running Loop=1
2023-08-16T14:45:26.617Z,1692197126.617 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-16T14:45:26.617Z,1692197126.617 [Startup:StartupSatComms:A] Running Loop=1
2023-08-16T14:45:27.022Z,1692197127.022 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-16T14:45:28.630Z,1692197128.630 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144518.00,A,4313.61748,N,08620.36443,W,1.089,0.00,160823,,,A*7C
2023-08-16T14:45:28.633Z,1692197128.633 [NAL9602](INFO): GPS fix at 20230816T144518: (43.226958, -86.339407)
2023-08-16T14:45:28.643Z,1692197128.643 [Startup:StartupSatComms:A] Stopped
2023-08-16T14:45:28.643Z,1692197128.643 [Startup:StartupSatComms:B] Running Loop=1
2023-08-16T14:45:29.062Z,1692197129.062 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-16T14:45:53.010Z,1692197153.010 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003975
2023-08-16T14:46:02.303Z,1692197162.303 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230816T045913/Courier0229.lzma
2023-08-16T14:46:03.306Z,1692197163.306 [DataOverHttps](INFO): Moved sent file to Logs/20230816T045913/Courier0229.lzma.bak
2023-08-16T14:46:03.307Z,1692197163.307 [DataOverHttps](INFO): SBD MOMSN=18563933
2023-08-16T14:46:19.130Z,1692197179.130 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230816T121419/Courier0000.lzma
2023-08-16T14:46:20.130Z,1692197180.130 [DataOverHttps](INFO): Moved sent file to Logs/20230816T121419/Courier0000.lzma.bak
2023-08-16T14:46:20.130Z,1692197180.130 [DataOverHttps](INFO): SBD MOMSN=18563935
2023-08-16T14:46:26.688Z,1692197186.688 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T14:46:28.712Z,1692197188.712 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-16T14:45:28.6Z
2023-08-16T14:46:28.712Z,1692197188.712 [Startup:StartupSatComms:B] Stopped
2023-08-16T14:46:28.712Z,1692197188.712 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-16T14:46:28.732Z,1692197188.732 [Startup:StartupSatComms] Stopped
2023-08-16T14:46:28.732Z,1692197188.732 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-16T14:46:28.733Z,1692197188.733 [Startup](INFO): Completed Startup
2023-08-16T14:46:28.733Z,1692197188.733 [MissionManager](INFO): Startup is completed.
2023-08-16T14:46:28.733Z,1692197188.733 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-16T14:46:28.733Z,1692197188.733 [Startup] Stopped
2023-08-16T14:46:28.733Z,1692197188.733 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-16T14:46:28.734Z,1692197188.734 [Startup:A.GoToSurface] Stopped
2023-08-16T14:46:28.734Z,1692197188.734 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-16T14:46:29.158Z,1692197189.158 [MissionManager](IMPORTANT): Started mission Default
2023-08-16T14:46:29.158Z,1692197189.158 [Default] Running Loop=1
2023-08-16T14:46:29.158Z,1692197189.158 [Default](DEBUG): Aggregate::initialize Default
2023-08-16T14:46:29.158Z,1692197189.158 [Default:B.GoToSurface] Running Loop=1
2023-08-16T14:46:29.159Z,1692197189.159 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-16T14:46:29.159Z,1692197189.159 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-16T14:46:29.159Z,1692197189.159 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-16T14:46:29.159Z,1692197189.159 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-16T14:46:29.160Z,1692197189.160 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-16T14:46:29.212Z,1692197189.212 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-16T14:46:29.232Z,1692197189.232 [Default:A.Wait] Running Loop=1
2023-08-16T14:46:29.232Z,1692197189.232 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-16T14:46:40.581Z,1692197200.581 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230816T121419/Courier0004.lzma
2023-08-16T14:46:41.582Z,1692197201.582 [DataOverHttps](INFO): Moved sent file to Logs/20230816T121419/Courier0004.lzma.bak
2023-08-16T14:46:41.582Z,1692197201.582 [DataOverHttps](INFO): SBD MOMSN=18563939
2023-08-16T14:46:42.541Z,1692197202.541 [Default:A.Wait](INFO): Done Waiting.
2023-08-16T14:46:42.542Z,1692197202.542 [Default:A.Wait] Stopped
2023-08-16T14:46:42.542Z,1692197202.542 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T14:46:42.952Z,1692197202.952 [Default:CheckIn] Running Loop=1
2023-08-16T14:46:42.953Z,1692197202.953 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T14:46:42.953Z,1692197202.953 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T14:46:43.373Z,1692197203.373 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-16T14:46:45.213Z,1692197205.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144643.00,A,4313.61047,N,08620.33576,W,0.914,0.00,160823,,,A*77
2023-08-16T14:46:45.215Z,1692197205.215 [NAL9602](INFO): GPS fix at 20230816T144643: (43.226841, -86.338929)
2023-08-16T14:46:45.255Z,1692197205.255 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T14:46:45.255Z,1692197205.255 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T14:46:45.522Z,1692197205.522 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-16T14:47:02.957Z,1692197222.957 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230816T121419/Courier0007.lzma
2023-08-16T14:47:03.958Z,1692197223.958 [DataOverHttps](INFO): Moved sent file to Logs/20230816T121419/Courier0007.lzma.bak
2023-08-16T14:47:03.958Z,1692197223.958 [DataOverHttps](INFO): SBD MOMSN=18563942
2023-08-16T14:47:23.053Z,1692197243.053 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20230816T135918/Courier0000.lzma
2023-08-16T14:47:24.054Z,1692197244.054 [DataOverHttps](INFO): Moved sent file to Logs/20230816T135918/Courier0000.lzma.bak
2023-08-16T14:47:24.054Z,1692197244.054 [DataOverHttps](INFO): SBD MOMSN=18563944
2023-08-16T14:47:42.197Z,1692197262.197 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230816T135918/Courier0004.lzma
2023-08-16T14:47:43.198Z,1692197263.198 [DataOverHttps](INFO): Moved sent file to Logs/20230816T135918/Courier0004.lzma.bak
2023-08-16T14:47:43.198Z,1692197263.198 [DataOverHttps](INFO): SBD MOMSN=18563948
2023-08-16T14:47:47.233Z,1692197267.233 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-16T14:47:59.799Z,1692197279.799 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230816T135918/Courier0007.lzma
2023-08-16T14:48:00.802Z,1692197280.802 [DataOverHttps](INFO): Moved sent file to Logs/20230816T135918/Courier0007.lzma.bak
2023-08-16T14:48:00.802Z,1692197280.802 [DataOverHttps](INFO): SBD MOMSN=18563951
2023-08-16T14:48:17.753Z,1692197297.753 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230816T140758/Courier0000.lzma
2023-08-16T14:48:17.938Z,1692197297.938 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T14:48:18.754Z,1692197298.754 [DataOverHttps](INFO): Moved sent file to Logs/20230816T140758/Courier0000.lzma.bak
2023-08-16T14:48:18.754Z,1692197298.754 [DataOverHttps](INFO): SBD MOMSN=18563953
2023-08-16T14:48:32.662Z,1692197312.662 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T14:48:38.705Z,1692197318.705 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230816T140758/Courier0004.lzma
2023-08-16T14:48:38.998Z,1692197318.998 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-16T14:48:39.001Z,1692197319.001 [BPC1](INFO): Received data from all battery sticks.
2023-08-16T14:48:39.706Z,1692197319.706 [DataOverHttps](INFO): Moved sent file to Logs/20230816T140758/Courier0004.lzma.bak
2023-08-16T14:48:39.706Z,1692197319.706 [DataOverHttps](INFO): SBD MOMSN=18563957
2023-08-16T14:49:00.067Z,1692197340.067 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230816T140758/Courier0007.lzma
2023-08-16T14:49:01.070Z,1692197341.070 [DataOverHttps](INFO): Moved sent file to Logs/20230816T140758/Courier0007.lzma.bak
2023-08-16T14:49:01.070Z,1692197341.070 [DataOverHttps](INFO): SBD MOMSN=18563960
2023-08-16T14:49:16.829Z,1692197356.829 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230816T141610/Courier0000.lzma
2023-08-16T14:49:17.830Z,1692197357.830 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Courier0000.lzma.bak
2023-08-16T14:49:17.830Z,1692197357.830 [DataOverHttps](INFO): SBD MOMSN=18563962
2023-08-16T14:49:33.573Z,1692197373.573 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230816T141610/Courier0004.lzma
2023-08-16T14:49:34.574Z,1692197374.574 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Courier0004.lzma.bak
2023-08-16T14:49:34.574Z,1692197374.574 [DataOverHttps](INFO): SBD MOMSN=18563966
2023-08-16T14:49:50.329Z,1692197390.329 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230816T141610/Courier0007.lzma
2023-08-16T14:49:51.330Z,1692197391.330 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Courier0007.lzma.bak
2023-08-16T14:49:51.330Z,1692197391.330 [DataOverHttps](INFO): SBD MOMSN=18563969
2023-08-16T14:50:07.061Z,1692197407.061 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230816T141610/Courier0010.lzma
2023-08-16T14:50:08.062Z,1692197408.062 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Courier0010.lzma.bak
2023-08-16T14:50:08.062Z,1692197408.062 [DataOverHttps](INFO): SBD MOMSN=18563972
2023-08-16T14:50:23.848Z,1692197423.848 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230816T141610/Courier0013.lzma
2023-08-16T14:50:24.846Z,1692197424.846 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Courier0013.lzma.bak
2023-08-16T14:50:24.846Z,1692197424.846 [DataOverHttps](INFO): SBD MOMSN=18563975
2023-08-16T14:50:47.994Z,1692197447.994 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230816T143231/Courier0000.lzma
2023-08-16T14:50:50.497Z,1692197450.497 [DataOverHttps](INFO): Moved sent file to Logs/20230816T143231/Courier0000.lzma.bak
2023-08-16T14:50:50.497Z,1692197450.497 [DataOverHttps](INFO): SBD MOMSN=18563977
2023-08-16T14:51:06.617Z,1692197466.617 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230816T143231/Courier0004.lzma
2023-08-16T14:51:07.618Z,1692197467.618 [DataOverHttps](INFO): Moved sent file to Logs/20230816T143231/Courier0004.lzma.bak
2023-08-16T14:51:07.618Z,1692197467.618 [DataOverHttps](INFO): SBD MOMSN=18563981
2023-08-16T14:51:34.445Z,1692197494.445 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20230816T143231/Courier0007.lzma
2023-08-16T14:51:35.446Z,1692197495.446 [DataOverHttps](INFO): Moved sent file to Logs/20230816T143231/Courier0007.lzma.bak
2023-08-16T14:51:35.446Z,1692197495.446 [DataOverHttps](INFO): SBD MOMSN=18563984
2023-08-16T14:51:51.100Z,1692197511.100 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230816T144223/Courier0000.lzma
2023-08-16T14:51:52.102Z,1692197512.102 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0000.lzma.bak
2023-08-16T14:51:52.102Z,1692197512.102 [DataOverHttps](INFO): SBD MOMSN=18563987
2023-08-16T14:52:07.864Z,1692197527.864 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230816T144223/Courier0004.lzma
2023-08-16T14:52:10.645Z,1692197530.645 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0004.lzma.bak
2023-08-16T14:52:10.645Z,1692197530.645 [DataOverHttps](INFO): SBD MOMSN=18563991
2023-08-16T14:52:27.156Z,1692197547.156 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230816T045913/Express0230.lzma
2023-08-16T14:52:28.158Z,1692197548.158 [DataOverHttps](INFO): Moved sent file to Logs/20230816T045913/Express0230.lzma.bak
2023-08-16T14:52:28.159Z,1692197548.159 [DataOverHttps](INFO): SBD MOMSN=18563994
2023-08-16T14:52:36.802Z,1692197556.802 [Power24vConverter](INFO): Powering down.
2023-08-16T14:52:40.067Z,1692197560.067 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2023-08-16T14:52:53.179Z,1692197573.179 [DataOverHttps](INFO): Sending 1246 bytes from file Logs/20230816T121419/Express0001.lzma
2023-08-16T14:52:54.178Z,1692197574.178 [DataOverHttps](INFO): Moved sent file to Logs/20230816T121419/Express0001.lzma.bak
2023-08-16T14:52:54.178Z,1692197574.178 [DataOverHttps](INFO): SBD MOMSN=18563996
2023-08-16T14:53:11.301Z,1692197591.301 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20230816T121419/Express0005.lzma
2023-08-16T14:53:12.302Z,1692197592.302 [DataOverHttps](INFO): Moved sent file to Logs/20230816T121419/Express0005.lzma.bak
2023-08-16T14:53:12.302Z,1692197592.302 [DataOverHttps](INFO): SBD MOMSN=18564026
2023-08-16T14:53:29.818Z,1692197609.818 [DataOverHttps](INFO): Sending 380 bytes from file Logs/20230816T121419/Express0008.lzma
2023-08-16T14:53:30.818Z,1692197610.818 [DataOverHttps](INFO): Moved sent file to Logs/20230816T121419/Express0008.lzma.bak
2023-08-16T14:53:30.818Z,1692197610.818 [DataOverHttps](INFO): SBD MOMSN=18564030
2023-08-16T14:53:48.219Z,1692197628.219 [DataOverHttps](INFO): Sending 1296 bytes from file Logs/20230816T135918/Express0001.lzma
2023-08-16T14:53:49.218Z,1692197629.218 [DataOverHttps](INFO): Moved sent file to Logs/20230816T135918/Express0001.lzma.bak
2023-08-16T14:53:49.218Z,1692197629.218 [DataOverHttps](INFO): SBD MOMSN=18564038
2023-08-16T14:54:08.009Z,1692197648.009 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20230816T135918/Express0005.lzma
2023-08-16T14:54:09.010Z,1692197649.010 [DataOverHttps](INFO): Moved sent file to Logs/20230816T135918/Express0005.lzma.bak
2023-08-16T14:54:09.010Z,1692197649.010 [DataOverHttps](INFO): SBD MOMSN=18564070
2023-08-16T14:54:26.255Z,1692197666.255 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230816T135918/Express0008.lzma
2023-08-16T14:54:27.258Z,1692197667.258 [DataOverHttps](INFO): Moved sent file to Logs/20230816T135918/Express0008.lzma.bak
2023-08-16T14:54:27.259Z,1692197667.259 [DataOverHttps](INFO): SBD MOMSN=18564073
2023-08-16T14:54:54.131Z,1692197694.131 [DataOverHttps](INFO): Sending 1516 bytes from file Logs/20230816T140758/Express0001.lzma
2023-08-16T14:54:55.130Z,1692197695.130 [DataOverHttps](INFO): Moved sent file to Logs/20230816T140758/Express0001.lzma.bak
2023-08-16T14:54:55.130Z,1692197695.130 [DataOverHttps](INFO): SBD MOMSN=18564075
2023-08-16T14:55:11.688Z,1692197711.688 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20230816T140758/Express0005.lzma
2023-08-16T14:55:12.689Z,1692197712.689 [DataOverHttps](INFO): Moved sent file to Logs/20230816T140758/Express0005.lzma.bak
2023-08-16T14:55:12.690Z,1692197712.690 [DataOverHttps](INFO): SBD MOMSN=18564107
2023-08-16T14:55:31.523Z,1692197731.523 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230816T140758/Express0008.lzma
2023-08-16T14:55:32.525Z,1692197732.525 [DataOverHttps](INFO): Moved sent file to Logs/20230816T140758/Express0008.lzma.bak
2023-08-16T14:55:32.525Z,1692197732.525 [DataOverHttps](INFO): SBD MOMSN=18564111
2023-08-16T14:55:49.694Z,1692197749.694 [DataOverHttps](INFO): Sending 1239 bytes from file Logs/20230816T141610/Express0001.lzma
2023-08-16T14:55:50.693Z,1692197750.693 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Express0001.lzma.bak
2023-08-16T14:55:50.693Z,1692197750.693 [DataOverHttps](INFO): SBD MOMSN=18564113
2023-08-16T14:56:09.080Z,1692197769.080 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20230816T141610/Express0005.lzma
2023-08-16T14:56:10.081Z,1692197770.081 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Express0005.lzma.bak
2023-08-16T14:56:10.081Z,1692197770.081 [DataOverHttps](INFO): SBD MOMSN=18564143
2023-08-16T14:56:27.397Z,1692197787.397 [DataOverHttps](INFO): Sending 469 bytes from file Logs/20230816T141610/Express0008.lzma
2023-08-16T14:56:28.377Z,1692197788.377 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Express0008.lzma.bak
2023-08-16T14:56:28.377Z,1692197788.377 [DataOverHttps](INFO): SBD MOMSN=18564146
2023-08-16T14:56:52.121Z,1692197812.121 [DataOverHttps](INFO): Sending 593 bytes from file Logs/20230816T141610/Express0011.lzma
2023-08-16T14:56:53.121Z,1692197813.121 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Express0011.lzma.bak
2023-08-16T14:56:53.121Z,1692197813.121 [DataOverHttps](INFO): SBD MOMSN=18564156
2023-08-16T14:57:11.892Z,1692197831.892 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20230816T141610/Express0014.lzma
2023-08-16T14:57:12.893Z,1692197832.893 [DataOverHttps](INFO): Moved sent file to Logs/20230816T141610/Express0014.lzma.bak
2023-08-16T14:57:12.893Z,1692197832.893 [DataOverHttps](INFO): SBD MOMSN=18564163
2023-08-16T14:57:30.410Z,1692197850.410 [DataOverHttps](INFO): Sending 1197 bytes from file Logs/20230816T143231/Express0001.lzma
2023-08-16T14:57:31.412Z,1692197851.412 [DataOverHttps](INFO): Moved sent file to Logs/20230816T143231/Express0001.lzma.bak
2023-08-16T14:57:31.412Z,1692197851.412 [DataOverHttps](INFO): SBD MOMSN=18564167
2023-08-16T14:57:49.820Z,1692197869.820 [DataOverHttps](INFO): Sending 114 bytes from file Logs/20230816T143231/Express0005.lzma
2023-08-16T14:57:50.825Z,1692197870.825 [DataOverHttps](INFO): Moved sent file to Logs/20230816T143231/Express0005.lzma.bak
2023-08-16T14:57:50.825Z,1692197870.825 [DataOverHttps](INFO): SBD MOMSN=18564194
2023-08-16T14:58:08.033Z,1692197888.033 [DataOverHttps](INFO): Sending 730 bytes from file Logs/20230816T143231/Express0008.lzma
2023-08-16T14:58:09.034Z,1692197889.034 [DataOverHttps](INFO): Moved sent file to Logs/20230816T143231/Express0008.lzma.bak
2023-08-16T14:58:09.034Z,1692197889.034 [DataOverHttps](INFO): SBD MOMSN=18564197
2023-08-16T14:58:28.081Z,1692197908.081 [DataOverHttps](INFO): Sending 1193 bytes from file Logs/20230816T144223/Express0001.lzma
2023-08-16T14:58:29.081Z,1692197909.081 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0001.lzma.bak
2023-08-16T14:58:29.081Z,1692197909.081 [DataOverHttps](INFO): SBD MOMSN=18564223
2023-08-16T14:58:53.191Z,1692197933.191 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20230816T144223/Express0005.lzma
2023-08-16T14:58:54.193Z,1692197934.193 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0005.lzma.bak
2023-08-16T14:58:54.193Z,1692197934.193 [DataOverHttps](INFO): SBD MOMSN=18564250
2023-08-16T14:58:57.093Z,1692197937.093 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T14:58:57.093Z,1692197937.093 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T14:58:57.093Z,1692197937.093 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:03:57.269Z,1692198237.269 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T150356
2023-08-16T15:03:57.582Z,1692198237.582 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:03:57.582Z,1692198237.582 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:03:57.583Z,1692198237.583 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:03:57.583Z,1692198237.583 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:03:57.986Z,1692198237.986 [Default:CheckIn:D] Stopped
2023-08-16T15:03:57.986Z,1692198237.986 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:03:58.387Z,1692198238.387 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.480459 min 
2023-08-16T15:03:58.387Z,1692198238.387 [Default:CheckIn:E] Stopped
2023-08-16T15:03:58.387Z,1692198238.387 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:03:58.387Z,1692198238.387 [Default:CheckIn] Stopped
2023-08-16T15:03:58.387Z,1692198238.387 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:03:58.388Z,1692198238.388 [Default:CheckIn](INFO): Running loop #2
2023-08-16T15:03:58.388Z,1692198238.388 [Default:CheckIn] Running Loop=2
2023-08-16T15:03:58.388Z,1692198238.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:03:58.388Z,1692198238.388 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:04:00.394Z,1692198240.394 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150359.00,A,4313.61653,N,08620.34034,W,0.039,0.00,160823,,,A*7D
2023-08-16T15:04:00.401Z,1692198240.401 [NAL9602](INFO): GPS fix at 20230816T150359: (43.226942, -86.339006)
2023-08-16T15:04:00.440Z,1692198240.440 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:04:00.440Z,1692198240.440 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:04:04.807Z,1692198244.807 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230816T144223/Courier0007.lzma
2023-08-16T15:04:04.810Z,1692198244.810 [DataOverHttps](INFO): Received command: failc
2023-08-16T15:04:04.912Z,1692198244.912 [CommandExec](IMPORTANT): got command failComponent
2023-08-16T15:04:04.912Z,1692198244.912 [CommandExec](IMPORTANT): Failed components:
2023-08-16T15:04:04.913Z,1692198244.913 [CommandExec](IMPORTANT): No failed Components.
2023-08-16T15:04:05.204Z,1692198245.204 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0007.lzma.bak
2023-08-16T15:04:05.204Z,1692198245.204 [DataOverHttps](INFO): SBD MOMSN=18564254
2023-08-16T15:04:20.983Z,1692198260.983 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230816T144223/Courier0010.lzma
2023-08-16T15:04:21.985Z,1692198261.985 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0010.lzma.bak
2023-08-16T15:04:21.985Z,1692198261.985 [DataOverHttps](INFO): SBD MOMSN=18564258
2023-08-16T15:04:31.194Z,1692198271.194 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-16T15:04:31.268Z,1692198271.268 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-08-16T15:04:47.276Z,1692198287.276 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20230816T144223/Express0008.lzma
2023-08-16T15:04:48.281Z,1692198288.281 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0008.lzma.bak
2023-08-16T15:04:48.281Z,1692198288.281 [DataOverHttps](INFO): SBD MOMSN=18564260
2023-08-16T15:05:02.397Z,1692198302.397 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-16T15:05:06.532Z,1692198306.532 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230816T144223/Express0011.lzma
2023-08-16T15:05:07.534Z,1692198307.534 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0011.lzma.bak
2023-08-16T15:05:07.534Z,1692198307.534 [DataOverHttps](INFO): SBD MOMSN=18564265
2023-08-16T15:05:10.107Z,1692198310.107 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:05:10.107Z,1692198310.107 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:05:10.107Z,1692198310.107 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:05:33.105Z,1692198333.105 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:07:47.909Z,1692198467.909 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T150747
2023-08-16T15:07:55.295Z,1692198475.295 [DataOverHttps](INFO): Received command: ibit
2023-08-16T15:07:55.340Z,1692198475.340 [CommandExec](IMPORTANT): got command ibit
2023-08-16T15:07:55.398Z,1692198475.398 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-08-16T15:07:55.398Z,1692198475.398 [IBIT](IMPORTANT): Beginning control surface checks.
2023-08-16T15:07:55.425Z,1692198475.425 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-16T15:07:56.998Z,1692198476.998 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150756.00,A,4313.61350,N,08620.34032,W,0.311,0.00,160823,,,A*7F
2023-08-16T15:07:57.001Z,1692198477.001 [NAL9602](INFO): GPS fix at 20230816T150756: (43.226892, -86.339005)
2023-08-16T15:07:58.308Z,1692198478.308 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-08-16T15:08:06.325Z,1692198486.325 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003598
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.003293
CHAN A3 (5V): -0.001463
CHAN B0 (3.3V): -0.000887
CHAN B1 (3.15aV): -0.001190
CHAN B2 (3.15bV): -0.001488
CHAN B3 (GND): -0.000301
OPEN: 0.003758
Full Scale: +/- 1 mA
2023-08-16T15:08:27.734Z,1692198507.734 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-16T15:08:27.816Z,1692198507.816 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-08-16T15:08:59.278Z,1692198539.278 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-16T15:09:04.127Z,1692198544.127 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150903.00,A,4313.61534,N,08620.34156,W,0.117,0.00,160823,,,A*72
2023-08-16T15:09:04.129Z,1692198544.129 [NAL9602](INFO): GPS fix at 20230816T150903: (43.226922, -86.339026)
2023-08-16T15:09:06.951Z,1692198546.951 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150906.00,A,4313.61530,N,08620.34125,W,0.505,0.00,160823,,,A*70
2023-08-16T15:09:06.953Z,1692198546.953 [NAL9602](INFO): GPS fix at 20230816T150906: (43.226922, -86.339021)
2023-08-16T15:09:10.180Z,1692198550.180 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150909.00,A,4313.61536,N,08620.34118,W,0.253,0.00,160823,,,A*73
2023-08-16T15:09:10.182Z,1692198550.182 [NAL9602](INFO): GPS fix at 20230816T150909: (43.226923, -86.339020)
2023-08-16T15:09:13.010Z,1692198553.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150912.00,A,4313.61548,N,08620.34109,W,0.136,0.00,160823,,,A*70
2023-08-16T15:09:13.013Z,1692198553.013 [NAL9602](INFO): GPS fix at 20230816T150912: (43.226925, -86.339018)
2023-08-16T15:09:15.834Z,1692198555.834 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150915.00,A,4313.61566,N,08620.34110,W,0.136,0.00,160823,,,A*73
2023-08-16T15:09:15.836Z,1692198555.836 [NAL9602](INFO): GPS fix at 20230816T150915: (43.226928, -86.339018)
2023-08-16T15:09:19.074Z,1692198559.074 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150918.00,A,4313.61566,N,08620.34094,W,0.272,0.00,160823,,,A*70
2023-08-16T15:09:19.077Z,1692198559.077 [NAL9602](INFO): GPS fix at 20230816T150918: (43.226928, -86.339016)
2023-08-16T15:09:21.895Z,1692198561.895 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150921.00,A,4313.61564,N,08620.34078,W,0.272,0.00,160823,,,A*7A
2023-08-16T15:09:21.897Z,1692198561.897 [NAL9602](INFO): GPS fix at 20230816T150921: (43.226927, -86.339013)
2023-08-16T15:09:25.137Z,1692198565.137 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150924.00,A,4313.61574,N,08620.34085,W,0.097,0.00,160823,,,A*75
2023-08-16T15:09:25.139Z,1692198565.139 [NAL9602](INFO): GPS fix at 20230816T150924: (43.226929, -86.339014)
2023-08-16T15:09:27.955Z,1692198567.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150927.00,A,4313.61584,N,08620.34072,W,0.039,0.00,160823,,,A*75
2023-08-16T15:09:27.957Z,1692198567.957 [NAL9602](INFO): GPS fix at 20230816T150927: (43.226931, -86.339012)
2023-08-16T15:09:30.787Z,1692198570.787 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150930.00,A,4313.61606,N,08620.34091,W,0.039,0.00,160823,,,A*77
2023-08-16T15:09:30.789Z,1692198570.789 [NAL9602](INFO): GPS fix at 20230816T150930: (43.226934, -86.339015)
2023-08-16T15:09:34.014Z,1692198574.014 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150933.00,A,4313.61640,N,08620.34073,W,0.311,0.00,160823,,,A*73
2023-08-16T15:09:34.016Z,1692198574.016 [NAL9602](INFO): GPS fix at 20230816T150933: (43.226940, -86.339012)
2023-08-16T15:09:36.847Z,1692198576.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150936.00,A,4313.61648,N,08620.34022,W,0.253,0.00,160823,,,A*7D
2023-08-16T15:09:36.849Z,1692198576.849 [NAL9602](INFO): GPS fix at 20230816T150936: (43.226941, -86.339004)
2023-08-16T15:09:40.074Z,1692198580.074 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150939.00,A,4313.61662,N,08620.34019,W,0.136,0.00,160823,,,A*72
2023-08-16T15:09:40.081Z,1692198580.081 [NAL9602](INFO): GPS fix at 20230816T150939: (43.226944, -86.339003)
2023-08-16T15:09:42.906Z,1692198582.906 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150942.00,A,4313.61666,N,08620.33955,W,0.369,0.00,160823,,,A*74
2023-08-16T15:09:42.918Z,1692198582.918 [NAL9602](INFO): GPS fix at 20230816T150942: (43.226944, -86.338993)
2023-08-16T15:09:46.150Z,1692198586.150 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150945.00,A,4313.61696,N,08620.33957,W,0.292,0.00,160823,,,A*7B
2023-08-16T15:09:46.152Z,1692198586.152 [NAL9602](INFO): GPS fix at 20230816T150945: (43.226949, -86.338993)
2023-08-16T15:09:46.581Z,1692198586.581 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2023-08-16T15:09:46.965Z,1692198586.965 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.730000
2023-08-16T15:09:46.965Z,1692198586.965 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-08-16T15:09:46.966Z,1692198586.966 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-08-16T15:09:47.366Z,1692198587.366 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-08-16T15:09:47.366Z,1692198587.366 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-08-16T15:09:47.367Z,1692198587.367 [IBIT](IMPORTANT): Pressure:10.208852 PSI
2023-08-16T15:09:47.367Z,1692198587.367 [IBIT](IMPORTANT): Humidity:4.816262 %
2023-08-16T15:09:47.785Z,1692198587.785 [IBIT](IMPORTANT): Vehicle Pitch:-3.260996 degrees
2023-08-16T15:09:47.785Z,1692198587.785 [IBIT](IMPORTANT): Vehicle  Roll:-2.440754 degrees
2023-08-16T15:09:47.786Z,1692198587.786 [IBIT](IMPORTANT): Vehicle  Heading:138.628204 degrees
2023-08-16T15:09:48.198Z,1692198588.198 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-08-16T15:09:48.198Z,1692198588.198 [IBIT](IMPORTANT): buoyancyNeutral: 427.503754 cc
2023-08-16T15:09:48.198Z,1692198588.198 [IBIT](IMPORTANT): massDefault: 2.415692 cm
2023-08-16T15:09:48.198Z,1692198588.198 [IBIT](IMPORTANT): stopDepth: 100.000000 m
2023-08-16T15:09:48.199Z,1692198588.199 [IBIT](IMPORTANT): abortDepth: 120.000000 m
2023-08-16T15:09:48.199Z,1692198588.199 [IBIT](IMPORTANT): IBIT FAILED
2023-08-16T15:09:48.964Z,1692198588.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150948.00,A,4313.61695,N,08620.33911,W,0.097,0.00,160823,,,A*70
2023-08-16T15:09:48.966Z,1692198588.966 [NAL9602](INFO): GPS fix at 20230816T150948: (43.226949, -86.338985)
2023-08-16T15:10:10.787Z,1692198610.787 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:10:10.787Z,1692198610.787 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:10:10.787Z,1692198610.787 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:10:10.787Z,1692198610.787 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:10:11.199Z,1692198611.199 [Default:CheckIn:D] Stopped
2023-08-16T15:10:11.199Z,1692198611.199 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:10:11.602Z,1692198611.602 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.700677 min 
2023-08-16T15:10:11.602Z,1692198611.602 [Default:CheckIn:E] Stopped
2023-08-16T15:10:11.602Z,1692198611.602 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:10:11.603Z,1692198611.603 [Default:CheckIn] Stopped
2023-08-16T15:10:11.603Z,1692198611.603 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:10:11.603Z,1692198611.603 [Default:CheckIn](INFO): Running loop #3
2023-08-16T15:10:11.603Z,1692198611.603 [Default:CheckIn] Running Loop=3
2023-08-16T15:10:11.603Z,1692198611.603 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:10:11.603Z,1692198611.603 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:10:13.610Z,1692198613.610 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151012.00,A,4313.61734,N,08620.33964,W,0.097,0.00,160823,,,A*7F
2023-08-16T15:10:13.613Z,1692198613.613 [NAL9602](INFO): GPS fix at 20230816T151012: (43.226956, -86.338994)
2023-08-16T15:10:13.636Z,1692198613.636 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:10:13.636Z,1692198613.636 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:10:17.467Z,1692198617.467 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230816T144223/Courier0013.lzma
2023-08-16T15:10:18.473Z,1692198618.473 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0013.lzma.bak
2023-08-16T15:10:18.473Z,1692198618.473 [DataOverHttps](INFO): SBD MOMSN=18564272
2023-08-16T15:10:40.778Z,1692198640.778 [DataOverHttps](INFO): Sending 844 bytes from file Logs/20230816T144223/Express0014.lzma
2023-08-16T15:10:41.778Z,1692198641.778 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0014.lzma.bak
2023-08-16T15:10:41.778Z,1692198641.778 [DataOverHttps](INFO): SBD MOMSN=18564275
2023-08-16T15:10:46.132Z,1692198646.132 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:11:02.475Z,1692198662.475 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230816T144223/Express0017.lzma
2023-08-16T15:11:03.477Z,1692198663.477 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0017.lzma.bak
2023-08-16T15:11:03.477Z,1692198663.477 [DataOverHttps](INFO): SBD MOMSN=18564303
2023-08-16T15:11:06.358Z,1692198666.358 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:11:06.358Z,1692198666.358 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:11:06.358Z,1692198666.358 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:16:06.987Z,1692198966.987 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:16:06.987Z,1692198966.987 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:16:06.987Z,1692198966.987 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:16:06.987Z,1692198966.987 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:16:07.388Z,1692198967.388 [Default:CheckIn:D] Stopped
2023-08-16T15:16:07.388Z,1692198967.388 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:16:07.802Z,1692198967.802 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.637162 min 
2023-08-16T15:16:07.803Z,1692198967.803 [Default:CheckIn:E] Stopped
2023-08-16T15:16:07.803Z,1692198967.803 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:16:07.803Z,1692198967.803 [Default:CheckIn] Stopped
2023-08-16T15:16:07.803Z,1692198967.803 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:16:07.803Z,1692198967.803 [Default:CheckIn](INFO): Running loop #4
2023-08-16T15:16:07.803Z,1692198967.803 [Default:CheckIn] Running Loop=4
2023-08-16T15:16:07.803Z,1692198967.803 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:16:07.804Z,1692198967.804 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:16:09.807Z,1692198969.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151609.00,A,4313.61335,N,08620.33609,W,0.058,0.00,160823,,,A*71
2023-08-16T15:16:09.809Z,1692198969.809 [NAL9602](INFO): GPS fix at 20230816T151609: (43.226889, -86.338935)
2023-08-16T15:16:09.836Z,1692198969.836 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:16:09.836Z,1692198969.836 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:16:17.683Z,1692198977.683 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230816T144223/Courier0019.lzma
2023-08-16T15:16:18.685Z,1692198978.685 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0019.lzma.bak
2023-08-16T15:16:18.685Z,1692198978.685 [DataOverHttps](INFO): SBD MOMSN=18564305
2023-08-16T15:16:39.279Z,1692198999.279 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230816T144223/Express0020.lzma
2023-08-16T15:16:40.227Z,1692199000.227 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-16T15:16:40.281Z,1692199000.281 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0020.lzma.bak
2023-08-16T15:16:40.282Z,1692199000.282 [DataOverHttps](INFO): SBD MOMSN=18564308
2023-08-16T15:16:40.308Z,1692199000.308 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-08-16T15:16:44.355Z,1692199004.355 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:16:44.355Z,1692199004.355 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:16:44.355Z,1692199004.355 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:17:12.625Z,1692199032.625 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-16T15:17:43.325Z,1692199063.325 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:21:44.976Z,1692199304.976 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:21:44.976Z,1692199304.976 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:21:44.977Z,1692199304.977 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:21:44.977Z,1692199304.977 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:21:45.381Z,1692199305.381 [Default:CheckIn:D] Stopped
2023-08-16T15:21:45.381Z,1692199305.381 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:21:45.791Z,1692199305.791 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.270378 min 
2023-08-16T15:21:45.792Z,1692199305.792 [Default:CheckIn:E] Stopped
2023-08-16T15:21:45.792Z,1692199305.792 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:21:45.792Z,1692199305.792 [Default:CheckIn] Stopped
2023-08-16T15:21:45.792Z,1692199305.792 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:21:45.792Z,1692199305.792 [Default:CheckIn](INFO): Running loop #5
2023-08-16T15:21:45.792Z,1692199305.792 [Default:CheckIn] Running Loop=5
2023-08-16T15:21:45.792Z,1692199305.792 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:21:45.792Z,1692199305.792 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:21:46.873Z,1692199306.873 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T152145
2023-08-16T15:21:47.795Z,1692199307.795 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152147.00,A,4313.61644,N,08620.31991,W,0.019,50.56,160823,,,A*43
2023-08-16T15:21:47.797Z,1692199307.797 [NAL9602](INFO): GPS fix at 20230816T152147: (43.226941, -86.338665)
2023-08-16T15:21:47.829Z,1692199307.829 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:21:47.829Z,1692199307.829 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:21:55.231Z,1692199315.231 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230816T144223/Courier0022.lzma
2023-08-16T15:21:55.233Z,1692199315.233 [DataOverHttps](INFO): Received command: ibit
2023-08-16T15:21:55.269Z,1692199315.269 [CommandExec](IMPORTANT): got command ibit
2023-08-16T15:21:55.499Z,1692199315.499 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-08-16T15:21:55.500Z,1692199315.500 [IBIT](IMPORTANT): Beginning control surface checks.
2023-08-16T15:21:55.503Z,1692199315.503 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-16T15:21:56.477Z,1692199316.477 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0022.lzma.bak
2023-08-16T15:21:56.477Z,1692199316.477 [DataOverHttps](INFO): SBD MOMSN=18564312
2023-08-16T15:21:58.373Z,1692199318.373 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-08-16T15:22:06.407Z,1692199326.407 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004767
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.003366
CHAN A3 (5V): -0.001153
CHAN B0 (3.3V): -0.001211
CHAN B1 (3.15aV): -0.001103
CHAN B2 (3.15bV): -0.000723
CHAN B3 (GND): -0.000518
OPEN: 0.003620
Full Scale: +/- 1 mA
2023-08-16T15:22:13.200Z,1692199333.200 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230816T144223/Courier0025.lzma
2023-08-16T15:22:14.197Z,1692199334.197 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0025.lzma.bak
2023-08-16T15:22:14.198Z,1692199334.198 [DataOverHttps](INFO): SBD MOMSN=18564316
2023-08-16T15:22:18.513Z,1692199338.513 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-16T15:22:18.587Z,1692199338.587 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-08-16T15:22:36.391Z,1692199356.391 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20230816T144223/Express0023.lzma
2023-08-16T15:22:37.394Z,1692199357.394 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0023.lzma.bak
2023-08-16T15:22:37.394Z,1692199357.394 [DataOverHttps](INFO): SBD MOMSN=18564318
2023-08-16T15:22:41.634Z,1692199361.634 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=2115, MT Status=1, MTMSN=376
2023-08-16T15:22:41.635Z,1692199361.635 [NAL9602](INFO): Data available in MT queue
2023-08-16T15:22:41.705Z,1692199361.705 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 43.226940 Longitude: -86.338669
2023-08-16T15:22:42.170Z,1692199362.170 [NAL9602](INFO): Received command: failc
2023-08-16T15:22:42.186Z,1692199362.186 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.730000
2023-08-16T15:22:42.186Z,1692199362.186 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-08-16T15:22:42.187Z,1692199362.187 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-08-16T15:22:42.236Z,1692199362.236 [CommandExec](IMPORTANT): got command failComponent
2023-08-16T15:22:42.236Z,1692199362.236 [CommandExec](IMPORTANT): Failed components:
2023-08-16T15:22:42.237Z,1692199362.237 [CommandExec](IMPORTANT): No failed Components.
2023-08-16T15:22:42.477Z,1692199362.477 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-08-16T15:22:42.478Z,1692199362.478 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-08-16T15:22:42.478Z,1692199362.478 [IBIT](IMPORTANT): Pressure:10.242229 PSI
2023-08-16T15:22:42.478Z,1692199362.478 [IBIT](IMPORTANT): Humidity:4.828470 %
2023-08-16T15:22:42.895Z,1692199362.895 [IBIT](IMPORTANT): Vehicle Pitch:-4.089179 degrees
2023-08-16T15:22:42.895Z,1692199362.895 [IBIT](IMPORTANT): Vehicle  Roll:-1.165810 degrees
2023-08-16T15:22:42.895Z,1692199362.895 [IBIT](IMPORTANT): Vehicle  Heading:212.403564 degrees
2023-08-16T15:22:43.269Z,1692199363.269 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-08-16T15:22:43.269Z,1692199363.269 [IBIT](IMPORTANT): buoyancyNeutral: 427.503754 cc
2023-08-16T15:22:43.269Z,1692199363.269 [IBIT](IMPORTANT): massDefault: 2.415692 cm
2023-08-16T15:22:43.270Z,1692199363.270 [IBIT](IMPORTANT): stopDepth: 100.000000 m
2023-08-16T15:22:43.270Z,1692199363.270 [IBIT](IMPORTANT): abortDepth: 120.000000 m
2023-08-16T15:22:43.270Z,1692199363.270 [IBIT](IMPORTANT): IBIT PASSED
2023-08-16T15:22:59.647Z,1692199379.647 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20230816T144223/Express0026.lzma
2023-08-16T15:23:00.649Z,1692199380.649 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0026.lzma.bak
2023-08-16T15:23:00.649Z,1692199380.649 [DataOverHttps](INFO): SBD MOMSN=18564323
2023-08-16T15:23:03.568Z,1692199383.568 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:23:03.568Z,1692199383.568 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:23:03.568Z,1692199383.568 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:23:12.805Z,1692199392.805 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:28:04.166Z,1692199684.166 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:28:04.166Z,1692199684.166 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:28:04.166Z,1692199684.166 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:28:04.166Z,1692199684.166 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:28:04.558Z,1692199684.558 [Default:CheckIn:D] Stopped
2023-08-16T15:28:04.558Z,1692199684.558 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:28:04.967Z,1692199684.967 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.589994 min 
2023-08-16T15:28:04.967Z,1692199684.967 [Default:CheckIn:E] Stopped
2023-08-16T15:28:04.967Z,1692199684.967 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:28:04.967Z,1692199684.967 [Default:CheckIn] Stopped
2023-08-16T15:28:04.968Z,1692199684.968 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:28:04.968Z,1692199684.968 [Default:CheckIn](INFO): Running loop #6
2023-08-16T15:28:04.968Z,1692199684.968 [Default:CheckIn] Running Loop=6
2023-08-16T15:28:04.968Z,1692199684.968 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:28:04.968Z,1692199684.968 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:28:06.974Z,1692199686.974 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152806.00,A,4313.61694,N,08620.31931,W,0.039,50.56,160823,,,A*4A
2023-08-16T15:28:06.976Z,1692199686.976 [NAL9602](INFO): GPS fix at 20230816T152806: (43.226949, -86.338655)
2023-08-16T15:28:06.987Z,1692199686.987 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:28:06.987Z,1692199686.987 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:28:14.359Z,1692199694.359 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230816T144223/Courier0028.lzma
2023-08-16T15:28:15.361Z,1692199695.361 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0028.lzma.bak
2023-08-16T15:28:15.361Z,1692199695.361 [DataOverHttps](INFO): SBD MOMSN=18564331
2023-08-16T15:28:20.314Z,1692199700.314 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=2116, MT Status=1, MTMSN=377
2023-08-16T15:28:20.314Z,1692199700.314 [NAL9602](INFO): Data available in MT queue
2023-08-16T15:28:20.792Z,1692199700.792 [NAL9602](INFO): Received command: burn on
2023-08-16T15:28:20.893Z,1692199700.893 [CommandExec](IMPORTANT): got command burn on
2023-08-16T15:28:20.893Z,1692199700.893 [CommandExec](IMPORTANT): Activating dropweight wire
2023-08-16T15:28:36.053Z,1692199716.053 [DataOverHttps](INFO): Sending 766 bytes from file Logs/20230816T144223/Express0029.lzma
2023-08-16T15:28:37.054Z,1692199717.054 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0029.lzma.bak
2023-08-16T15:28:37.054Z,1692199717.054 [DataOverHttps](INFO): SBD MOMSN=18564334
2023-08-16T15:28:41.889Z,1692199721.889 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:28:41.889Z,1692199721.889 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:28:41.889Z,1692199721.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:28:51.185Z,1692199731.185 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:30:48.757Z,1692199848.757 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T153047
2023-08-16T15:30:56.079Z,1692199856.079 [DataOverHttps](INFO): Received command: ibit
2023-08-16T15:30:56.081Z,1692199856.081 [CommandExec](IMPORTANT): got command ibit
2023-08-16T15:30:56.462Z,1692199856.462 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-08-16T15:30:56.462Z,1692199856.462 [IBIT](IMPORTANT): Beginning control surface checks.
2023-08-16T15:30:56.466Z,1692199856.466 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-16T15:30:58.055Z,1692199858.055 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153057.00,A,4313.61879,N,08620.31854,W,0.000,50.56,160823,,,A*42
2023-08-16T15:30:58.058Z,1692199858.058 [NAL9602](INFO): GPS fix at 20230816T153057: (43.226980, -86.338642)
2023-08-16T15:30:59.337Z,1692199859.337 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-08-16T15:31:07.371Z,1692199867.371 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005123
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.003366
CHAN A3 (5V): -0.001740
CHAN B0 (3.3V): -0.000548
CHAN B1 (3.15aV): -0.001150
CHAN B2 (3.15bV): -0.000193
CHAN B3 (GND): 0.000152
OPEN: 0.003876
Full Scale: +/- 1 mA
2023-08-16T15:31:19.470Z,1692199879.470 [NAL9602](INFO): SBD MO Status=2, MOMSN=2117, MT Status=2, MTMSN=0
2023-08-16T15:31:19.470Z,1692199879.470 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-16T15:31:36.838Z,1692199896.838 [NAL9602](INFO): SBD MO Status=2, MOMSN=2117, MT Status=2, MTMSN=0
2023-08-16T15:31:36.838Z,1692199896.838 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-16T15:32:00.273Z,1692199920.273 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-16T15:32:01.486Z,1692199921.486 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153200.00,A,4313.61833,N,08620.31867,W,0.058,50.56,160823,,,A*41
2023-08-16T15:32:01.489Z,1692199921.489 [NAL9602](INFO): GPS fix at 20230816T153200: (43.226972, -86.338645)
2023-08-16T15:32:03.907Z,1692199923.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153203.00,A,4313.61835,N,08620.31855,W,0.058,50.56,160823,,,A*45
2023-08-16T15:32:03.922Z,1692199923.922 [NAL9602](INFO): GPS fix at 20230816T153203: (43.226973, -86.338643)
2023-08-16T15:32:07.138Z,1692199927.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153206.00,A,4313.61833,N,08620.31845,W,0.039,50.56,160823,,,A*40
2023-08-16T15:32:07.148Z,1692199927.148 [NAL9602](INFO): GPS fix at 20230816T153206: (43.226972, -86.338641)
2023-08-16T15:32:09.975Z,1692199929.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153209.00,A,4313.61836,N,08620.31836,W,0.019,50.56,160823,,,A*4C
2023-08-16T15:32:09.977Z,1692199929.977 [NAL9602](INFO): GPS fix at 20230816T153209: (43.226973, -86.338639)
2023-08-16T15:32:12.794Z,1692199932.794 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153212.00,A,4313.61837,N,08620.31830,W,0.019,50.56,160823,,,A*41
2023-08-16T15:32:12.797Z,1692199932.797 [NAL9602](INFO): GPS fix at 20230816T153212: (43.226973, -86.338638)
2023-08-16T15:32:16.033Z,1692199936.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153215.00,A,4313.61840,N,08620.31827,W,0.019,50.56,160823,,,A*40
2023-08-16T15:32:16.035Z,1692199936.035 [NAL9602](INFO): GPS fix at 20230816T153215: (43.226973, -86.338638)
2023-08-16T15:32:18.858Z,1692199938.858 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153218.00,A,4313.61847,N,08620.31829,W,0.019,50.56,160823,,,A*44
2023-08-16T15:32:18.861Z,1692199938.861 [NAL9602](INFO): GPS fix at 20230816T153218: (43.226974, -86.338638)
2023-08-16T15:32:22.091Z,1692199942.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153221.00,A,4313.61853,N,08620.31836,W,0.058,50.56,160823,,,A*40
2023-08-16T15:32:22.093Z,1692199942.093 [NAL9602](INFO): GPS fix at 20230816T153221: (43.226976, -86.338639)
2023-08-16T15:32:24.914Z,1692199944.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153224.00,A,4313.61853,N,08620.31832,W,0.019,50.56,160823,,,A*44
2023-08-16T15:32:24.916Z,1692199944.916 [NAL9602](INFO): GPS fix at 20230816T153224: (43.226976, -86.338639)
2023-08-16T15:32:28.147Z,1692199948.147 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153227.00,A,4313.61844,N,08620.31830,W,0.019,50.56,160823,,,A*43
2023-08-16T15:32:28.149Z,1692199948.149 [NAL9602](INFO): GPS fix at 20230816T153227: (43.226974, -86.338638)
2023-08-16T15:32:30.983Z,1692199950.983 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153230.00,A,4313.61845,N,08620.31834,W,0.039,50.56,160823,,,A*42
2023-08-16T15:32:30.986Z,1692199950.986 [NAL9602](INFO): GPS fix at 20230816T153230: (43.226974, -86.338639)
2023-08-16T15:32:33.810Z,1692199953.810 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153233.00,A,4313.61846,N,08620.31829,W,0.019,50.56,160823,,,A*4C
2023-08-16T15:32:33.812Z,1692199953.812 [NAL9602](INFO): GPS fix at 20230816T153233: (43.226974, -86.338638)
2023-08-16T15:32:37.043Z,1692199957.043 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153236.00,A,4313.61841,N,08620.31831,W,0.039,50.56,160823,,,A*45
2023-08-16T15:32:37.045Z,1692199957.045 [NAL9602](INFO): GPS fix at 20230816T153236: (43.226973, -86.338639)
2023-08-16T15:32:39.879Z,1692199959.879 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153239.00,A,4313.61835,N,08620.31830,W,0.039,50.56,160823,,,A*48
2023-08-16T15:32:39.882Z,1692199959.882 [NAL9602](INFO): GPS fix at 20230816T153239: (43.226973, -86.338638)
2023-08-16T15:32:43.159Z,1692199963.159 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153242.00,A,4313.61825,N,08620.31832,W,0.019,50.56,160823,,,A*45
2023-08-16T15:32:43.161Z,1692199963.161 [NAL9602](INFO): GPS fix at 20230816T153242: (43.226971, -86.338639)
2023-08-16T15:32:45.955Z,1692199965.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153245.00,A,4313.61814,N,08620.31839,W,0.078,50.56,160823,,,A*4C
2023-08-16T15:32:45.957Z,1692199965.957 [NAL9602](INFO): GPS fix at 20230816T153245: (43.226969, -86.338640)
2023-08-16T15:32:47.595Z,1692199967.595 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2023-08-16T15:32:48.002Z,1692199968.002 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.730000
2023-08-16T15:32:48.002Z,1692199968.002 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-08-16T15:32:48.003Z,1692199968.003 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-08-16T15:32:48.421Z,1692199968.421 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-08-16T15:32:48.421Z,1692199968.421 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-08-16T15:32:48.422Z,1692199968.422 [IBIT](IMPORTANT): Pressure:10.206134 PSI
2023-08-16T15:32:48.422Z,1692199968.422 [IBIT](IMPORTANT): Humidity:4.694177 %
2023-08-16T15:32:48.787Z,1692199968.787 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153248.00,A,4313.61801,N,08620.31850,W,0.019,50.56,160823,,,A*4D
2023-08-16T15:32:48.789Z,1692199968.789 [NAL9602](INFO): GPS fix at 20230816T153248: (43.226967, -86.338642)
2023-08-16T15:32:48.806Z,1692199968.806 [IBIT](IMPORTANT): Vehicle Pitch:-4.177133 degrees
2023-08-16T15:32:48.806Z,1692199968.806 [IBIT](IMPORTANT): Vehicle  Roll:-1.185109 degrees
2023-08-16T15:32:48.807Z,1692199968.807 [IBIT](IMPORTANT): Vehicle  Heading:212.322571 degrees
2023-08-16T15:32:49.222Z,1692199969.222 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-08-16T15:32:49.222Z,1692199969.222 [IBIT](IMPORTANT): buoyancyNeutral: 427.503754 cc
2023-08-16T15:32:49.223Z,1692199969.223 [IBIT](IMPORTANT): massDefault: 2.415692 cm
2023-08-16T15:32:49.223Z,1692199969.223 [IBIT](IMPORTANT): stopDepth: 100.000000 m
2023-08-16T15:32:49.224Z,1692199969.224 [IBIT](IMPORTANT): abortDepth: 120.000000 m
2023-08-16T15:32:49.224Z,1692199969.224 [IBIT](IMPORTANT): IBIT FAILED
2023-08-16T15:33:21.512Z,1692200001.512 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:33:42.531Z,1692200022.531 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:33:42.531Z,1692200022.531 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:33:42.531Z,1692200022.531 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:33:42.531Z,1692200022.531 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:33:42.935Z,1692200022.935 [Default:CheckIn:D] Stopped
2023-08-16T15:33:42.935Z,1692200022.935 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:33:43.335Z,1692200023.335 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.229606 min 
2023-08-16T15:33:43.336Z,1692200023.336 [Default:CheckIn:E] Stopped
2023-08-16T15:33:43.336Z,1692200023.336 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:33:43.336Z,1692200023.336 [Default:CheckIn] Stopped
2023-08-16T15:33:43.336Z,1692200023.336 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:33:43.336Z,1692200023.336 [Default:CheckIn](INFO): Running loop #7
2023-08-16T15:33:43.336Z,1692200023.336 [Default:CheckIn] Running Loop=7
2023-08-16T15:33:43.336Z,1692200023.336 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:33:43.336Z,1692200023.336 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:33:45.342Z,1692200025.342 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153344.00,A,4313.61886,N,08620.32058,W,0.019,50.56,160823,,,A*4C
2023-08-16T15:33:45.345Z,1692200025.345 [NAL9602](INFO): GPS fix at 20230816T153344: (43.226981, -86.338676)
2023-08-16T15:33:45.355Z,1692200025.355 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:33:45.356Z,1692200025.356 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:33:53.707Z,1692200033.707 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230816T144223/Courier0031.lzma
2023-08-16T15:33:54.710Z,1692200034.710 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0031.lzma.bak
2023-08-16T15:33:54.710Z,1692200034.710 [DataOverHttps](INFO): SBD MOMSN=18564362
2023-08-16T15:34:12.033Z,1692200052.033 [DataOverHttps](INFO): Sending 889 bytes from file Logs/20230816T144223/Express0032.lzma
2023-08-16T15:34:13.033Z,1692200053.033 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0032.lzma.bak
2023-08-16T15:34:13.034Z,1692200053.034 [DataOverHttps](INFO): SBD MOMSN=18564365
2023-08-16T15:34:16.045Z,1692200056.045 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-16T15:34:16.123Z,1692200056.123 [NAL9602](ERROR): received: 
+CSQ:0

OK17, 2, 0, 0, 0

OK
2023-08-16T15:34:32.635Z,1692200072.635 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=2117, MT Status=1, MTMSN=378
2023-08-16T15:34:32.635Z,1692200072.635 [NAL9602](INFO): Data available in MT queue
2023-08-16T15:34:33.175Z,1692200073.175 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230816T144223/Express0033.lzma
2023-08-16T15:34:33.249Z,1692200073.249 [NAL9602](INFO): Received command: burn off
2023-08-16T15:34:33.297Z,1692200073.297 [CommandExec](IMPORTANT): got command burn off
2023-08-16T15:34:33.297Z,1692200073.297 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-08-16T15:34:34.178Z,1692200074.178 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0033.lzma.bak
2023-08-16T15:34:34.178Z,1692200074.178 [DataOverHttps](INFO): SBD MOMSN=18564395
2023-08-16T15:34:37.961Z,1692200077.961 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:34:37.961Z,1692200077.961 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:34:37.961Z,1692200077.961 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:35:03.845Z,1692200103.845 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:37:19.953Z,1692200239.953 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T153719
2023-08-16T15:37:27.279Z,1692200247.279 [DataOverHttps](INFO): Received command: configSet NAL9602.iridiumMTQueueTimeout 5 minute persist
2023-08-16T15:37:27.627Z,1692200247.627 [CommandExec](IMPORTANT): got command configSet NAL9602.iridiumMTQueueTimeout 5 minute persist
2023-08-16T15:38:41.965Z,1692200321.965 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T153841
2023-08-16T15:38:51.815Z,1692200331.815 [DataOverHttps](INFO): Received command: ibit
2023-08-16T15:38:51.826Z,1692200331.826 [CommandExec](IMPORTANT): got command ibit
2023-08-16T15:38:51.855Z,1692200331.855 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-08-16T15:38:51.855Z,1692200331.855 [IBIT](IMPORTANT): Beginning control surface checks.
2023-08-16T15:38:51.859Z,1692200331.859 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-16T15:38:53.449Z,1692200333.449 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153852.00,A,4313.61768,N,08620.31992,W,0.019,50.56,160823,,,A*43
2023-08-16T15:38:53.452Z,1692200333.452 [NAL9602](INFO): GPS fix at 20230816T153852: (43.226961, -86.338665)
2023-08-16T15:38:54.757Z,1692200334.757 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-08-16T15:39:02.783Z,1692200342.783 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005143
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.003428
CHAN A3 (5V): -0.002175
CHAN B0 (3.3V): -0.000910
CHAN B1 (3.15aV): -0.001235
CHAN B2 (3.15bV): -0.001079
CHAN B3 (GND): -0.000126
OPEN: 0.003928
Full Scale: +/- 1 mA
2023-08-16T15:40:31.255Z,1692200431.255 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 43.226963 Longitude: -86.338669
2023-08-16T15:40:31.661Z,1692200431.661 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.730000
2023-08-16T15:40:31.661Z,1692200431.661 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-08-16T15:40:31.661Z,1692200431.661 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-08-16T15:40:32.118Z,1692200432.118 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-08-16T15:40:32.119Z,1692200432.119 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-08-16T15:40:32.119Z,1692200432.119 [IBIT](IMPORTANT): Pressure:10.187766 PSI
2023-08-16T15:40:32.119Z,1692200432.119 [IBIT](IMPORTANT): Humidity:4.590404 %
2023-08-16T15:40:32.540Z,1692200432.540 [IBIT](IMPORTANT): Vehicle Pitch:-4.189720 degrees
2023-08-16T15:40:32.540Z,1692200432.540 [IBIT](IMPORTANT): Vehicle  Roll:-1.211347 degrees
2023-08-16T15:40:32.540Z,1692200432.540 [IBIT](IMPORTANT): Vehicle  Heading:212.344086 degrees
2023-08-16T15:40:32.927Z,1692200432.927 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-08-16T15:40:32.948Z,1692200432.948 [IBIT](IMPORTANT): buoyancyNeutral: 427.503754 cc
2023-08-16T15:40:32.948Z,1692200432.948 [IBIT](IMPORTANT): massDefault: 2.415692 cm
2023-08-16T15:40:32.948Z,1692200432.948 [IBIT](IMPORTANT): stopDepth: 100.000000 m
2023-08-16T15:40:32.949Z,1692200432.949 [IBIT](IMPORTANT): abortDepth: 120.000000 m
2023-08-16T15:40:32.949Z,1692200432.949 [IBIT](IMPORTANT): IBIT PASSED
2023-08-16T15:40:33.305Z,1692200433.305 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:40:33.305Z,1692200433.305 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:40:33.306Z,1692200433.306 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:40:33.306Z,1692200433.306 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:40:33.705Z,1692200433.705 [Default:CheckIn:D] Stopped
2023-08-16T15:40:33.705Z,1692200433.705 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:40:34.146Z,1692200434.146 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.075777 min 
2023-08-16T15:40:34.146Z,1692200434.146 [Default:CheckIn:E] Stopped
2023-08-16T15:40:34.146Z,1692200434.146 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:40:34.146Z,1692200434.146 [Default:CheckIn] Stopped
2023-08-16T15:40:34.146Z,1692200434.146 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:40:34.146Z,1692200434.146 [Default:CheckIn](INFO): Running loop #8
2023-08-16T15:40:34.146Z,1692200434.146 [Default:CheckIn] Running Loop=8
2023-08-16T15:40:34.146Z,1692200434.146 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:40:34.147Z,1692200434.147 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:40:49.858Z,1692200449.858 [NAL9602](INFO): SBD MO Status=2, MOMSN=2118, MT Status=2, MTMSN=0
2023-08-16T15:40:49.858Z,1692200449.858 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-16T15:41:06.836Z,1692200466.836 [NAL9602](INFO): SBD MO Status=2, MOMSN=2118, MT Status=2, MTMSN=0
2023-08-16T15:41:06.836Z,1692200466.836 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-16T15:41:50.064Z,1692200510.064 [NAL9602](INFO): SBD MO Status=2, MOMSN=2118, MT Status=2, MTMSN=0
2023-08-16T15:41:50.065Z,1692200510.065 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-16T15:42:09.450Z,1692200529.450 [NAL9602](INFO): SBD MO Status=0, MOMSN=2118, MT Status=0, MTMSN=0
2023-08-16T15:42:09.450Z,1692200529.450 [NAL9602](INFO): No messages in MT queue
2023-08-16T15:42:10.667Z,1692200530.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154210.00,A,4313.61781,N,08620.32056,W,0.019,50.56,160823,,,A*4D
2023-08-16T15:42:10.669Z,1692200530.669 [NAL9602](INFO): GPS fix at 20230816T154210: (43.226963, -86.338676)
2023-08-16T15:42:10.700Z,1692200530.700 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:42:10.700Z,1692200530.700 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:42:18.867Z,1692200538.867 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230816T144223/Courier0036.lzma
2023-08-16T15:42:19.869Z,1692200539.869 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0036.lzma.bak
2023-08-16T15:42:19.870Z,1692200539.870 [DataOverHttps](INFO): SBD MOMSN=18564404
2023-08-16T15:42:42.269Z,1692200562.269 [DataOverHttps](INFO): Sending 909 bytes from file Logs/20230816T144223/Express0038.lzma
2023-08-16T15:42:43.270Z,1692200563.270 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0038.lzma.bak
2023-08-16T15:42:43.270Z,1692200563.270 [DataOverHttps](INFO): SBD MOMSN=18564407
2023-08-16T15:42:43.469Z,1692200563.469 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:42:48.449Z,1692200568.449 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:42:48.449Z,1692200568.449 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:42:48.449Z,1692200568.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:43:06.205Z,1692200586.205 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T154305
2023-08-16T15:43:14.387Z,1692200594.387 [DataOverHttps](INFO): Received command: burn on
2023-08-16T15:43:14.393Z,1692200594.393 [CommandExec](IMPORTANT): got command burn on
2023-08-16T15:43:14.393Z,1692200594.393 [CommandExec](IMPORTANT): Activating dropweight wire
2023-08-16T15:44:25.445Z,1692200665.445 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T154424
2023-08-16T15:44:35.554Z,1692200675.554 [DataOverHttps](INFO): Received command: burn off
2023-08-16T15:44:35.560Z,1692200675.560 [CommandExec](IMPORTANT): got command burn off
2023-08-16T15:44:35.560Z,1692200675.560 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-08-16T15:47:49.133Z,1692200869.133 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:47:49.133Z,1692200869.133 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:47:49.133Z,1692200869.133 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:47:49.133Z,1692200869.133 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:47:49.518Z,1692200869.518 [Default:CheckIn:D] Stopped
2023-08-16T15:47:49.518Z,1692200869.518 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:47:49.924Z,1692200869.924 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.339331 min 
2023-08-16T15:47:49.924Z,1692200869.924 [Default:CheckIn:E] Stopped
2023-08-16T15:47:49.924Z,1692200869.924 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:47:49.924Z,1692200869.924 [Default:CheckIn] Stopped
2023-08-16T15:47:49.924Z,1692200869.924 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:47:49.925Z,1692200869.925 [Default:CheckIn](INFO): Running loop #9
2023-08-16T15:47:49.925Z,1692200869.925 [Default:CheckIn] Running Loop=9
2023-08-16T15:47:49.925Z,1692200869.925 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:47:49.925Z,1692200869.925 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:47:51.936Z,1692200871.936 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154751.00,A,4313.61489,N,08620.32013,W,0.019,50.56,160823,,,A*47
2023-08-16T15:47:51.938Z,1692200871.938 [NAL9602](INFO): GPS fix at 20230816T154751: (43.226915, -86.338669)
2023-08-16T15:47:51.949Z,1692200871.949 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:47:51.949Z,1692200871.949 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:48:00.423Z,1692200880.423 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230816T144223/Courier0043.lzma
2023-08-16T15:48:01.426Z,1692200881.426 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0043.lzma.bak
2023-08-16T15:48:01.426Z,1692200881.426 [DataOverHttps](INFO): SBD MOMSN=18564443
2023-08-16T15:48:12.537Z,1692200892.537 [NAL9602](INFO): SBD MO Status=0, MOMSN=2119, MT Status=0, MTMSN=0
2023-08-16T15:48:12.537Z,1692200892.537 [NAL9602](INFO): No messages in MT queue
2023-08-16T15:48:18.619Z,1692200898.619 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20230816T144223/Express0044.lzma
2023-08-16T15:48:19.621Z,1692200899.621 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0044.lzma.bak
2023-08-16T15:48:19.622Z,1692200899.622 [DataOverHttps](INFO): SBD MOMSN=18564446
2023-08-16T15:48:42.623Z,1692200922.623 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230816T144223/Express0047.lzma
2023-08-16T15:48:43.318Z,1692200923.318 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:48:43.626Z,1692200923.626 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0047.lzma.bak
2023-08-16T15:48:43.626Z,1692200923.626 [DataOverHttps](INFO): SBD MOMSN=18564455
2023-08-16T15:48:48.688Z,1692200928.688 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:48:48.688Z,1692200928.688 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:48:48.688Z,1692200928.688 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:53:49.255Z,1692201229.255 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:53:49.255Z,1692201229.255 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:53:49.255Z,1692201229.255 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:53:49.255Z,1692201229.255 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:53:49.719Z,1692201229.719 [Default:CheckIn:D] Stopped
2023-08-16T15:53:49.719Z,1692201229.719 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:53:50.089Z,1692201230.089 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.342680 min 
2023-08-16T15:53:50.089Z,1692201230.089 [Default:CheckIn:E] Stopped
2023-08-16T15:53:50.089Z,1692201230.089 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:53:50.089Z,1692201230.089 [Default:CheckIn] Stopped
2023-08-16T15:53:50.089Z,1692201230.089 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:53:50.089Z,1692201230.089 [Default:CheckIn](INFO): Running loop #10
2023-08-16T15:53:50.089Z,1692201230.089 [Default:CheckIn] Running Loop=10
2023-08-16T15:53:50.089Z,1692201230.089 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:53:50.090Z,1692201230.090 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:53:52.096Z,1692201232.096 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155351.00,A,4313.49511,N,08620.21214,W,2.488,139.18,160823,,,A*75
2023-08-16T15:53:52.098Z,1692201232.098 [NAL9602](INFO): GPS fix at 20230816T155351: (43.224919, -86.336869)
2023-08-16T15:53:52.109Z,1692201232.109 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:53:52.109Z,1692201232.109 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:54:00.643Z,1692201240.643 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230816T144223/Courier0049.lzma
2023-08-16T15:54:02.649Z,1692201242.649 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0049.lzma.bak
2023-08-16T15:54:02.650Z,1692201242.650 [DataOverHttps](INFO): SBD MOMSN=18564458
2023-08-16T15:54:22.849Z,1692201262.849 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230816T144223/Express0050.lzma
2023-08-16T15:54:23.849Z,1692201263.849 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0050.lzma.bak
2023-08-16T15:54:23.850Z,1692201263.850 [DataOverHttps](INFO): SBD MOMSN=18564461
2023-08-16T15:54:26.864Z,1692201266.864 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:54:26.864Z,1692201266.864 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:54:26.864Z,1692201266.864 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T15:54:43.884Z,1692201283.884 [NAL9602](INFO): SBD MO Status=0, MOMSN=2120, MT Status=0, MTMSN=0
2023-08-16T15:54:43.884Z,1692201283.884 [NAL9602](INFO): No messages in MT queue
2023-08-16T15:55:14.356Z,1692201314.356 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T15:56:04.064Z,1692201364.064 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-16T15:56:04.067Z,1692201364.067 [BPC1](INFO): Received data from all battery sticks.
2023-08-16T15:59:27.388Z,1692201567.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T15:59:27.388Z,1692201567.388 [Default:CheckIn:C.Wait] Stopped
2023-08-16T15:59:27.388Z,1692201567.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T15:59:27.389Z,1692201567.389 [Default:CheckIn:D] Running Loop=1
2023-08-16T15:59:27.780Z,1692201567.780 [Default:CheckIn:D] Stopped
2023-08-16T15:59:27.781Z,1692201567.781 [Default:CheckIn:E] Running Loop=1
2023-08-16T15:59:28.183Z,1692201568.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.977035 min 
2023-08-16T15:59:28.183Z,1692201568.183 [Default:CheckIn:E] Stopped
2023-08-16T15:59:28.183Z,1692201568.183 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T15:59:28.183Z,1692201568.183 [Default:CheckIn] Stopped
2023-08-16T15:59:28.188Z,1692201568.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T15:59:28.188Z,1692201568.188 [Default:CheckIn](INFO): Running loop #11
2023-08-16T15:59:28.188Z,1692201568.188 [Default:CheckIn] Running Loop=11
2023-08-16T15:59:28.188Z,1692201568.188 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T15:59:28.188Z,1692201568.188 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T15:59:30.182Z,1692201570.182 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155929.00,A,4313.34501,N,08620.15319,W,0.641,251.27,160823,,,A*74
2023-08-16T15:59:30.185Z,1692201570.185 [NAL9602](INFO): GPS fix at 20230816T155929: (43.222417, -86.335887)
2023-08-16T15:59:30.219Z,1692201570.219 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T15:59:30.219Z,1692201570.219 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T15:59:32.151Z,1692201572.151 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20230816T144223/Courier0052.lzma
2023-08-16T15:59:32.349Z,1692201572.349 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0052.lzma.bak
2023-08-16T15:59:32.350Z,1692201572.350 [DataOverHttps](INFO): SBD MOMSN=18564465
2023-08-16T15:59:50.948Z,1692201590.948 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230816T144223/Express0053.lzma
2023-08-16T15:59:52.954Z,1692201592.954 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0053.lzma.bak
2023-08-16T15:59:52.954Z,1692201592.954 [DataOverHttps](INFO): SBD MOMSN=18564468
2023-08-16T15:59:55.665Z,1692201595.665 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T15:59:55.665Z,1692201595.665 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T15:59:55.665Z,1692201595.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T16:00:18.666Z,1692201618.666 [NAL9602](INFO): SBD MO Status=0, MOMSN=2121, MT Status=0, MTMSN=0
2023-08-16T16:00:18.666Z,1692201618.666 [NAL9602](INFO): No messages in MT queue
2023-08-16T16:00:49.390Z,1692201649.390 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T16:04:56.331Z,1692201896.331 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T16:04:56.331Z,1692201896.331 [Default:CheckIn:C.Wait] Stopped
2023-08-16T16:04:56.331Z,1692201896.331 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T16:04:56.331Z,1692201896.331 [Default:CheckIn:D] Running Loop=1
2023-08-16T16:04:56.739Z,1692201896.739 [Default:CheckIn:D] Stopped
2023-08-16T16:04:56.739Z,1692201896.739 [Default:CheckIn:E] Running Loop=1
2023-08-16T16:04:57.131Z,1692201897.131 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.459676 min 
2023-08-16T16:04:57.131Z,1692201897.131 [Default:CheckIn:E] Stopped
2023-08-16T16:04:57.131Z,1692201897.131 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T16:04:57.131Z,1692201897.131 [Default:CheckIn] Stopped
2023-08-16T16:04:57.131Z,1692201897.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T16:04:57.131Z,1692201897.131 [Default:CheckIn](INFO): Running loop #12
2023-08-16T16:04:57.132Z,1692201897.132 [Default:CheckIn] Running Loop=12
2023-08-16T16:04:57.132Z,1692201897.132 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T16:04:57.132Z,1692201897.132 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T16:04:59.142Z,1692201899.142 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160458.00,A,4313.34490,N,08620.15436,W,0.019,232.00,160823,,,D*74
2023-08-16T16:04:59.145Z,1692201899.145 [NAL9602](INFO): GPS fix at 20230816T160458: (43.222415, -86.335906)
2023-08-16T16:04:59.204Z,1692201899.204 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T16:04:59.204Z,1692201899.204 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T16:05:07.715Z,1692201907.715 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230816T144223/Courier0055.lzma
2023-08-16T16:05:08.717Z,1692201908.717 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0055.lzma.bak
2023-08-16T16:05:08.718Z,1692201908.718 [DataOverHttps](INFO): SBD MOMSN=18564472
2023-08-16T16:05:26.127Z,1692201926.127 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230816T144223/Express0056.lzma
2023-08-16T16:05:27.129Z,1692201927.129 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0056.lzma.bak
2023-08-16T16:05:27.130Z,1692201927.130 [DataOverHttps](INFO): SBD MOMSN=18564476
2023-08-16T16:05:29.951Z,1692201929.951 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T16:05:29.951Z,1692201929.951 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T16:05:29.951Z,1692201929.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T16:05:34.766Z,1692201934.766 [NAL9602](INFO): SBD MO Status=2, MOMSN=2122, MT Status=2, MTMSN=0
2023-08-16T16:05:34.767Z,1692201934.767 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-16T16:06:20.826Z,1692201980.826 [NAL9602](INFO): SBD MO Status=2, MOMSN=2122, MT Status=2, MTMSN=0
2023-08-16T16:06:20.826Z,1692201980.826 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-16T16:06:49.510Z,1692202009.510 [NAL9602](INFO): SBD MO Status=0, MOMSN=2122, MT Status=0, MTMSN=0
2023-08-16T16:06:49.510Z,1692202009.510 [NAL9602](INFO): No messages in MT queue
2023-08-16T16:07:20.221Z,1692202040.221 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T16:10:30.558Z,1692202230.558 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T16:10:30.558Z,1692202230.558 [Default:CheckIn:C.Wait] Stopped
2023-08-16T16:10:30.558Z,1692202230.558 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T16:10:30.558Z,1692202230.558 [Default:CheckIn:D] Running Loop=1
2023-08-16T16:10:30.971Z,1692202230.971 [Default:CheckIn:D] Stopped
2023-08-16T16:10:30.971Z,1692202230.971 [Default:CheckIn:E] Running Loop=1
2023-08-16T16:10:31.377Z,1692202231.377 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.030208 min 
2023-08-16T16:10:31.378Z,1692202231.378 [Default:CheckIn:E] Stopped
2023-08-16T16:10:31.378Z,1692202231.378 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T16:10:31.378Z,1692202231.378 [Default:CheckIn] Stopped
2023-08-16T16:10:31.378Z,1692202231.378 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T16:10:31.378Z,1692202231.378 [Default:CheckIn](INFO): Running loop #13
2023-08-16T16:10:31.378Z,1692202231.378 [Default:CheckIn] Running Loop=13
2023-08-16T16:10:31.378Z,1692202231.378 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T16:10:31.378Z,1692202231.378 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T16:10:33.379Z,1692202233.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161032.00,A,4313.34198,N,08620.15807,W,1.225,218.96,160823,,,D*75
2023-08-16T16:10:33.381Z,1692202233.381 [NAL9602](INFO): GPS fix at 20230816T161032: (43.222366, -86.335968)
2023-08-16T16:10:33.396Z,1692202233.396 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T16:10:33.396Z,1692202233.396 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T16:10:51.839Z,1692202251.839 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230816T144223/Courier0058.lzma
2023-08-16T16:10:52.858Z,1692202252.858 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0058.lzma.bak
2023-08-16T16:10:52.858Z,1692202252.858 [DataOverHttps](INFO): SBD MOMSN=18564480
2023-08-16T16:11:13.399Z,1692202273.399 [NAL9602](INFO): SBD MO Status=0, MOMSN=2123, MT Status=0, MTMSN=0
2023-08-16T16:11:13.399Z,1692202273.399 [NAL9602](INFO): No messages in MT queue
2023-08-16T16:11:30.135Z,1692202290.135 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230816T144223/Express0059.lzma
2023-08-16T16:11:31.137Z,1692202291.137 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0059.lzma.bak
2023-08-16T16:11:31.138Z,1692202291.138 [DataOverHttps](INFO): SBD MOMSN=18564483
2023-08-16T16:11:34.011Z,1692202294.011 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T16:11:34.011Z,1692202294.011 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T16:11:34.011Z,1692202294.011 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T16:11:44.097Z,1692202304.097 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T16:16:34.663Z,1692202594.663 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-16T16:16:34.663Z,1692202594.663 [Default:CheckIn:C.Wait] Stopped
2023-08-16T16:16:34.668Z,1692202594.668 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T16:16:34.668Z,1692202594.668 [Default:CheckIn:D] Running Loop=1
2023-08-16T16:16:35.084Z,1692202595.084 [Default:CheckIn:D] Stopped
2023-08-16T16:16:35.084Z,1692202595.084 [Default:CheckIn:E] Running Loop=1
2023-08-16T16:16:35.462Z,1692202595.462 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.098486 min 
2023-08-16T16:16:35.462Z,1692202595.462 [Default:CheckIn:E] Stopped
2023-08-16T16:16:35.463Z,1692202595.463 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-16T16:16:35.463Z,1692202595.463 [Default:CheckIn] Stopped
2023-08-16T16:16:35.463Z,1692202595.463 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T16:16:35.463Z,1692202595.463 [Default:CheckIn](INFO): Running loop #14
2023-08-16T16:16:35.463Z,1692202595.463 [Default:CheckIn] Running Loop=14
2023-08-16T16:16:35.463Z,1692202595.463 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T16:16:35.468Z,1692202595.468 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T16:16:36.610Z,1692202596.610 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T161635
2023-08-16T16:16:37.475Z,1692202597.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161636.00,A,4313.33726,N,08620.16395,W,0.039,208.80,160823,,,D*78
2023-08-16T16:16:37.477Z,1692202597.477 [NAL9602](INFO): GPS fix at 20230816T161636: (43.222288, -86.336066)
2023-08-16T16:16:37.493Z,1692202597.493 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T16:16:37.493Z,1692202597.493 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T16:16:49.715Z,1692202609.715 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230816T144223/Courier0061.lzma
2023-08-16T16:16:49.717Z,1692202609.717 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 43.20017 degree;set transit.Longitude -86.3649 degree;set transit.Depth 1 m;set transit.MaxDepth 2 m" 4enun 1 2
2023-08-16T16:16:49.768Z,1692202609.768 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 43.20017 degree;set transit.Longitude -86.3649 degree;set transit.Depth 1 m;set transit.MaxDepth 2 m" 4enun 1 2.000000
2023-08-16T16:16:49.769Z,1692202609.769 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=4enun
2023-08-16T16:16:49.770Z,1692202609.770 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='4enun'): "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 43.20017 degree;set transit.Longitude -86.3649 degree;set transit.Depth 1 m;set transit.MaxDepth 2 m", ASAP
2023-08-16T16:16:50.889Z,1692202610.889 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0061.lzma.bak
2023-08-16T16:16:50.890Z,1692202610.890 [DataOverHttps](IMPORTANT): SBD MOMSN=18564488, MTMSN=20230816T161649
2023-08-16T16:16:52.138Z,1692202612.138 [NAL9602](INFO): SBD MO Status=0, MOMSN=2124, MT Status=0, MTMSN=0
2023-08-16T16:16:52.138Z,1692202612.138 [NAL9602](INFO): No messages in MT queue
2023-08-16T16:16:58.155Z,1692202618.155 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .0000000001 km;run" 4enun 2 2
2023-08-16T16:16:58.242Z,1692202618.242 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .0000000001 km;run" 4enun 2 2.000000
2023-08-16T16:16:58.243Z,1692202618.243 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=4enun
2023-08-16T16:16:58.244Z,1692202618.244 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='4enun'): "set transit.MinOffshore .0000000001 km;run", ASAP
2023-08-16T16:16:58.358Z,1692202618.358 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-08-16T16:16:58.358Z,1692202618.358 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-08-16T16:16:58.359Z,1692202618.359 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-08-16T16:16:58.432Z,1692202618.432 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-08-16T16:16:58.435Z,1692202618.435 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-08-16T16:16:58.438Z,1692202618.438 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-08-16T16:16:58.440Z,1692202618.440 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-08-16T16:16:58.443Z,1692202618.443 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-08-16T16:16:58.445Z,1692202618.445 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-08-16T16:16:58.448Z,1692202618.448 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-08-16T16:16:58.451Z,1692202618.451 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-08-16T16:16:58.454Z,1692202618.454 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-08-16T16:16:58.456Z,1692202618.456 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-08-16T16:16:58.459Z,1692202618.459 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-08-16T16:16:58.500Z,1692202618.500 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-08-16T16:16:58.503Z,1692202618.503 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-08-16T16:16:58.503Z,1692202618.503 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-08-16T16:16:58.504Z,1692202618.504 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-08-16T16:16:58.663Z,1692202618.663 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-08-16T16:16:58.676Z,1692202618.676 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-08-16T16:16:58.679Z,1692202618.679 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-08-16T16:16:58.682Z,1692202618.682 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-08-16T16:16:58.708Z,1692202618.708 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-08-16T16:16:58.711Z,1692202618.711 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-08-16T16:16:58.714Z,1692202618.714 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-08-16T16:16:58.717Z,1692202618.717 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-08-16T16:16:58.728Z,1692202618.728 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-16T16:16:58.769Z,1692202618.769 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-08-16T16:16:58.769Z,1692202618.769 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-08-16T16:16:58.820Z,1692202618.820 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-08-16T16:16:58.823Z,1692202618.823 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-08-16T16:16:58.830Z,1692202618.830 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-08-16T16:16:58.837Z,1692202618.837 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-08-16T16:16:58.838Z,1692202618.838 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-08-16T16:16:58.854Z,1692202618.854 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-08-16T16:16:58.884Z,1692202618.884 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-08-16T16:16:58.905Z,1692202618.905 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-08-16T16:16:58.908Z,1692202618.908 [transit:MassHold.Pitch](DEBUG): Construct.
2023-08-16T16:16:58.940Z,1692202618.940 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-08-16T16:16:58.945Z,1692202618.945 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-08-16T16:16:58.960Z,1692202618.960 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-08-16T16:16:58.986Z,1692202618.986 [MissionManager](DEBUG): 
mission transit {
  """
  Vehicle transits to desired waypoint and can be commanded to use/hold
  mass or buoyancy.
  """

  arguments {
    MissionTimeout = 60 minute
      """
      Maximum duration of mission
      """

    NeedCommsTime = 30 minute
      """
      How often to surface for commumications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to seek. If set to NaN, uses latitude at mission
      initialization.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to seek. If set to NaN, uses longitude at mission
      initialization.
      """

    Northings = 0 meter
      """
      Northward distance of the waypoint to seek. If combined with Latitude
      and/or Longitude, this is an offset from the specified Latitude and/or
      Longitude.
      """

    Eastings = 0 meter
      """
      Eastward distance of the waypoint to seek. If combined with Latitude
      and/or Longitude, this is an offset from the specified Latitude and/or
      Longitude.
      """

    Depth = 10 meter
      """
      Depth of flat and level flight during the mission.
      """

    Speed = 1 meter_per_second
      """
      Speed of vehicle (relative to water) during the mission.
      """

    MaxDepth = 20 meter
      """
      Maximum depth for the entire mission.
      """

    MinOffshore = 1 kilometer
      """
      Minimum distance offshore for the entire mission.
      """

    MinAltitude = 7 meter
      """
      Minimum height above the sea floor for the entire mission.
      """

    MassHold = true
      """
      Set to True in order to hold mass at default position, False to allow
      mass to run on its own.
      """

    BuoyancyHold = true
      """
      Set to True in order to hold buoyancy at neutral position, False to
      allow buoyancy to run on its own.
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  assign in sequence NeedComms:WaitForPitchUp = 1 second

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MinAltitude = MinAltitude

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  behavior Guidance:Buoyancy id="BuoyancyHold" {
    run while ( BuoyancyHold )

    set position = Control:VerticalControl.buoyancyNeutral
  }

  behavior Guidance:Pitch id="MassHold" {
    run while ( MassHold )

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate Transit {
    run in sequence

    behavior Guidance:Pitch {
      run in parallel

      set depth = Depth
    }

    behavior Guidance:SetSpeed {
      run in parallel

      set speed = Speed
    }

    behavior Guidance:Waypoint id="Wpt1" {
      run in sequence

      set latitude = Latitude
      set longitude = Longitude
      set northingsDelta = Northings
      set eastingsDelta = Eastings
    }

    call id="PhoneHome" refId="NeedComms"
  }
}


2023-08-16T16:16:58.986Z,1692202618.986 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-08-16T16:17:09.891Z,1692202629.891 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230816T144223/Courier0064.lzma
2023-08-16T16:17:10.839Z,1692202630.839 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute
2023-08-16T16:17:10.840Z,1692202630.840 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 12 minute
2023-08-16T16:17:10.841Z,1692202630.841 [CommandExec](IMPORTANT): got command set transit.Latitude 43.20017 degree
2023-08-16T16:17:10.841Z,1692202630.841 [CommandExec](IMPORTANT): got command set transit.Longitude -86.3649 degree
2023-08-16T16:17:10.842Z,1692202630.842 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-08-16T16:17:10.843Z,1692202630.843 [CommandExec](IMPORTANT): got command set transit.MaxDepth 2 meter
2023-08-16T16:17:10.894Z,1692202630.894 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0064.lzma.bak
2023-08-16T16:17:10.894Z,1692202630.894 [DataOverHttps](INFO): SBD MOMSN=18564493
2023-08-16T16:17:12.718Z,1692202632.718 [CommandExec](IMPORTANT): got command set transit.MinOffshore .0000000001 kilometer
2023-08-16T16:17:12.718Z,1692202632.718 [CommandExec](IMPORTANT): got command run
2023-08-16T16:17:12.720Z,1692202632.720 [CommandExec](IMPORTANT): Running 
2023-08-16T16:17:12.783Z,1692202632.783 [Default] Stopped
2023-08-16T16:17:12.783Z,1692202632.783 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-16T16:17:12.783Z,1692202632.783 [Default:B.GoToSurface] Stopped
2023-08-16T16:17:12.792Z,1692202632.792 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-16T16:17:12.792Z,1692202632.792 [Default:CheckIn] Stopped
2023-08-16T16:17:12.792Z,1692202632.792 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T16:17:12.792Z,1692202632.792 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T16:17:12.792Z,1692202632.792 [MissionManager](IMPORTANT): Started mission transit
2023-08-16T16:17:12.792Z,1692202632.792 [transit] Running Loop=1
2023-08-16T16:17:12.792Z,1692202632.792 [transit](DEBUG): Aggregate::initialize transit
2023-08-16T16:17:12.792Z,1692202632.792 [transit:StandardEnvelopes] Running Loop=1
2023-08-16T16:17:12.793Z,1692202632.793 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-08-16T16:17:12.793Z,1692202632.793 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-08-16T16:17:12.793Z,1692202632.793 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-08-16T16:17:12.793Z,1692202632.793 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-08-16T16:17:12.793Z,1692202632.793 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-08-16T16:17:12.793Z,1692202632.793 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-08-16T16:17:12.793Z,1692202632.793 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-08-16T16:17:12.793Z,1692202632.793 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-08-16T16:17:12.794Z,1692202632.794 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-08-16T16:17:12.794Z,1692202632.794 [transit:MassHold.Pitch] Running Loop=1
2023-08-16T16:17:12.794Z,1692202632.794 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-08-16T16:17:12.794Z,1692202632.794 [transit:B] Running Loop=1
2023-08-16T16:17:12.795Z,1692202632.795 [transit:MassHold.Pitch] Running Loop=1
2023-08-16T16:17:12.795Z,1692202632.795 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-08-16T16:17:12.803Z,1692202632.803 [transit:StandardEnvelopes] Running Loop=1
2023-08-16T16:17:12.804Z,1692202632.804 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-08-16T16:17:12.830Z,1692202632.830 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-08-16T16:17:12.830Z,1692202632.830 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-08-16T16:17:12.831Z,1692202632.831 [transit:B] Stopped
2023-08-16T16:17:12.831Z,1692202632.831 [transit:C] Running Loop=1
2023-08-16T16:17:13.208Z,1692202633.208 [transit:C] Stopped
2023-08-16T16:17:13.208Z,1692202633.208 [transit:E] Running Loop=1
2023-08-16T16:17:13.572Z,1692202633.572 [transit:E] Stopped
2023-08-16T16:17:13.573Z,1692202633.573 [transit:F] Running Loop=1
2023-08-16T16:17:13.969Z,1692202633.969 [transit:F] Stopped
2023-08-16T16:17:13.969Z,1692202633.969 [transit:G] Running Loop=1
2023-08-16T16:17:14.371Z,1692202634.371 [transit:G] Stopped
2023-08-16T16:17:14.371Z,1692202634.371 [transit:Transit] Running Loop=1
2023-08-16T16:17:14.371Z,1692202634.371 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-08-16T16:17:14.371Z,1692202634.371 [transit:Transit:A.Pitch] Running Loop=1
2023-08-16T16:17:14.371Z,1692202634.371 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-08-16T16:17:14.372Z,1692202634.372 [transit:Transit:B.SetSpeed] Running Loop=1
2023-08-16T16:17:14.372Z,1692202634.372 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-08-16T16:17:14.372Z,1692202634.372 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-08-16T16:17:14.372Z,1692202634.372 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-08-16T16:17:14.374Z,1692202634.374 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 43.200169,-86.364899
2023-08-16T16:17:14.375Z,1692202634.375 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2023-08-16T16:17:14.781Z,1692202634.781 [transit:Transit:B.SetSpeed] Running Loop=1
2023-08-16T16:17:14.782Z,1692202634.782 [transit:Transit:A.Pitch] Running Loop=1
2023-08-16T16:17:22.844Z,1692202642.844 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T16:17:42.096Z,1692202662.096 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:17:42.483Z,1692202662.483 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:17:42.911Z,1692202662.911 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:17:43.400Z,1692202663.400 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:17:43.767Z,1692202663.767 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:17:44.009Z,1692202664.009 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-08-16T16:17:44.009Z,1692202664.009 [ThrusterHE](INFO): Powering down
2023-08-16T16:17:44.849Z,1692202664.849 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-16T16:17:44.969Z,1692202664.969 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-16T16:17:55.699Z,1692202675.699 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230816T144223/Courier0067.lzma
2023-08-16T16:17:56.702Z,1692202676.702 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0067.lzma.bak
2023-08-16T16:17:56.702Z,1692202676.702 [DataOverHttps](INFO): SBD MOMSN=18564495
2023-08-16T16:18:19.663Z,1692202699.663 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:18:20.019Z,1692202700.019 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:18:20.451Z,1692202700.451 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:18:20.808Z,1692202700.808 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:18:21.239Z,1692202701.239 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:18:21.723Z,1692202701.723 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-08-16T16:18:22.073Z,1692202702.073 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-08-16T16:18:22.073Z,1692202702.073 [ThrusterHE](INFO): Powering down
2023-08-16T16:18:22.742Z,1692202702.742 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-16T16:18:22.861Z,1692202702.861 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-16T16:19:43.881Z,1692202783.881 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T161943
2023-08-16T16:19:51.583Z,1692202791.583 [DataOverHttps](INFO): Received command: stop; schedule resume
2023-08-16T16:19:51.594Z,1692202791.594 [CommandExec](IMPORTANT): got command stop
2023-08-16T16:19:51.594Z,1692202791.594 [CommandExec](IMPORTANT): Scheduling is paused
2023-08-16T16:19:51.594Z,1692202791.594 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-08-16T16:19:51.595Z,1692202791.595 [CommandExec](IMPORTANT): got command schedule resume
2023-08-16T16:19:51.595Z,1692202791.595 [CommandExec](IMPORTANT): Scheduling is resumed
2023-08-16T16:19:51.682Z,1692202791.682 [MissionManager](INFO): MissionManager is completed.
2023-08-16T16:19:51.682Z,1692202791.682 [MissionManager](INFO): Uninitializing Mission transit
2023-08-16T16:19:51.682Z,1692202791.682 [transit] Stopped
2023-08-16T16:19:51.682Z,1692202791.682 [transit](DEBUG): Aggregate::uninitialize transit
2023-08-16T16:19:51.682Z,1692202791.682 [transit:StandardEnvelopes] Stopped
2023-08-16T16:19:51.682Z,1692202791.682 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2023-08-16T16:19:51.682Z,1692202791.682 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2023-08-16T16:19:51.682Z,1692202791.682 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2023-08-16T16:19:51.682Z,1692202791.682 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2023-08-16T16:19:51.683Z,1692202791.683 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2023-08-16T16:19:51.683Z,1692202791.683 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2023-08-16T16:19:51.683Z,1692202791.683 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2023-08-16T16:19:51.683Z,1692202791.683 [transit:BuoyancyHold.Buoyancy] Stopped
2023-08-16T16:19:51.683Z,1692202791.683 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-08-16T16:19:51.683Z,1692202791.683 [transit:MassHold.Pitch] Stopped
2023-08-16T16:19:51.683Z,1692202791.683 [transit:Transit] Stopped
2023-08-16T16:19:51.683Z,1692202791.683 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2023-08-16T16:19:51.683Z,1692202791.683 [transit:Transit:A.Pitch] Stopped
2023-08-16T16:19:51.683Z,1692202791.683 [transit:Transit:B.SetSpeed] Stopped
2023-08-16T16:19:51.683Z,1692202791.683 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2023-08-16T16:19:51.684Z,1692202791.684 [transit:Transit:Wpt1.Waypoint] Stopped
2023-08-16T16:19:51.684Z,1692202791.684 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2023-08-16T16:19:52.086Z,1692202792.086 [MissionManager](IMPORTANT): Started mission Default
2023-08-16T16:19:52.087Z,1692202792.087 [Default] Running Loop=1
2023-08-16T16:19:52.087Z,1692202792.087 [Default](DEBUG): Aggregate::initialize Default
2023-08-16T16:19:52.087Z,1692202792.087 [Default:B.GoToSurface] Running Loop=1
2023-08-16T16:19:52.087Z,1692202792.087 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-16T16:19:52.087Z,1692202792.087 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-16T16:19:52.088Z,1692202792.088 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-16T16:19:52.088Z,1692202792.088 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-16T16:19:52.088Z,1692202792.088 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-16T16:19:52.088Z,1692202792.088 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-16T16:19:52.089Z,1692202792.089 [Default:A.Wait] Running Loop=1
2023-08-16T16:19:52.089Z,1692202792.089 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-16T16:20:05.425Z,1692202805.425 [Default:A.Wait](INFO): Done Waiting.
2023-08-16T16:20:05.425Z,1692202805.425 [Default:A.Wait] Stopped
2023-08-16T16:20:05.425Z,1692202805.425 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T16:20:05.815Z,1692202805.815 [Default:CheckIn] Running Loop=1
2023-08-16T16:20:05.828Z,1692202805.828 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T16:20:05.828Z,1692202805.828 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T16:20:07.827Z,1692202807.827 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162007.00,A,4313.33761,N,08620.16396,W,0.019,174.88,160823,,,D*7D
2023-08-16T16:20:07.829Z,1692202807.829 [NAL9602](INFO): GPS fix at 20230816T162007: (43.222293, -86.336066)
2023-08-16T16:20:07.839Z,1692202807.839 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T16:20:07.839Z,1692202807.839 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T16:20:16.063Z,1692202816.063 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230816T144223/Courier0070.lzma
2023-08-16T16:20:17.065Z,1692202817.065 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0070.lzma.bak
2023-08-16T16:20:17.066Z,1692202817.066 [DataOverHttps](INFO): SBD MOMSN=18564500
2023-08-16T16:20:35.350Z,1692202835.350 [NAL9602](INFO): SBD MO Status=0, MOMSN=2125, MT Status=0, MTMSN=0
2023-08-16T16:20:35.350Z,1692202835.350 [NAL9602](INFO): No messages in MT queue
2023-08-16T16:20:37.784Z,1692202837.784 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20230816T144223/Express0062.lzma
2023-08-16T16:20:38.786Z,1692202838.786 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0062.lzma.bak
2023-08-16T16:20:38.786Z,1692202838.786 [DataOverHttps](INFO): SBD MOMSN=18564503
2023-08-16T16:20:53.861Z,1692202853.861 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T162053
2023-08-16T16:21:01.180Z,1692202861.180 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20230816T144223/Express0065.lzma
2023-08-16T16:21:01.182Z,1692202861.182 [DataOverHttps](INFO): Received command: get distance_from_shore
2023-08-16T16:21:01.911Z,1692202861.911 [CommandExec](IMPORTANT): got command get distance_from_shore
2023-08-16T16:21:01.912Z,1692202861.912 [CommandExec](IMPORTANT): distance_from_shore 127.791466 m
2023-08-16T16:21:02.361Z,1692202862.361 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0065.lzma.bak
2023-08-16T16:21:02.361Z,1692202862.361 [DataOverHttps](INFO): SBD MOMSN=18564508
2023-08-16T16:21:06.124Z,1692202866.124 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T16:21:17.951Z,1692202877.951 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230816T144223/Courier0076.lzma
2023-08-16T16:21:18.953Z,1692202878.953 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Courier0076.lzma.bak
2023-08-16T16:21:18.954Z,1692202878.954 [DataOverHttps](INFO): SBD MOMSN=18564514
2023-08-16T16:21:36.100Z,1692202896.100 [DataOverHttps](INFO): Sending 351 bytes from file Logs/20230816T144223/Express0068.lzma
2023-08-16T16:21:37.102Z,1692202897.102 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0068.lzma.bak
2023-08-16T16:21:37.102Z,1692202897.102 [DataOverHttps](INFO): SBD MOMSN=18564516
2023-08-16T16:21:54.340Z,1692202914.340 [DataOverHttps](INFO): Sending 563 bytes from file Logs/20230816T144223/Express0071.lzma
2023-08-16T16:21:55.342Z,1692202915.342 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0071.lzma.bak
2023-08-16T16:21:55.342Z,1692202915.342 [DataOverHttps](INFO): SBD MOMSN=18564526
2023-08-16T16:22:14.031Z,1692202934.031 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230816T144223/Express0074.lzma
2023-08-16T16:22:15.034Z,1692202935.034 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0074.lzma.bak
2023-08-16T16:22:15.034Z,1692202935.034 [DataOverHttps](INFO): SBD MOMSN=18564557
2023-08-16T16:22:35.532Z,1692202955.532 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20230816T144223/Express0077.lzma
2023-08-16T16:22:36.534Z,1692202956.534 [DataOverHttps](INFO): Moved sent file to Logs/20230816T144223/Express0077.lzma.bak
2023-08-16T16:22:36.534Z,1692202956.534 [DataOverHttps](INFO): SBD MOMSN=18564559
2023-08-16T16:22:40.393Z,1692202960.393 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T16:22:40.393Z,1692202960.393 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-16T16:22:40.393Z,1692202960.393 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-16T16:26:53.482Z,1692203213.482 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T162652
2023-08-16T16:27:00.827Z,1692203220.827 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 43.20017 degree;set transit.Longitude -86.3649 degree;set transit.Depth 1 m;set transit.MaxDepth 2 m" 4eocd 1 2
2023-08-16T16:27:00.844Z,1692203220.844 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 43.20017 degree;set transit.Longitude -86.3649 degree;set transit.Depth 1 m;set transit.MaxDepth 2 m" 4eocd 1 2.000000
2023-08-16T16:27:00.844Z,1692203220.844 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=4eocd
2023-08-16T16:27:00.845Z,1692203220.845 [CommandExec](IMPORTANT): Scheduled #3 (#1 of 2 with id='4eocd'): "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 43.20017 degree;set transit.Longitude -86.3649 degree;set transit.Depth 1 m;set transit.MaxDepth 2 m", ASAP
2023-08-16T16:27:01.829Z,1692203221.829 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T162701
2023-08-16T16:27:11.219Z,1692203231.219 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .0000000001 km;run" 4eocd 2 2
2023-08-16T16:27:11.266Z,1692203231.266 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .0000000001 km;run" 4eocd 2 2.000000
2023-08-16T16:27:11.267Z,1692203231.267 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=4eocd
2023-08-16T16:27:11.268Z,1692203231.268 [CommandExec](IMPORTANT): Scheduled #4 (#2 of 2 with id='4eocd'): "set transit.MinOffshore .0000000001 km;run", ASAP
2023-08-16T16:27:11.380Z,1692203231.380 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-08-16T16:27:11.381Z,1692203231.381 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-08-16T16:27:11.381Z,1692203231.381 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-08-16T16:27:11.449Z,1692203231.449 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-08-16T16:27:11.451Z,1692203231.451 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-08-16T16:27:11.462Z,1692203231.462 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-08-16T16:27:11.464Z,1692203231.464 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-08-16T16:27:11.466Z,1692203231.466 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-08-16T16:27:11.468Z,1692203231.468 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-08-16T16:27:11.470Z,1692203231.470 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-08-16T16:27:11.472Z,1692203231.472 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-08-16T16:27:11.474Z,1692203231.474 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-08-16T16:27:11.476Z,1692203231.476 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-08-16T16:27:11.478Z,1692203231.478 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-08-16T16:27:11.480Z,1692203231.480 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-08-16T16:27:11.482Z,1692203231.482 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-08-16T16:27:11.482Z,1692203231.482 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-08-16T16:27:11.483Z,1692203231.483 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-08-16T16:27:11.640Z,1692203231.640 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-08-16T16:27:11.642Z,1692203231.642 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-08-16T16:27:11.648Z,1692203231.648 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-08-16T16:27:11.653Z,1692203231.653 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-08-16T16:27:11.655Z,1692203231.655 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-08-16T16:27:11.658Z,1692203231.658 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-08-16T16:27:11.664Z,1692203231.664 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-08-16T16:27:11.666Z,1692203231.666 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-08-16T16:27:11.676Z,1692203231.676 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-16T16:27:11.712Z,1692203231.712 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-08-16T16:27:11.714Z,1692203231.714 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-08-16T16:27:11.772Z,1692203231.772 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-08-16T16:27:11.774Z,1692203231.774 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-08-16T16:27:11.788Z,1692203231.788 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-08-16T16:27:11.790Z,1692203231.790 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-08-16T16:27:11.791Z,1692203231.791 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-08-16T16:27:11.798Z,1692203231.798 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-08-16T16:27:11.803Z,1692203231.803 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-08-16T16:27:11.826Z,1692203231.826 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-08-16T16:27:11.829Z,1692203231.829 [transit:MassHold.Pitch](DEBUG): Construct.
2023-08-16T16:27:11.841Z,1692203231.841 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-08-16T16:27:11.845Z,1692203231.845 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-08-16T16:27:11.847Z,1692203231.847 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-08-16T16:27:11.861Z,1692203231.861 [MissionManager](DEBUG): 
mission transit {
  """
  Vehicle transits to desired waypoint and can be commanded to use/hold
  mass or buoyancy.
  """

  arguments {
    MissionTimeout = 60 minute
      """
      Maximum duration of mission
      """

    NeedCommsTime = 30 minute
      """
      How often to surface for commumications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to seek. If set to NaN, uses latitude at mission
      initialization.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to seek. If set to NaN, uses longitude at mission
      initialization.
      """

    Northings = 0 meter
      """
      Northward distance of the waypoint to seek. If combined with Latitude
      and/or Longitude, this is an offset from the specified Latitude and/or
      Longitude.
      """

    Eastings = 0 meter
      """
      Eastward distance of the waypoint to seek. If combined with Latitude
      and/or Longitude, this is an offset from the specified Latitude and/or
      Longitude.
      """

    Depth = 10 meter
      """
      Depth of flat and level flight during the mission.
      """

    Speed = 1 meter_per_second
      """
      Speed of vehicle (relative to water) during the mission.
      """

    MaxDepth = 20 meter
      """
      Maximum depth for the entire mission.
      """

    MinOffshore = 1 kilometer
      """
      Minimum distance offshore for the entire mission.
      """

    MinAltitude = 7 meter
      """
      Minimum height above the sea floor for the entire mission.
      """

    MassHold = true
      """
      Set to True in order to hold mass at default position, False to allow
      mass to run on its own.
      """

    BuoyancyHold = true
      """
      Set to True in order to hold buoyancy at neutral position, False to
      allow buoyancy to run on its own.
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  assign in sequence NeedComms:WaitForPitchUp = 1 second

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MinAltitude = MinAltitude

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  behavior Guidance:Buoyancy id="BuoyancyHold" {
    run while ( BuoyancyHold )

    set position = Control:VerticalControl.buoyancyNeutral
  }

  behavior Guidance:Pitch id="MassHold" {
    run while ( MassHold )

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate Transit {
    run in sequence

    behavior Guidance:Pitch {
      run in parallel

      set depth = Depth
    }

    behavior Guidance:SetSpeed {
      run in parallel

      set speed = Speed
    }

    behavior Guidance:Waypoint id="Wpt1" {
      run in sequence

      set latitude = Latitude
      set longitude = Longitude
      set northingsDelta = Northings
      set eastingsDelta = Eastings
    }

    call id="PhoneHome" refId="NeedComms"
  }
}


2023-08-16T16:27:11.861Z,1692203231.861 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-08-16T16:27:19.883Z,1692203239.883 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute
2023-08-16T16:27:19.884Z,1692203239.884 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 12 minute
2023-08-16T16:27:19.884Z,1692203239.884 [CommandExec](IMPORTANT): got command set transit.Latitude 43.20017 degree
2023-08-16T16:27:19.885Z,1692203239.885 [CommandExec](IMPORTANT): got command set transit.Longitude -86.3649 degree
2023-08-16T16:27:19.886Z,1692203239.886 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-08-16T16:27:19.886Z,1692203239.886 [CommandExec](IMPORTANT): got command set transit.MaxDepth 2 meter
2023-08-16T16:27:21.446Z,1692203241.446 [CommandExec](IMPORTANT): got command set transit.MinOffshore .0000000001 kilometer
2023-08-16T16:27:21.446Z,1692203241.446 [CommandExec](IMPORTANT): got command run
2023-08-16T16:27:21.448Z,1692203241.448 [CommandExec](IMPORTANT): Running 
2023-08-16T16:27:21.625Z,1692203241.625 [Default] Stopped
2023-08-16T16:27:21.625Z,1692203241.625 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-16T16:27:21.625Z,1692203241.625 [Default:B.GoToSurface] Stopped
2023-08-16T16:27:21.625Z,1692203241.625 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-16T16:27:21.625Z,1692203241.625 [Default:CheckIn] Stopped
2023-08-16T16:27:21.625Z,1692203241.625 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T16:27:21.625Z,1692203241.625 [Default:CheckIn:C.Wait] Stopped
2023-08-16T16:27:21.625Z,1692203241.625 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T16:27:21.625Z,1692203241.625 [MissionManager](IMPORTANT): Started mission transit
2023-08-16T16:27:21.626Z,1692203241.626 [transit] Running Loop=1
2023-08-16T16:27:21.626Z,1692203241.626 [transit](DEBUG): Aggregate::initialize transit
2023-08-16T16:27:21.626Z,1692203241.626 [transit:StandardEnvelopes] Running Loop=1
2023-08-16T16:27:21.626Z,1692203241.626 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-08-16T16:27:21.626Z,1692203241.626 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-08-16T16:27:21.626Z,1692203241.626 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-08-16T16:27:21.626Z,1692203241.626 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-08-16T16:27:21.626Z,1692203241.626 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-08-16T16:27:21.627Z,1692203241.627 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-08-16T16:27:21.627Z,1692203241.627 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-08-16T16:27:21.627Z,1692203241.627 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-08-16T16:27:21.627Z,1692203241.627 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-08-16T16:27:21.627Z,1692203241.627 [transit:MassHold.Pitch] Running Loop=1
2023-08-16T16:27:21.627Z,1692203241.627 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-08-16T16:27:21.627Z,1692203241.627 [transit:B] Running Loop=1
2023-08-16T16:27:21.628Z,1692203241.628 [transit:MassHold.Pitch] Running Loop=1
2023-08-16T16:27:21.628Z,1692203241.628 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-08-16T16:27:21.628Z,1692203241.628 [transit:StandardEnvelopes] Running Loop=1
2023-08-16T16:27:21.629Z,1692203241.629 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-08-16T16:27:21.653Z,1692203241.653 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-08-16T16:27:21.653Z,1692203241.653 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-08-16T16:27:21.653Z,1692203241.653 [transit:B] Stopped
2023-08-16T16:27:21.654Z,1692203241.654 [transit:C] Running Loop=1
2023-08-16T16:27:22.041Z,1692203242.041 [transit:C] Stopped
2023-08-16T16:27:22.041Z,1692203242.041 [transit:E] Running Loop=1
2023-08-16T16:27:22.438Z,1692203242.438 [transit:E] Stopped
2023-08-16T16:27:22.438Z,1692203242.438 [transit:F] Running Loop=1
2023-08-16T16:27:22.853Z,1692203242.853 [transit:F] Stopped
2023-08-16T16:27:22.853Z,1692203242.853 [transit:G] Running Loop=1
2023-08-16T16:27:23.247Z,1692203243.247 [transit:G] Stopped
2023-08-16T16:27:23.247Z,1692203243.247 [transit:Transit] Running Loop=1
2023-08-16T16:27:23.247Z,1692203243.247 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-08-16T16:27:23.247Z,1692203243.247 [transit:Transit:A.Pitch] Running Loop=1
2023-08-16T16:27:23.247Z,1692203243.247 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-08-16T16:27:23.248Z,1692203243.248 [transit:Transit:B.SetSpeed] Running Loop=1
2023-08-16T16:27:23.248Z,1692203243.248 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-08-16T16:27:23.248Z,1692203243.248 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-08-16T16:27:23.248Z,1692203243.248 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-08-16T16:27:23.249Z,1692203243.249 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 43.200169,-86.364899
2023-08-16T16:27:23.250Z,1692203243.250 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2023-08-16T16:27:23.646Z,1692203243.646 [transit:Transit:B.SetSpeed] Running Loop=1
2023-08-16T16:27:23.647Z,1692203243.647 [transit:Transit:A.Pitch] Running Loop=1
2023-08-16T16:28:08.456Z,1692203288.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-16T16:28:15.484Z,1692203295.484 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251263
2023-08-16T16:29:18.748Z,1692203358.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-16T16:29:51.650Z,1692203391.650 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004057
2023-08-16T16:30:51.804Z,1692203451.804 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.158348
2023-08-16T16:31:39.996Z,1692203499.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-16T16:32:08.061Z,1692203528.061 [transit:NeedComms] Running Loop=1
2023-08-16T16:32:08.061Z,1692203528.061 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2023-08-16T16:32:08.061Z,1692203528.061 [transit:NeedComms:B.GoToSurface] Running Loop=1
2023-08-16T16:32:08.061Z,1692203528.061 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-16T16:32:08.062Z,1692203528.062 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2023-08-16T16:32:08.062Z,1692203528.062 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2023-08-16T16:32:08.063Z,1692203528.063 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2023-08-16T16:32:08.063Z,1692203528.063 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2023-08-16T16:32:08.063Z,1692203528.063 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-16T16:32:08.064Z,1692203528.064 [transit:NeedComms:A] Running Loop=1
2023-08-16T16:32:08.065Z,1692203528.065 [transit:NeedComms:A](INFO): last time_fix was: 1692202807.000000 second since 1970/01/01T00:00:00Z 
2023-08-16T16:32:08.065Z,1692203528.065 [transit:NeedComms:A] Stopped
2023-08-16T16:32:14.137Z,1692203534.137 [transit:NeedComms:C] Running Loop=1
2023-08-16T16:32:14.540Z,1692203534.540 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-16T16:32:15.136Z,1692203535.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-16T16:32:29.867Z,1692203549.867 [NAL9602](INFO): SBD MO Status=2, MOMSN=2126, MT Status=2, MTMSN=0
2023-08-16T16:32:29.867Z,1692203549.867 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-16T16:32:49.262Z,1692203569.262 [NAL9602](INFO): SBD MO Status=0, MOMSN=2126, MT Status=0, MTMSN=0
2023-08-16T16:32:49.262Z,1692203569.262 [NAL9602](INFO): No messages in MT queue
2023-08-16T16:32:50.288Z,1692203570.288 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-16T16:32:50.479Z,1692203570.479 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163249.00,A,4313.23737,N,08620.33109,W,0.272,139.48,160823,,,D*7F
2023-08-16T16:32:50.481Z,1692203570.481 [NAL9602](INFO): GPS fix at 20230816T163249: (43.220623, -86.338852)
2023-08-16T16:32:50.494Z,1692203570.494 [transit:NeedComms:C] Stopped
2023-08-16T16:32:50.495Z,1692203570.495 [transit:NeedComms:D] Running Loop=1
2023-08-16T16:32:50.908Z,1692203570.908 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-16T16:33:05.333Z,1692203585.333 [DataOverHttps](IMPORTANT): SBD MTMSN=20230816T163304
2023-08-16T16:33:05.337Z,1692203585.337 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003665
2023-08-16T16:33:10.336Z,1692203590.336 [NAL9602](INFO): SBD MO Status=1, MOMSN=2127, MT Status=0, MTMSN=0
2023-08-16T16:33:10.384Z,1692203590.384 [NAL9602](INFO): Sent 72 bytes from file Logs/20230816T144223/Courier0079.lzma
2023-08-16T16:33:10.384Z,1692203590.384 [NAL9602](INFO): Packets left to send: 0
2023-08-16T16:33:15.139Z,1692203595.139 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230816T144223/Courier0082.lzma
2023-08-16T16:33:15.141Z,1692203595.141 [DataOverHttps](INFO): Received command: restart logs