2023-09-08T14:13:21.817Z,1694182401.817 [Supervisor](DEBUG): Initializing supervisor.
2023-09-08T14:13:21.821Z,1694182401.821 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-08T14:13:21.822Z,1694182401.822 [SyncHandler](INFO): Protected caller Thread ID is 1849
2023-09-08T14:13:21.823Z,1694182401.823 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-08T14:13:21.823Z,1694182401.823 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-08T14:13:21.824Z,1694182401.824 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1850
2023-09-08T14:13:21.828Z,1694182401.828 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-08T14:13:21.846Z,1694182401.846 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-08T14:13:21.847Z,1694182401.847 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-08T14:13:21.847Z,1694182401.847 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1851
2023-09-08T14:13:21.851Z,1694182401.851 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-08T14:13:21.852Z,1694182401.852 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-08T14:13:21.853Z,1694182401.853 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1852
2023-09-08T14:13:21.855Z,1694182401.855 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-08T14:13:21.856Z,1694182401.856 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-08T14:13:21.856Z,1694182401.856 [logger ThreadHandler](INFO): Protected caller Thread ID is 1853
2023-09-08T14:13:21.860Z,1694182401.860 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-08T14:13:21.860Z,1694182401.860 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-08T14:13:21.862Z,1694182401.862 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-08T14:13:22.385Z,1694182402.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-08T14:13:22.385Z,1694182402.385 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-08T14:13:22.690Z,1694182402.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-08T14:13:22.690Z,1694182402.690 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-08T14:13:22.773Z,1694182402.773 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-08T14:13:23.006Z,1694182403.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-08T14:13:23.006Z,1694182403.006 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-08T14:13:23.087Z,1694182403.087 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-08T14:13:23.187Z,1694182403.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-08T14:13:23.188Z,1694182403.188 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-08T14:13:23.570Z,1694182403.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-08T14:13:23.570Z,1694182403.570 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-08T14:13:23.710Z,1694182403.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-08T14:13:23.710Z,1694182403.710 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-08T14:13:24.233Z,1694182404.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-08T14:13:24.234Z,1694182404.234 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-08T14:13:24.443Z,1694182404.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-08T14:13:24.444Z,1694182404.444 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-08T14:13:24.892Z,1694182404.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-08T14:13:24.893Z,1694182404.893 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-08T14:13:25.204Z,1694182405.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-08T14:13:25.205Z,1694182405.205 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-08T14:13:25.319Z,1694182405.319 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-08T14:13:25.320Z,1694182405.320 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-08T14:13:26.158Z,1694182406.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-08T14:13:26.159Z,1694182406.159 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-08T14:13:26.514Z,1694182406.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-08T14:13:26.515Z,1694182406.515 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-08T14:13:26.759Z,1694182406.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-08T14:13:26.761Z,1694182406.761 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-09-08T14:13:26.762Z,1694182406.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-09-08T14:13:26.889Z,1694182406.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-09-08T14:13:26.975Z,1694182406.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-09-08T14:13:27.118Z,1694182407.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-09-08T14:13:27.206Z,1694182407.206 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-09-08T14:13:27.572Z,1694182407.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-08T14:13:27.573Z,1694182407.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-09-08T14:13:28.239Z,1694182408.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-09-08T14:13:28.351Z,1694182408.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-09-08T14:13:28.475Z,1694182408.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-09-08T14:13:28.633Z,1694182408.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-09-08T14:13:28.735Z,1694182408.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-09-08T14:13:28.838Z,1694182408.838 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2023-09-08T14:13:28.838Z,1694182408.838 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-08T14:13:28.852Z,1694182408.852 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-08T14:13:28.863Z,1694182408.863 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-08T14:13:28.864Z,1694182408.864 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-08T14:13:28.973Z,1694182408.973 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-08T14:13:28.973Z,1694182408.973 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-08T14:13:29.029Z,1694182409.029 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-08T14:13:29.092Z,1694182409.092 [VerticalControl] Loaded
2023-09-08T14:13:29.092Z,1694182409.092 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-08T14:13:29.095Z,1694182409.095 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-08T14:13:29.138Z,1694182409.138 [HorizontalControl] Loaded
2023-09-08T14:13:29.138Z,1694182409.138 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-08T14:13:29.140Z,1694182409.140 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-08T14:13:29.144Z,1694182409.144 [SpeedControl] Loaded
2023-09-08T14:13:29.144Z,1694182409.144 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-08T14:13:29.147Z,1694182409.147 [LoopControl](DEBUG): Construct LoopControl.
2023-09-08T14:13:29.147Z,1694182409.147 [LoopControl] Loaded
2023-09-08T14:13:29.147Z,1694182409.147 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-08T14:13:29.148Z,1694182409.148 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-08T14:13:29.148Z,1694182409.148 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-08T14:13:29.313Z,1694182409.313 [BuoyancyServo] Loaded
2023-09-08T14:13:29.313Z,1694182409.313 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-08T14:13:29.335Z,1694182409.335 [ElevatorServo] Loaded
2023-09-08T14:13:29.335Z,1694182409.335 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-08T14:13:29.355Z,1694182409.355 [MassServo] Loaded
2023-09-08T14:13:29.355Z,1694182409.355 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-08T14:13:29.374Z,1694182409.374 [RudderServo] Loaded
2023-09-08T14:13:29.374Z,1694182409.374 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-08T14:13:29.390Z,1694182409.390 [ThrusterHE] Loaded
2023-09-08T14:13:29.391Z,1694182409.391 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-08T14:13:29.391Z,1694182409.391 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-08T14:13:29.392Z,1694182409.392 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-08T14:13:29.445Z,1694182409.445 [DepthRateCalculator] Loaded
2023-09-08T14:13:29.445Z,1694182409.445 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-08T14:13:29.450Z,1694182409.450 [PitchRateCalculator] Loaded
2023-09-08T14:13:29.450Z,1694182409.450 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-08T14:13:29.460Z,1694182409.460 [SpeedCalculator] Loaded
2023-09-08T14:13:29.461Z,1694182409.461 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-08T14:13:29.465Z,1694182409.465 [YawRateCalculator] Loaded
2023-09-08T14:13:29.465Z,1694182409.465 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-08T14:13:29.485Z,1694182409.485 [ElevatorOffsetCalculator] Loaded
2023-09-08T14:13:29.485Z,1694182409.485 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-08T14:13:29.486Z,1694182409.486 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-08T14:13:29.486Z,1694182409.486 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-08T14:13:29.605Z,1694182409.605 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-09-08T14:13:29.605Z,1694182409.605 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-09-08T14:13:29.619Z,1694182409.619 [NavChart] Loaded
2023-09-08T14:13:29.620Z,1694182409.620 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-08T14:13:29.626Z,1694182409.626 [UniversalFixResidualReporter] Loaded
2023-09-08T14:13:29.626Z,1694182409.626 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-08T14:13:29.626Z,1694182409.626 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-08T14:13:29.627Z,1694182409.627 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-08T14:13:30.077Z,1694182410.077 [AHRS_M2] Loaded
2023-09-08T14:13:30.077Z,1694182410.077 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-08T14:13:30.114Z,1694182410.114 [BackseatComponent] Loaded
2023-09-08T14:13:30.114Z,1694182410.114 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-08T14:13:30.115Z,1694182410.115 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E84E0
2023-09-08T14:13:30.116Z,1694182410.116 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1935
2023-09-08T14:13:30.119Z,1694182410.119 [LcmUniversalReporter] Loaded
2023-09-08T14:13:30.119Z,1694182410.119 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-08T14:13:30.126Z,1694182410.126 [BioacousticsDataBridge] Loaded
2023-09-08T14:13:30.126Z,1694182410.126 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2023-09-08T14:13:30.990Z,1694182410.990 [BPC1] Loaded
2023-09-08T14:13:30.990Z,1694182410.990 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-08T14:13:31.066Z,1694182411.066 [DataOverHttps] Loaded
2023-09-08T14:13:31.066Z,1694182411.066 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-08T14:13:31.067Z,1694182411.067 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409184E0
2023-09-08T14:13:31.067Z,1694182411.067 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1936
2023-09-08T14:13:31.089Z,1694182411.089 [Depth_Keller] Loaded
2023-09-08T14:13:31.090Z,1694182411.090 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-08T14:13:31.095Z,1694182411.095 [DropWeight] Loaded
2023-09-08T14:13:31.095Z,1694182411.095 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-09-08T14:13:31.160Z,1694182411.160 [NAL9602] Loaded
2023-09-08T14:13:31.161Z,1694182411.161 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-08T14:13:31.192Z,1694182411.192 [Onboard] Loaded
2023-09-08T14:13:31.192Z,1694182411.192 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-08T14:13:31.193Z,1694182411.193 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409484E0
2023-09-08T14:13:31.194Z,1694182411.194 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1937
2023-09-08T14:13:31.206Z,1694182411.206 [Power24vConverter] Loaded
2023-09-08T14:13:31.207Z,1694182411.207 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-08T14:13:31.221Z,1694182411.221 [Radio_Surface] Loaded
2023-09-08T14:13:31.221Z,1694182411.221 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-08T14:13:31.222Z,1694182411.222 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409784E0
2023-09-08T14:13:31.222Z,1694182411.222 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1938
2023-09-08T14:13:31.237Z,1694182411.237 [Sonardyne_Nano] Loaded
2023-09-08T14:13:31.237Z,1694182411.237 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-09-08T14:13:31.237Z,1694182411.237 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-08T14:13:31.238Z,1694182411.238 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-08T14:13:31.297Z,1694182411.297 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-08T14:13:31.298Z,1694182411.298 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-08T14:13:31.458Z,1694182411.458 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-08T14:13:31.459Z,1694182411.459 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-08T14:13:31.487Z,1694182411.487 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-08T14:13:31.487Z,1694182411.487 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-08T14:13:31.669Z,1694182411.669 [CTD_Seabird] Loaded
2023-09-08T14:13:31.669Z,1694182411.669 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-08T14:13:31.670Z,1694182411.670 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2023-09-08T14:13:31.671Z,1694182411.671 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1939
2023-09-08T14:13:31.692Z,1694182411.692 [PAR_Licor] Loaded
2023-09-08T14:13:31.692Z,1694182411.692 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-08T14:13:31.735Z,1694182411.735 [WetLabsBB2FL] Loaded
2023-09-08T14:13:31.735Z,1694182411.735 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-08T14:13:31.736Z,1694182411.736 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2023-09-08T14:13:31.737Z,1694182411.737 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1940
2023-09-08T14:13:31.737Z,1694182411.737 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-08T14:13:31.738Z,1694182411.738 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-08T14:13:32.071Z,1694182412.071 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-08T14:13:32.071Z,1694182412.071 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-08T14:13:32.221Z,1694182412.221 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-08T14:13:32.230Z,1694182412.230 [SBIT] Loaded
2023-09-08T14:13:32.231Z,1694182412.231 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-08T14:13:32.234Z,1694182412.234 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-08T14:13:32.248Z,1694182412.248 [IBIT] Loaded
2023-09-08T14:13:32.248Z,1694182412.248 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-08T14:13:32.254Z,1694182412.254 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-08T14:13:32.364Z,1694182412.364 [CBIT] Loaded
2023-09-08T14:13:32.364Z,1694182412.364 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-08T14:13:32.365Z,1694182412.365 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-08T14:13:32.371Z,1694182412.371 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-08T14:13:32.374Z,1694182412.374 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-08T14:13:32.386Z,1694182412.386 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-08T14:13:32.387Z,1694182412.387 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-09-08T14:13:32.387Z,1694182412.387 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1941
2023-09-08T14:13:32.392Z,1694182412.392 [Supervisor](INFO): Main Thread ID is 836
2023-09-08T14:13:32.392Z,1694182412.392 [Supervisor](DEBUG): Running supervisor.
2023-09-08T14:13:32.393Z,1694182412.393 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1942
2023-09-08T14:13:32.393Z,1694182412.393 [CommandExec](INFO): Initializing the command executive.
2023-09-08T14:13:32.395Z,1694182412.395 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1943
2023-09-08T14:13:32.398Z,1694182412.398 [controlThread ThreadHandler](INFO): Handler Thread ID is 1944
2023-09-08T14:13:32.398Z,1694182412.398 [controlThread](DEBUG): Initializing ControlThread
2023-09-08T14:13:32.399Z,1694182412.399 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-08T14:13:32.401Z,1694182412.401 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-08T14:13:32.402Z,1694182412.402 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-08T14:13:32.402Z,1694182412.402 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-08T14:13:32.404Z,1694182412.404 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-08T14:13:32.404Z,1694182412.404 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-08T14:13:32.404Z,1694182412.404 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-08T14:13:32.405Z,1694182412.405 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-08T14:13:32.405Z,1694182412.405 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-08T14:13:32.406Z,1694182412.406 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-08T14:13:32.406Z,1694182412.406 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-08T14:13:32.413Z,1694182412.413 [SBIT](INFO): Initialize SBIT Component.
2023-09-08T14:13:32.414Z,1694182412.414 [SBIT](IMPORTANT): git: 2023-09-07
2023-09-08T14:13:32.414Z,1694182412.414 [SBIT](INFO): git hash: de73b65bba9e058a7fe4bf2bc7b950ee8b764c06
2023-09-08T14:13:32.414Z,1694182412.414 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-08T14:13:32.415Z,1694182412.415 [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-09-08T14:13:32.416Z,1694182412.416 [SBIT](INFO): Beginning SBIT in 122.000000 seconds.
2023-09-08T14:13:32.417Z,1694182412.417 [IBIT](INFO): Initialize IBIT Component.
2023-09-08T14:13:32.418Z,1694182412.418 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-08T14:13:32.419Z,1694182412.419 [logger ThreadHandler](INFO): Handler Thread ID is 1945
2023-09-08T14:13:32.429Z,1694182412.429 [CBIT](DEBUG): Initialized mux pins.
2023-09-08T14:13:32.429Z,1694182412.429 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-08T14:13:32.437Z,1694182412.437 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1946
2023-09-08T14:13:32.449Z,1694182412.449 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1947
2023-09-08T14:13:32.450Z,1694182412.450 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-08T14:13:32.453Z,1694182412.453 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-08T14:13:32.453Z,1694182412.453 [CBIT](DEBUG): Initializing heartbeat.
2023-09-08T14:13:32.461Z,1694182412.461 [Onboard ThreadHandler](INFO): Handler Thread ID is 1948
2023-09-08T14:13:32.479Z,1694182412.479 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1949
2023-09-08T14:13:32.501Z,1694182412.501 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1950
2023-09-08T14:13:32.502Z,1694182412.502 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-08T14:13:32.506Z,1694182412.506 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1952
2023-09-08T14:13:32.509Z,1694182412.509 [WetLabsBB2FL](INFO): Powering up
2023-09-08T14:13:32.510Z,1694182412.510 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1954
2023-09-08T14:13:32.514Z,1694182412.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T14:13:32.515Z,1694182412.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-09-08T14:13:32.515Z,1694182412.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T14:13:32.515Z,1694182412.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-09-08T14:13:32.515Z,1694182412.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T14:13:32.515Z,1694182412.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-09-08T14:13:32.516Z,1694182412.516 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T14:13:32.516Z,1694182412.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-09-08T14:13:32.516Z,1694182412.516 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T14:13:32.516Z,1694182412.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-09-08T14:13:32.516Z,1694182412.516 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T14:13:32.516Z,1694182412.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-09-08T14:13:32.518Z,1694182412.518 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T14:13:32.518Z,1694182412.518 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000
2023-09-08T14:13:32.518Z,1694182412.518 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T14:13:32.518Z,1694182412.518 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000
2023-09-08T14:13:32.519Z,1694182412.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T14:13:32.519Z,1694182412.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-09-08T14:13:32.519Z,1694182412.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T14:13:32.519Z,1694182412.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-09-08T14:13:32.519Z,1694182412.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T14:13:32.519Z,1694182412.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-09-08T14:13:32.519Z,1694182412.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T14:13:32.520Z,1694182412.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-09-08T14:13:32.520Z,1694182412.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T14:13:32.520Z,1694182412.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-09-08T14:13:32.525Z,1694182412.525 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-08T14:13:32.525Z,1694182412.525 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-08T14:13:32.565Z,1694182412.565 [CBIT](DEBUG): Backplane powered.
2023-09-08T14:13:32.566Z,1694182412.566 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-08T14:13:32.578Z,1694182412.578 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T14:13:32.590Z,1694182412.590 [MissionManager](DEBUG):
2023-09-08T14:13:32.591Z,1694182412.591 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-08T14:13:32.686Z,1694182412.686 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-08T14:13:32.687Z,1694182412.687 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-08T14:13:32.698Z,1694182412.698 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T14:13:32.746Z,1694182412.746 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-08T14:13:32.748Z,1694182412.748 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-08T14:13:32.770Z,1694182412.770 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-08T14:13:32.782Z,1694182412.782 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2023-09-08T14:13:32.787Z,1694182412.787 [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-09-08T14:13:32.847Z,1694182412.847 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-08T14:13:32.885Z,1694182412.885 [Radio_Surface](INFO): Powering up
2023-09-08T14:13:32.911Z,1694182412.911 [Power24vConverter](INFO): Powering up.
2023-09-08T14:13:32.912Z,1694182412.912 [Sonardyne_Nano](INFO): Initializing.
2023-09-08T14:13:33.007Z,1694182413.007 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-08T14:13:33.013Z,1694182413.013 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-08T14:13:33.014Z,1694182413.014 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-08T14:13:33.021Z,1694182413.021 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-08T14:13:33.022Z,1694182413.022 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-08T14:13:33.029Z,1694182413.029 [MassServo](DEBUG): Initializing MassServo.
2023-09-08T14:13:33.030Z,1694182413.030 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-08T14:13:33.038Z,1694182413.038 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-08T14:13:33.038Z,1694182413.038 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-08T14:13:33.053Z,1694182413.053 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-08T14:13:34.617Z,1694182414.617 [WetLabsBB2FL](INFO): Powering down
2023-09-08T14:13:35.219Z,1694182415.219 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-09-08T14:13:36.470Z,1694182416.470 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2201
2023-09-08T14:13:38.710Z,1694182418.710 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-08T14:14:01.482Z,1694182441.482 [NAL9602](INFO): Powering up NAL9602
2023-09-08T14:14:12.395Z,1694182452.395 [NAL9602](INFO): NAL9602 initialized
2023-09-08T14:15:00.068Z,1694182500.068 [NAL9602](INFO): SBD MO Status=0, MOMSN=3502, MT Status=0, MTMSN=0
2023-09-08T14:15:00.068Z,1694182500.068 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:15:01.280Z,1694182501.280 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141459.00,A,4145.53003,N,08116.64971,W,0.019,0.00,080923,,,A*7A
2023-09-08T14:15:01.327Z,1694182501.327 [NAL9602](INFO): GPS fix at 20230908T141459: (41.758834, -81.277495)
2023-09-08T14:15:33.193Z,1694182533.193 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:15:34.871Z,1694182534.871 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-08T14:15:34.875Z,1694182534.875 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-08T14:15:45.774Z,1694182545.774 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006577
CHAN A1 (24V): -0.007934
CHAN A2 (12V): -0.004845
CHAN A3 (5V): -0.002958
CHAN B0 (3.3V): -0.001499
CHAN B1 (3.15aV): -0.001075
CHAN B2 (3.15bV): -0.000269
CHAN B3 (GND): -0.000203
OPEN: 0.004035
Full Scale: +/- 1 mA
2023-09-08T14:16:28.986Z,1694182588.986 [SBIT](IMPORTANT): SBIT PASSED
2023-09-08T14:16:28.986Z,1694182588.986 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-08T14:16:28.987Z,1694182588.987 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2023-09-08T14:16:28.987Z,1694182588.987 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;
2023-09-08T14:16:28.987Z,1694182588.987 [SBIT](IMPORTANT): Express linearApproximation BackseatComponent.handled_message 1.000000 bool;
2023-09-08T14:16:28.988Z,1694182588.988 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius;
2023-09-08T14:16:28.988Z,1694182588.988 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;
2023-09-08T14:16:28.988Z,1694182588.988 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2023-09-08T14:16:28.988Z,1694182588.988 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter;
2023-09-08T14:16:28.988Z,1694182588.988 [SBIT](IMPORTANT): Express none platform_roll_angle;
2023-09-08T14:16:28.988Z,1694182588.988 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;
2023-09-08T14:16:28.988Z,1694182588.988 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=5 minute;
2023-09-08T14:16:28.989Z,1694182588.989 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;
2023-09-08T14:16:28.989Z,1694182588.989 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=391.543020 cubic_centimeter;
2023-09-08T14:16:28.989Z,1694182588.989 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;
2023-09-08T14:16:28.989Z,1694182588.989 [SBIT](IMPORTANT): VerticalControl.massDefault=25.724938 millimeter;
2023-09-08T14:16:29.380Z,1694182589.380 [MissionManager](IMPORTANT): Started mission Startup
2023-09-08T14:16:29.381Z,1694182589.381 [Startup] Running Loop=1
2023-09-08T14:16:29.381Z,1694182589.381 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-08T14:16:29.381Z,1694182589.381 [Startup:A.GoToSurface] Running Loop=1
2023-09-08T14:16:29.381Z,1694182589.381 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T14:16:29.381Z,1694182589.381 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T14:16:29.382Z,1694182589.382 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T14:16:29.382Z,1694182589.382 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T14:16:29.383Z,1694182589.383 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T14:16:29.383Z,1694182589.383 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T14:16:29.384Z,1694182589.384 [Startup:StartupSatComms] Running Loop=1
2023-09-08T14:16:29.384Z,1694182589.384 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-08T14:16:29.384Z,1694182589.385 [Startup:StartupSatComms:A] Running Loop=1
2023-09-08T14:16:29.790Z,1694182589.790 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-08T14:16:31.372Z,1694182591.372 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141629.00,A,4145.53005,N,08116.64923,W,0.019,0.00,080923,,,D*7B
2023-09-08T14:16:31.374Z,1694182591.374 [NAL9602](INFO): GPS fix at 20230908T141629: (41.758834, -81.277487)
2023-09-08T14:16:31.386Z,1694182591.386 [Startup:StartupSatComms:A] Stopped
2023-09-08T14:16:31.386Z,1694182591.386 [Startup:StartupSatComms:B] Running Loop=1
2023-09-08T14:16:31.804Z,1694182591.804 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T14:16:43.307Z,1694182603.307 [NAL9602](INFO): SBD MO Status=1, MOMSN=3503, MT Status=0, MTMSN=0
2023-09-08T14:16:43.365Z,1694182603.365 [NAL9602](INFO): Sent 39 bytes from file Logs/20230908T134902/Courier0016.lzma
2023-09-08T14:16:43.365Z,1694182603.365 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:16:55.610Z,1694182615.610 [NAL9602](INFO): SBD MO Status=1, MOMSN=3504, MT Status=0, MTMSN=0
2023-09-08T14:16:55.669Z,1694182615.669 [NAL9602](INFO): Sent 269 bytes from file Logs/20230908T141321/Courier0000.lzma
2023-09-08T14:16:55.669Z,1694182615.669 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:17:04.960Z,1694182624.960 [NAL9602](INFO): SBD MO Status=1, MOMSN=3505, MT Status=0, MTMSN=0
2023-09-08T14:17:05.013Z,1694182625.013 [NAL9602](INFO): Sent 110 bytes from file Logs/20230908T134902/Express0017.lzma
2023-09-08T14:17:05.013Z,1694182625.013 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:17:14.359Z,1694182634.359 [NAL9602](INFO): SBD MO Status=1, MOMSN=3506, MT Status=0, MTMSN=0
2023-09-08T14:17:14.417Z,1694182634.417 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T141321/Express0001.lzma
2023-09-08T14:17:14.417Z,1694182634.417 [NAL9602](INFO): Packets left to send: 3
2023-09-08T14:17:28.576Z,1694182648.576 [NAL9602](INFO): SBD MO Status=1, MOMSN=3507, MT Status=0, MTMSN=0
2023-09-08T14:17:28.629Z,1694182648.629 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T141321/Express0001.lzma
2023-09-08T14:17:28.629Z,1694182648.629 [NAL9602](INFO): Packets left to send: 2
2023-09-08T14:17:31.450Z,1694182651.450 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-08T14:16:31.4Z
2023-09-08T14:17:31.450Z,1694182651.450 [Startup:StartupSatComms:B] Stopped
2023-09-08T14:17:31.450Z,1694182651.450 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-08T14:17:31.450Z,1694182651.450 [Startup:StartupSatComms] Stopped
2023-09-08T14:17:31.450Z,1694182651.450 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-08T14:17:31.451Z,1694182651.451 [Startup](INFO): Completed Startup
2023-09-08T14:17:31.451Z,1694182651.451 [MissionManager](INFO): Startup is completed.
2023-09-08T14:17:31.451Z,1694182651.451 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-08T14:17:31.451Z,1694182651.451 [Startup] Stopped
2023-09-08T14:17:31.452Z,1694182651.452 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-08T14:17:31.452Z,1694182651.452 [Startup:A.GoToSurface] Stopped
2023-09-08T14:17:31.452Z,1694182651.452 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T14:17:33.822Z,1694182653.822 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T14:17:33.822Z,1694182653.822 [Default] Running Loop=1
2023-09-08T14:17:33.822Z,1694182653.822 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T14:17:33.822Z,1694182653.822 [Default:B.GoToSurface] Running Loop=1
2023-09-08T14:17:33.823Z,1694182653.823 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T14:17:33.823Z,1694182653.823 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T14:17:33.823Z,1694182653.823 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T14:17:33.823Z,1694182653.823 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T14:17:33.824Z,1694182653.824 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T14:17:33.824Z,1694182653.824 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T14:17:33.824Z,1694182653.824 [Default:A.Wait] Running Loop=1
2023-09-08T14:17:33.824Z,1694182653.824 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:17:40.340Z,1694182660.340 [NAL9602](INFO): SBD MO Status=1, MOMSN=3508, MT Status=0, MTMSN=0
2023-09-08T14:17:40.385Z,1694182660.385 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T141321/Express0001.lzma
2023-09-08T14:17:40.385Z,1694182660.385 [NAL9602](INFO): Packets left to send: 1
2023-09-08T14:17:47.160Z,1694182667.160 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T14:17:47.160Z,1694182667.160 [Default:A.Wait] Stopped
2023-09-08T14:17:47.160Z,1694182667.160 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:17:47.569Z,1694182667.569 [Default:CheckIn] Running Loop=1
2023-09-08T14:17:47.570Z,1694182667.570 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:17:47.570Z,1694182667.570 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:17:47.985Z,1694182667.985 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-08T14:17:49.592Z,1694182669.592 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141748.00,A,4145.52981,N,08116.65019,W,0.039,0.00,080923,,,D*7A
2023-09-08T14:17:49.598Z,1694182669.598 [NAL9602](INFO): GPS fix at 20230908T141748: (41.758830, -81.277503)
2023-09-08T14:17:49.613Z,1694182669.613 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:17:49.613Z,1694182669.613 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:17:50.034Z,1694182670.034 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T14:18:03.844Z,1694182683.844 [NAL9602](INFO): SBD MO Status=1, MOMSN=3509, MT Status=0, MTMSN=0
2023-09-08T14:18:03.893Z,1694182683.893 [NAL9602](INFO): Sent 72 bytes from file Logs/20230908T141321/Courier0004.lzma
2023-09-08T14:18:03.893Z,1694182683.893 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:18:24.311Z,1694182704.311 [NAL9602](INFO): SBD MO Status=1, MOMSN=3510, MT Status=0, MTMSN=0
2023-09-08T14:18:24.369Z,1694182704.369 [NAL9602](INFO): Sent 134 bytes from file Logs/20230908T141321/Express0001.lzma
2023-09-08T14:18:24.369Z,1694182704.369 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:18:38.675Z,1694182718.675 [NAL9602](INFO): SBD MO Status=2, MOMSN=3511, MT Status=2, MTMSN=0
2023-09-08T14:18:38.676Z,1694182718.676 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T14:19:06.627Z,1694182746.627 [NAL9602](INFO): SBD MO Status=1, MOMSN=3511, MT Status=0, MTMSN=0
2023-09-08T14:19:06.693Z,1694182746.693 [NAL9602](INFO): Sent 103 bytes from file Logs/20230908T141321/Express0005.lzma
2023-09-08T14:19:06.693Z,1694182746.693 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:19:14.675Z,1694182754.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=3512, MT Status=0, MTMSN=0
2023-09-08T14:19:14.759Z,1694182754.759 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:19:14.759Z,1694182754.759 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:19:14.759Z,1694182754.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:19:45.133Z,1694182785.133 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:20:59.880Z,1694182859.880 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-08T14:20:59.884Z,1694182859.884 [BPC1](INFO): Received data from all battery sticks.
2023-09-08T14:23:33.806Z,1694183013.806 [Power24vConverter](INFO): Powering down.
2023-09-08T14:24:15.424Z,1694183055.424 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:24:15.424Z,1694183055.424 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:24:15.424Z,1694183055.424 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:24:15.424Z,1694183055.424 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:24:15.829Z,1694183055.829 [Default:CheckIn:D] Stopped
2023-09-08T14:24:15.829Z,1694183055.829 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:24:16.228Z,1694183056.228 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.700101 min
2023-09-08T14:24:16.229Z,1694183056.229 [Default:CheckIn:E] Stopped
2023-09-08T14:24:16.229Z,1694183056.229 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:24:16.229Z,1694183056.229 [Default:CheckIn] Stopped
2023-09-08T14:24:16.229Z,1694183056.229 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:24:16.229Z,1694183056.229 [Default:CheckIn](INFO): Running loop #2
2023-09-08T14:24:16.229Z,1694183056.229 [Default:CheckIn] Running Loop=2
2023-09-08T14:24:16.229Z,1694183056.229 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:24:16.229Z,1694183056.229 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:24:18.240Z,1694183058.240 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142417.00,A,4145.52964,N,08116.65050,W,0.039,0.00,080923,,,D*76
2023-09-08T14:24:18.242Z,1694183058.242 [NAL9602](INFO): GPS fix at 20230908T142417: (41.758827, -81.277508)
2023-09-08T14:24:18.281Z,1694183058.281 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:24:18.281Z,1694183058.281 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:24:35.767Z,1694183075.767 [NAL9602](INFO): SBD MO Status=1, MOMSN=3513, MT Status=0, MTMSN=0
2023-09-08T14:24:35.821Z,1694183075.821 [NAL9602](INFO): Sent 201 bytes from file Logs/20230908T141321/Courier0007.lzma
2023-09-08T14:24:35.821Z,1694183075.821 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:24:50.035Z,1694183090.035 [NAL9602](INFO): SBD MO Status=1, MOMSN=3514, MT Status=0, MTMSN=0
2023-09-08T14:24:50.089Z,1694183090.089 [NAL9602](INFO): Sent 160 bytes from file Logs/20230908T141321/Express0008.lzma
2023-09-08T14:24:50.089Z,1694183090.089 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:25:00.111Z,1694183100.111 [NAL9602](INFO): SBD MO Status=0, MOMSN=3515, MT Status=0, MTMSN=0
2023-09-08T14:25:00.204Z,1694183100.204 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:25:00.204Z,1694183100.204 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:25:00.204Z,1694183100.204 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:25:30.819Z,1694183130.819 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:28:53.855Z,1694183333.855 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004484
2023-09-08T14:29:40.168Z,1694183380.168 [CommandExec](IMPORTANT): got command get distance_from_shore
2023-09-08T14:29:40.169Z,1694183380.169 [CommandExec](IMPORTANT): distance_from_shore 65.369484 m
2023-09-08T14:30:00.633Z,1694183400.633 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-08T14:30:00.633Z,1694183400.633 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-08T14:30:00.633Z,1694183400.633 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/run_backseat_on_surface.tx
2023-09-08T14:30:00.793Z,1694183400.793 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min
2023-09-08T14:30:00.794Z,1694183400.794 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2023-09-08T14:30:00.794Z,1694183400.794 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2023-09-08T14:30:01.405Z,1694183401.405 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectChlActive = 0 bool
2023-09-08T14:30:01.408Z,1694183401.408 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.TimeWindowPeakReport = nan min
2023-09-08T14:30:01.423Z,1694183401.423 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestChlPeakReportActive = 0 bool
2023-09-08T14:30:01.437Z,1694183401.437 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestSaltPeakReportActive = 0 bool
2023-09-08T14:30:01.440Z,1694183401.440 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestOilPeakReportActive = 0 bool
2023-09-08T14:30:01.442Z,1694183401.442 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PatchTracking = 0 bool
2023-09-08T14:30:01.445Z,1694183401.445 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DetectTrough = 0 bool
2023-09-08T14:30:01.448Z,1694183401.448 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.FilterWidthHorizontal = 3.000000 count
2023-09-08T14:30:01.527Z,1694183401.527 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesSlidingwindow = 100.000000 count
2023-09-08T14:30:01.530Z,1694183401.530 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionHorizontal = 80.000000 %
2023-09-08T14:30:01.532Z,1694183401.532 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectNO3Active = 0 bool
2023-09-08T14:30:01.614Z,1694183401.614 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectOilActive = 0 bool
2023-09-08T14:30:01.649Z,1694183401.649 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectFDOMActive = 0 bool
2023-09-08T14:30:01.652Z,1694183401.652 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectSalinityActive = 0 bool
2023-09-08T14:30:01.668Z,1694183401.668 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-09-08T14:30:01.680Z,1694183401.680 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledAanderaaO2 = 0 bool
2023-09-08T14:30:01.701Z,1694183401.701 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledNeilBrown = 0 bool
2023-09-08T14:30:01.721Z,1694183401.721 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledSeabird = 1 bool
2023-09-08T14:30:01.724Z,1694183401.724 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsBB2FL = 1 bool
2023-09-08T14:30:01.736Z,1694183401.736 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-09-08T14:30:01.747Z,1694183401.747 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsUBAT = 0 bool
2023-09-08T14:30:01.768Z,1694183401.768 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.LowPassWindowLength = 20.000000 count
2023-09-08T14:30:01.791Z,1694183401.791 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.MedianFilterLen = 5.000000 count
2023-09-08T14:30:01.794Z,1694183401.794 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakShallowBound = nan m
2023-09-08T14:30:01.817Z,1694183401.817 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDeepBound = nan m
2023-09-08T14:30:01.820Z,1694183401.820 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-09-08T14:30:01.822Z,1694183401.822 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlThreshTriggerActive = 0 bool
2023-09-08T14:30:01.834Z,1694183401.834 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlLowerThresh = nan ug/l
2023-09-08T14:30:01.836Z,1694183401.836 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlUpperThresh = nan ug/l
2023-09-08T14:30:01.839Z,1694183401.839 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesThresh = 5.000000 count
2023-09-08T14:30:01.866Z,1694183401.866 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChl = nan ug/l
2023-09-08T14:30:01.869Z,1694183401.869 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlDepth = nan m
2023-09-08T14:30:01.872Z,1694183401.872 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlTemperature = nan degC
2023-09-08T14:30:01.923Z,1694183401.923 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLatitude = nan arcdeg
2023-09-08T14:30:01.930Z,1694183401.930 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLongitude = nan arcdeg
2023-09-08T14:30:01.961Z,1694183401.961 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3 = nan umol/l
2023-09-08T14:30:01.964Z,1694183401.964 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Depth = nan m
2023-09-08T14:30:02.005Z,1694183402.005 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Latitude = nan arcdeg
2023-09-08T14:30:02.008Z,1694183402.008 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Longitude = nan arcdeg
2023-09-08T14:30:02.019Z,1694183402.019 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOil = nan kg/m3
2023-09-08T14:30:02.027Z,1694183402.027 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilDepth = nan m
2023-09-08T14:30:02.042Z,1694183402.042 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLatitude = nan arcdeg
2023-09-08T14:30:02.051Z,1694183402.051 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLongitude = nan arcdeg
2023-09-08T14:30:02.074Z,1694183402.074 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOM = nan ppb
2023-09-08T14:30:02.092Z,1694183402.092 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMDepth = nan m
2023-09-08T14:30:02.095Z,1694183402.095 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMTemperature = nan degC
2023-09-08T14:30:02.126Z,1694183402.126 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLatitude = nan arcdeg
2023-09-08T14:30:02.129Z,1694183402.129 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLongitude = nan arcdeg
2023-09-08T14:30:02.132Z,1694183402.132 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchFDOMDistance = nan m
2023-09-08T14:30:02.164Z,1694183402.164 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSalt = nan psu
2023-09-08T14:30:02.167Z,1694183402.167 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltDepth = nan m
2023-09-08T14:30:02.179Z,1694183402.179 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltTemperature = nan degC
2023-09-08T14:30:02.182Z,1694183402.182 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLatitude = nan arcdeg
2023-09-08T14:30:02.213Z,1694183402.213 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLongitude = nan arcdeg
2023-09-08T14:30:02.216Z,1694183402.216 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChl = nan ug/l
2023-09-08T14:30:02.219Z,1694183402.219 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDepth = nan m
2023-09-08T14:30:02.243Z,1694183402.243 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLatitude = nan arcdeg
2023-09-08T14:30:02.246Z,1694183402.246 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLongitude = nan arcdeg
2023-09-08T14:30:02.282Z,1694183402.282 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDistance = nan m
2023-09-08T14:30:02.309Z,1694183402.309 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSalt = nan psu
2023-09-08T14:30:02.312Z,1694183402.312 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDepth = nan m
2023-09-08T14:30:02.327Z,1694183402.327 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLatitude = nan arcdeg
2023-09-08T14:30:02.347Z,1694183402.347 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLongitude = nan arcdeg
2023-09-08T14:30:02.362Z,1694183402.362 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDistance = nan m
2023-09-08T14:30:02.381Z,1694183402.381 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOil = nan psu
2023-09-08T14:30:02.407Z,1694183402.407 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDepth = nan m
2023-09-08T14:30:02.420Z,1694183402.420 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLatitude = nan arcdeg
2023-09-08T14:30:02.431Z,1694183402.431 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLongitude = nan arcdeg
2023-09-08T14:30:02.447Z,1694183402.447 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDistance = nan m
2023-09-08T14:30:02.466Z,1694183402.466 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.TriggeredOnChl = 0 bool
2023-09-08T14:30:02.474Z,1694183402.474 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.ChlTriggering = nan ug/l
2023-09-08T14:30:02.598Z,1694183402.598 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T14:30:02.663Z,1694183402.663 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-08T14:30:02.724Z,1694183402.724 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T14:30:02.822Z,1694183402.822 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T14:30:02.874Z,1694183402.874 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-08T14:30:02.911Z,1694183402.911 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T14:30:02.965Z,1694183402.965 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-08T14:30:02.997Z,1694183402.997 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-08T14:30:03.049Z,1694183403.049 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-09-08T14:30:03.056Z,1694183403.056 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-08T14:30:03.056Z,1694183403.056 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-08T14:30:03.135Z,1694183403.135 [MissionManager](INFO): DefineArg run_backseat_on_surface:BackseatDriver.EnableBackseat = 0 bool
2023-09-08T14:30:03.150Z,1694183403.150 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-08T14:30:03.168Z,1694183403.168 [run_backseat_on_surface:D.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T14:30:03.228Z,1694183403.228 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-08T14:30:03.238Z,1694183403.238 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-08T14:30:03.252Z,1694183403.252 [run_backseat_on_surface:G.Execute](DEBUG): Construct Execute.
2023-09-08T14:30:03.339Z,1694183403.339 [MissionManager](DEBUG):
mission run_backseat_on_surface {
arguments {
ElapsedSinceMissionStarted = 0 minute
}
# Power the science payload.
insert id="Science" Insert/Science.tl
# Power the Backseat payload.
insert Insert/BackseatDriver.tl
assign in sequence BackseatDriver:EnableBackseat = true
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P2H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceMissionStarted = elapsed ( Universal:mission_started )
syslog important "run_backseat_on_surface mission has been running for "
+ ElapsedSinceMissionStarted~minute
}
syslog important "Restarting logs and run_backseat_on_surface mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2023-09-08T14:30:03.367Z,1694183403.367 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-08T14:30:03.550Z,1694183403.550 [Default] Stopped
2023-09-08T14:30:03.550Z,1694183403.550 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T14:30:03.550Z,1694183403.550 [Default:B.GoToSurface] Stopped
2023-09-08T14:30:03.550Z,1694183403.550 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T14:30:03.550Z,1694183403.550 [Default:CheckIn] Stopped
2023-09-08T14:30:03.550Z,1694183403.550 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:30:03.550Z,1694183403.550 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:30:03.550Z,1694183403.550 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:30:03.550Z,1694183403.550 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface] Running Loop=1
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science] Running Loop=1
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science](DEBUG): Aggregate::initialize run_backseat_on_surface:Science
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science:A] Running Loop=1
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science:B] Running Loop=1
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science:C] Running Loop=1
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science:D] Running Loop=1
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science:E] Running Loop=1
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science:F] Running Loop=1
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science:Read_Oil] Running Loop=1
2023-09-08T14:30:03.551Z,1694183403.551 [run_backseat_on_surface:Science:PeakDetectChl] Running Loop=1
2023-09-08T14:30:03.552Z,1694183403.552 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectChl
2023-09-08T14:30:03.552Z,1694183403.552 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T14:30:03.552Z,1694183403.552 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T14:30:03.558Z,1694183403.558 [run_backseat_on_surface:Science:HighestChlPeakReport] Running Loop=1
2023-09-08T14:30:03.558Z,1694183403.558 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-09-08T14:30:03.558Z,1694183403.558 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-08T14:30:03.558Z,1694183403.558 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-08T14:30:03.559Z,1694183403.559 [run_backseat_on_surface:Science:PeakDetectNO3] Running Loop=1
2023-09-08T14:30:03.559Z,1694183403.559 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectNO3
2023-09-08T14:30:03.559Z,1694183403.559 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T14:30:03.559Z,1694183403.559 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T14:30:03.585Z,1694183403.585 [run_backseat_on_surface:Science:PeakDetectOil] Running Loop=1
2023-09-08T14:30:03.585Z,1694183403.585 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectOil
2023-09-08T14:30:03.585Z,1694183403.585 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T14:30:03.585Z,1694183403.585 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T14:30:03.587Z,1694183403.587 [run_backseat_on_surface:Science:HighestOilPeakReport] Running Loop=1
2023-09-08T14:30:03.587Z,1694183403.587 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-09-08T14:30:03.587Z,1694183403.587 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-08T14:30:03.587Z,1694183403.587 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-08T14:30:03.588Z,1694183403.588 [run_backseat_on_surface:Science:PeakDetectFDOM] Running Loop=1
2023-09-08T14:30:03.588Z,1694183403.588 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-09-08T14:30:03.588Z,1694183403.588 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T14:30:03.588Z,1694183403.588 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T14:30:03.610Z,1694183403.610 [run_backseat_on_surface:Science:PeakDetectSalinity] Running Loop=1
2023-09-08T14:30:03.610Z,1694183403.610 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-09-08T14:30:03.610Z,1694183403.610 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-09-08T14:30:03.610Z,1694183403.610 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-08T14:30:03.612Z,1694183403.612 [run_backseat_on_surface:Science:HighestSaltPeakReport] Running Loop=1
2023-09-08T14:30:03.612Z,1694183403.612 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-09-08T14:30:03.612Z,1694183403.612 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-08T14:30:03.612Z,1694183403.612 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-08T14:30:03.621Z,1694183403.621 [run_backseat_on_surface:Science:ChlThreshTrigger] Running Loop=1
2023-09-08T14:30:03.621Z,1694183403.621 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:ChlThreshTrigger
2023-09-08T14:30:03.621Z,1694183403.621 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-09-08T14:30:03.621Z,1694183403.621 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-09-08T14:30:03.622Z,1694183403.622 [run_backseat_on_surface:Science:OceanCurrent] Running Loop=1
2023-09-08T14:30:03.622Z,1694183403.622 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:OceanCurrent
2023-09-08T14:30:03.622Z,1694183403.622 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-09-08T14:30:03.622Z,1694183403.622 [run_backseat_on_surface:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-09-08T14:30:03.622Z,1694183403.622 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2023-09-08T14:30:03.622Z,1694183403.622 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::initialize run_backseat_on_surface:BackseatDriver
2023-09-08T14:30:03.622Z,1694183403.622 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-08T14:30:03.622Z,1694183403.622 [run_backseat_on_surface:D.GoToSurface] Running Loop=1
2023-09-08T14:30:03.622Z,1694183403.622 [run_backseat_on_surface:D.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T14:30:03.623Z,1694183403.623 [run_backseat_on_surface:D.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T14:30:03.623Z,1694183403.623 [run_backseat_on_surface:D.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T14:30:03.623Z,1694183403.623 [run_backseat_on_surface:D.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T14:30:03.624Z,1694183403.624 [run_backseat_on_surface:D.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T14:30:03.624Z,1694183403.624 [run_backseat_on_surface:D.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T14:30:03.624Z,1694183403.624 [run_backseat_on_surface:C] Running Loop=1
2023-09-08T14:30:03.638Z,1694183403.638 [run_backseat_on_surface:C] Stopped
2023-09-08T14:30:03.638Z,1694183403.638 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2023-09-08T14:30:03.638Z,1694183403.638 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2023-09-08T14:30:03.712Z,1694183403.712 [BackseatComponent](INFO): Powering up
2023-09-08T14:30:03.713Z,1694183403.713 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-09-08T14:30:03.774Z,1694183403.774 [run_backseat_on_surface:Science] Running Loop=1
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:ChlThreshTrigger] Stopped
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:ChlThreshTrigger
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:HighestSaltPeakReport] Stopped
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:PeakDetectSalinity] Stopped
2023-09-08T14:30:03.775Z,1694183403.775 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:PeakDetectFDOM] Stopped
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:HighestOilPeakReport] Stopped
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-08T14:30:03.776Z,1694183403.776 [run_backseat_on_surface:Science:PeakDetectOil] Stopped
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectOil
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:PeakDetectNO3] Stopped
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectNO3
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:HighestChlPeakReport] Stopped
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:PeakDetectChl] Stopped
2023-09-08T14:30:03.777Z,1694183403.777 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectChl
2023-09-08T14:30:03.778Z,1694183403.778 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-09-08T14:30:03.778Z,1694183403.778 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-08T14:30:03.778Z,1694183403.778 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-09-08T14:30:03.778Z,1694183403.778 [run_backseat_on_surface:Science:Read_Oil] Stopped
2023-09-08T14:30:03.778Z,1694183403.778 [run_backseat_on_surface:Science:F] Stopped
2023-09-08T14:30:03.778Z,1694183403.778 [run_backseat_on_surface:Science:E] Running Loop=1
2023-09-08T14:30:03.779Z,1694183403.779 [run_backseat_on_surface:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-09-08T14:30:03.779Z,1694183403.779 [run_backseat_on_surface:Science:D] Stopped
2023-09-08T14:30:03.779Z,1694183403.779 [run_backseat_on_surface:Science:C] Running Loop=1
2023-09-08T14:30:03.780Z,1694183403.780 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-09-08T14:30:03.780Z,1694183403.780 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-09-08T14:30:03.780Z,1694183403.780 [run_backseat_on_surface:Science:B] Running Loop=1
2023-09-08T14:30:03.797Z,1694183403.797 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-09-08T14:30:03.798Z,1694183403.798 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-09-08T14:30:03.798Z,1694183403.798 [run_backseat_on_surface:Science:A] Stopped
2023-09-08T14:30:03.926Z,1694183403.926 [Power24vConverter](INFO): Powering up.
2023-09-08T14:30:03.990Z,1694183403.990 [run_backseat_on_surface:CheckIn] Running Loop=1
2023-09-08T14:30:03.990Z,1694183403.990 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2023-09-08T14:30:03.990Z,1694183403.990 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:30:04.019Z,1694183404.019 [WetLabsBB2FL](INFO): Powering up
2023-09-08T14:30:04.361Z,1694183404.361 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-08T14:30:05.940Z,1694183405.940 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143005.00,A,4145.52951,N,08116.65057,W,0.019,0.00,080923,,,D*73
2023-09-08T14:30:05.942Z,1694183405.942 [NAL9602](INFO): GPS fix at 20230908T143005: (41.758825, -81.277509)
2023-09-08T14:30:05.994Z,1694183405.994 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2023-09-08T14:30:05.995Z,1694183405.995 [run_backseat_on_surface:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:30:06.369Z,1694183406.369 [run_backseat_on_surface:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T14:30:07.570Z,1694183407.570 [CTD_Seabird](ERROR): Failed to parse device response:
2023-09-08T14:30:08.968Z,1694183408.968 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0010.lzma
2023-09-08T14:30:09.975Z,1694183409.975 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0010.lzma.bak
2023-09-08T14:30:09.975Z,1694183409.975 [DataOverHttps](INFO): SBD MOMSN=18750288
2023-09-08T14:30:31.477Z,1694183431.477 [DataOverHttps](INFO): Sending 325 bytes from file Logs/20230908T141321/Express0011.lzma
2023-09-08T14:30:32.479Z,1694183432.479 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0011.lzma.bak
2023-09-08T14:30:32.479Z,1694183432.479 [DataOverHttps](INFO): SBD MOMSN=18750292
2023-09-08T14:30:39.589Z,1694183439.589 [run_backseat_on_surface:CheckIn:Read_Iridium] Stopped
2023-09-08T14:30:39.589Z,1694183439.589 [run_backseat_on_surface:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:30:39.589Z,1694183439.589 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:30:56.237Z,1694183456.237 [NAL9602](INFO): SBD MO Status=0, MOMSN=3516, MT Status=0, MTMSN=0
2023-09-08T14:30:56.237Z,1694183456.237 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:31:10.571Z,1694183470.571 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20230908T142944
2023-09-08T14:31:13.276Z,1694183473.276 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2023-09-08T14:31:13.277Z,1694183473.277 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2023-09-08T14:31:13.282Z,1694183473.282 [BackseatComponent](INFO): Created universal data reader for latitude with code: 23
2023-09-08T14:31:13.282Z,1694183473.282 [BackseatComponent](INFO): Requesting data from latitude (23). Requested size is: 2
2023-09-08T14:31:13.286Z,1694183473.286 [BackseatComponent](INFO): Created universal data reader for longitude with code: 26
2023-09-08T14:31:13.287Z,1694183473.287 [BackseatComponent](INFO): Requesting data from longitude (26). Requested size is: 3
2023-09-08T14:31:13.290Z,1694183473.290 [BackseatComponent](INFO): Created universal data reader for platform_orientation with code: 52
2023-09-08T14:31:13.291Z,1694183473.291 [BackseatComponent](INFO): Requesting data from platform_orientation (52). Requested size is: 4
2023-09-08T14:31:13.294Z,1694183473.294 [BackseatComponent](INFO): Created universal data reader for platform_pitch_angle with code: 54
2023-09-08T14:31:13.295Z,1694183473.295 [BackseatComponent](INFO): Requesting data from platform_pitch_angle (54). Requested size is: 5
2023-09-08T14:31:13.299Z,1694183473.299 [BackseatComponent](INFO): Created universal data reader for platform_roll_angle with code: 59
2023-09-08T14:31:13.299Z,1694183473.299 [BackseatComponent](INFO): Requesting data from platform_roll_angle (59). Requested size is: 6
2023-09-08T14:31:13.308Z,1694183473.308 [BackseatComponent](INFO): Created universal data reader for platform_x_velocity_wrt_sea_water with code: 70
2023-09-08T14:31:13.308Z,1694183473.308 [BackseatComponent](INFO): Requesting data from platform_x_velocity_wrt_sea_water (70). Requested size is: 7
2023-09-08T14:31:26.956Z,1694183486.956 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:31:54.448Z,1694183514.448 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1920
2023-09-08T14:32:29.518Z,1694183549.518 [CommandExec](IMPORTANT): got command stop
2023-09-08T14:32:29.518Z,1694183549.518 [CommandExec](IMPORTANT): Scheduling is paused
2023-09-08T14:32:29.518Z,1694183549.518 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-09-08T14:32:29.682Z,1694183549.682 [MissionManager](INFO): MissionManager is completed.
2023-09-08T14:32:29.682Z,1694183549.682 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface
2023-09-08T14:32:29.682Z,1694183549.682 [run_backseat_on_surface] Stopped
2023-09-08T14:32:29.682Z,1694183549.682 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface
2023-09-08T14:32:29.682Z,1694183549.682 [run_backseat_on_surface:Science] Stopped
2023-09-08T14:32:29.682Z,1694183549.682 [run_backseat_on_surface:Science](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science
2023-09-08T14:32:29.682Z,1694183549.682 [run_backseat_on_surface:Science:B] Stopped
2023-09-08T14:32:29.682Z,1694183549.682 [run_backseat_on_surface:Science:C] Stopped
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:Science:E] Stopped
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:Science:OceanCurrent] Stopped
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:OceanCurrent
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:Science:OceanCurrent:A.] Stopped
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:BackseatDriver] Stopped
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::uninitialize run_backseat_on_surface:BackseatDriver
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Stopped
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:D.GoToSurface] Stopped
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:D.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:CheckIn] Stopped
2023-09-08T14:32:29.683Z,1694183549.683 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2023-09-08T14:32:29.684Z,1694183549.684 [run_backseat_on_surface:CheckIn:C.Wait] Stopped
2023-09-08T14:32:29.684Z,1694183549.684 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:32:29.838Z,1694183549.838 [WetLabsBB2FL](INFO): Powering down
2023-09-08T14:32:29.983Z,1694183549.983 [BioacousticsDataBridge](INFO): No valid data from altitude reader, stopping
2023-09-08T14:32:30.032Z,1694183550.032 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T14:32:30.032Z,1694183550.032 [Default] Running Loop=1
2023-09-08T14:32:30.032Z,1694183550.032 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T14:32:30.032Z,1694183550.032 [Default:B.GoToSurface] Running Loop=1
2023-09-08T14:32:30.032Z,1694183550.032 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T14:32:30.037Z,1694183550.037 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T14:32:30.037Z,1694183550.037 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T14:32:30.038Z,1694183550.038 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T14:32:30.038Z,1694183550.038 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T14:32:30.038Z,1694183550.038 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T14:32:30.039Z,1694183550.039 [Default:A.Wait] Running Loop=1
2023-09-08T14:32:30.039Z,1694183550.039 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:32:31.400Z,1694183551.400 [BackseatComponent](INFO): Sent LCM shutdown request.
2023-09-08T14:32:31.401Z,1694183551.401 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2023-09-08T14:32:35.725Z,1694183555.725 [CommandExec](IMPORTANT): got command failComponent
2023-09-08T14:32:35.726Z,1694183555.726 [CommandExec](IMPORTANT): Failed components:
2023-09-08T14:32:35.726Z,1694183555.726 [CommandExec](IMPORTANT): No failed Components.
2023-09-08T14:32:43.346Z,1694183563.346 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T14:32:43.346Z,1694183563.346 [Default:A.Wait] Stopped
2023-09-08T14:32:43.346Z,1694183563.346 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:32:43.755Z,1694183563.755 [Default:CheckIn] Running Loop=1
2023-09-08T14:32:43.755Z,1694183563.755 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:32:43.755Z,1694183563.755 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:32:43.801Z,1694183563.801 [CommandExec](IMPORTANT): got command ibit
2023-09-08T14:32:44.152Z,1694183564.152 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-09-08T14:32:44.152Z,1694183564.152 [IBIT](IMPORTANT): Beginning control surface checks.
2023-09-08T14:32:44.155Z,1694183564.155 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-08T14:32:45.770Z,1694183565.770 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143245.00,A,4145.52966,N,08116.65137,W,0.097,0.00,080923,,,D*70
2023-09-08T14:32:45.773Z,1694183565.773 [NAL9602](INFO): GPS fix at 20230908T143245: (41.758828, -81.277523)
2023-09-08T14:32:49.425Z,1694183569.425 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2023-09-08T14:32:49.426Z,1694183569.426 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2023-09-08T14:32:49.426Z,1694183569.426 [LcmUniversalReporter](INFO): Deactivating messaging.
2023-09-08T14:32:55.117Z,1694183575.117 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007062
CHAN A1 (24V): -0.007130
CHAN A2 (12V): -0.004022
CHAN A3 (5V): -0.002773
CHAN B0 (3.3V): -0.000593
CHAN B1 (3.15aV): -0.001117
CHAN B2 (3.15bV): -0.000985
CHAN B3 (GND): -0.000345
OPEN: 0.003687
Full Scale: +/- 1 mA
2023-09-08T14:33:16.055Z,1694183596.055 [NAL9602](INFO): SBD MO Status=0, MOMSN=3517, MT Status=0, MTMSN=0
2023-09-08T14:33:16.055Z,1694183596.055 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:33:17.268Z,1694183597.268 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143316.00,A,4145.52926,N,08116.65050,W,0.097,0.00,080923,,,D*73
2023-09-08T14:33:17.279Z,1694183597.279 [NAL9602](INFO): GPS fix at 20230908T143316: (41.758821, -81.277508)
2023-09-08T14:33:19.692Z,1694183599.692 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143319.00,A,4145.52902,N,08116.65049,W,0.019,0.00,080923,,,D*74
2023-09-08T14:33:19.694Z,1694183599.694 [NAL9602](INFO): GPS fix at 20230908T143319: (41.758817, -81.277508)
2023-09-08T14:33:22.920Z,1694183602.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143322.00,A,4145.52899,N,08116.65046,W,0.019,0.00,080923,,,D*70
2023-09-08T14:33:22.922Z,1694183602.922 [NAL9602](INFO): GPS fix at 20230908T143322: (41.758817, -81.277508)
2023-09-08T14:33:25.752Z,1694183605.752 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143325.00,A,4145.52898,N,08116.65047,W,0.039,0.00,080923,,,D*75
2023-09-08T14:33:25.754Z,1694183605.754 [NAL9602](INFO): GPS fix at 20230908T143325: (41.758816, -81.277508)
2023-09-08T14:33:28.984Z,1694183608.984 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143328.00,A,4145.52894,N,08116.65046,W,0.019,0.00,080923,,,D*77
2023-09-08T14:33:28.986Z,1694183608.986 [NAL9602](INFO): GPS fix at 20230908T143328: (41.758816, -81.277508)
2023-09-08T14:33:30.644Z,1694183610.644 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 41.758816 Longitude: -81.277504
2023-09-08T14:33:31.023Z,1694183611.023 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2023-09-08T14:33:31.024Z,1694183611.024 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-09-08T14:33:31.024Z,1694183611.024 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-09-08T14:33:31.451Z,1694183611.451 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-09-08T14:33:31.452Z,1694183611.452 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-09-08T14:33:31.452Z,1694183611.452 [IBIT](IMPORTANT): Pressure:10.193256 PSI
2023-09-08T14:33:31.452Z,1694183611.452 [IBIT](IMPORTANT): Humidity:5.151996 %
2023-09-08T14:33:31.809Z,1694183611.809 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143331.00,A,4145.52883,N,08116.65037,W,0.039,0.00,080923,,,D*7D
2023-09-08T14:33:31.811Z,1694183611.811 [NAL9602](INFO): GPS fix at 20230908T143331: (41.758814, -81.277506)
2023-09-08T14:33:31.829Z,1694183611.829 [IBIT](IMPORTANT): Vehicle Pitch:-1.720315 degrees
2023-09-08T14:33:31.829Z,1694183611.829 [IBIT](IMPORTANT): Vehicle Roll:-2.710618 degrees
2023-09-08T14:33:31.829Z,1694183611.829 [IBIT](IMPORTANT): Vehicle Heading:299.131989 degrees
2023-09-08T14:33:32.255Z,1694183612.255 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-09-08T14:33:32.255Z,1694183612.255 [IBIT](IMPORTANT): buoyancyNeutral: 391.543030 cc
2023-09-08T14:33:32.255Z,1694183612.255 [IBIT](IMPORTANT): massDefault: 2.572494 cm
2023-09-08T14:33:32.255Z,1694183612.255 [IBIT](IMPORTANT): stopDepth: 100.000000 m
2023-09-08T14:33:32.256Z,1694183612.256 [IBIT](IMPORTANT): abortDepth: 120.000000 m
2023-09-08T14:33:32.256Z,1694183612.256 [IBIT](IMPORTANT): IBIT PASSED
2023-09-08T14:33:32.629Z,1694183612.629 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:33:32.630Z,1694183612.630 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:33:41.272Z,1694183621.272 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0013.lzma
2023-09-08T14:33:42.274Z,1694183622.274 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0013.lzma.bak
2023-09-08T14:33:42.275Z,1694183622.275 [DataOverHttps](INFO): SBD MOMSN=18750307
2023-09-08T14:34:00.830Z,1694183640.830 [DataOverHttps](INFO): Sending 1243 bytes from file Logs/20230908T141321/Express0014.lzma
2023-09-08T14:34:03.024Z,1694183643.024 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0014.lzma.bak
2023-09-08T14:34:03.025Z,1694183643.025 [DataOverHttps](INFO): SBD MOMSN=18750313
2023-09-08T14:34:04.534Z,1694183644.534 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:34:06.574Z,1694183646.574 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:34:06.574Z,1694183646.574 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:34:06.574Z,1694183646.574 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:39:07.280Z,1694183947.280 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:39:07.280Z,1694183947.280 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:39:07.280Z,1694183947.280 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:39:07.280Z,1694183947.280 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:39:07.689Z,1694183947.689 [Default:CheckIn:D] Stopped
2023-09-08T14:39:07.690Z,1694183947.690 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:39:08.084Z,1694183948.084 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.627618 min
2023-09-08T14:39:08.084Z,1694183948.084 [Default:CheckIn:E] Stopped
2023-09-08T14:39:08.084Z,1694183948.084 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:39:08.084Z,1694183948.084 [Default:CheckIn] Stopped
2023-09-08T14:39:08.085Z,1694183948.085 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:39:08.085Z,1694183948.085 [Default:CheckIn](INFO): Running loop #2
2023-09-08T14:39:08.085Z,1694183948.085 [Default:CheckIn] Running Loop=2
2023-09-08T14:39:08.085Z,1694183948.085 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:39:08.085Z,1694183948.085 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:39:10.096Z,1694183950.096 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143909.00,A,4145.52917,N,08116.65016,W,0.058,0.00,080923,,,D*74
2023-09-08T14:39:10.098Z,1694183950.098 [NAL9602](INFO): GPS fix at 20230908T143909: (41.758820, -81.277503)
2023-09-08T14:39:10.109Z,1694183950.109 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:39:10.109Z,1694183950.109 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:39:18.585Z,1694183958.585 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T141321/Courier0016.lzma
2023-09-08T14:39:19.587Z,1694183959.587 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0016.lzma.bak
2023-09-08T14:39:19.587Z,1694183959.587 [DataOverHttps](INFO): SBD MOMSN=18750355
2023-09-08T14:39:32.719Z,1694183972.719 [NAL9602](INFO): SBD MO Status=2, MOMSN=3518, MT Status=2, MTMSN=0
2023-09-08T14:39:32.719Z,1694183972.719 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T14:39:38.013Z,1694183978.013 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20230908T141321/Express0017.lzma
2023-09-08T14:39:38.999Z,1694183978.999 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0017.lzma.bak
2023-09-08T14:39:38.999Z,1694183978.999 [DataOverHttps](INFO): SBD MOMSN=18750362
2023-09-08T14:39:44.892Z,1694183984.892 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:39:44.893Z,1694183984.893 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:39:44.893Z,1694183984.893 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:40:15.948Z,1694184015.948 [NAL9602](INFO): SBD MO Status=2, MOMSN=3518, MT Status=2, MTMSN=0
2023-09-08T14:40:15.948Z,1694184015.948 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T14:40:34.560Z,1694184034.560 [NAL9602](INFO): SBD MO Status=0, MOMSN=3518, MT Status=0, MTMSN=0
2023-09-08T14:40:34.560Z,1694184034.560 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:41:05.302Z,1694184065.302 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:42:50.355Z,1694184170.355 [Power24vConverter](INFO): Powering down.
2023-09-08T14:44:45.582Z,1694184285.582 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:44:45.582Z,1694184285.582 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:44:45.582Z,1694184285.582 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:44:45.583Z,1694184285.583 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:44:45.949Z,1694184285.949 [Default:CheckIn:D] Stopped
2023-09-08T14:44:45.949Z,1694184285.949 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:44:46.340Z,1694184286.340 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.265285 min
2023-09-08T14:44:46.340Z,1694184286.340 [Default:CheckIn:E] Stopped
2023-09-08T14:44:46.365Z,1694184286.365 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:44:46.365Z,1694184286.365 [Default:CheckIn] Stopped
2023-09-08T14:44:46.365Z,1694184286.365 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:44:46.365Z,1694184286.365 [Default:CheckIn](INFO): Running loop #3
2023-09-08T14:44:46.365Z,1694184286.365 [Default:CheckIn] Running Loop=3
2023-09-08T14:44:46.365Z,1694184286.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:44:46.365Z,1694184286.365 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:44:48.348Z,1694184288.348 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144447.00,A,4145.53000,N,08116.65083,W,0.039,0.00,080923,,,D*71
2023-09-08T14:44:48.350Z,1694184288.350 [NAL9602](INFO): GPS fix at 20230908T144447: (41.758833, -81.277514)
2023-09-08T14:44:48.362Z,1694184288.362 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:44:48.362Z,1694184288.362 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:44:56.956Z,1694184296.956 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0019.lzma
2023-09-08T14:44:57.958Z,1694184297.958 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0019.lzma.bak
2023-09-08T14:44:57.959Z,1694184297.959 [DataOverHttps](INFO): SBD MOMSN=18750376
2023-09-08T14:45:17.435Z,1694184317.435 [NAL9602](INFO): SBD MO Status=0, MOMSN=3519, MT Status=0, MTMSN=0
2023-09-08T14:45:17.436Z,1694184317.436 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:45:17.764Z,1694184317.764 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0020.lzma
2023-09-08T14:45:18.767Z,1694184318.767 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0020.lzma.bak
2023-09-08T14:45:18.767Z,1694184318.767 [DataOverHttps](INFO): SBD MOMSN=18750386
2023-09-08T14:45:22.700Z,1694184322.700 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:45:22.700Z,1694184322.700 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:45:22.700Z,1694184322.700 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:45:48.133Z,1694184348.133 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:50:23.341Z,1694184623.341 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:50:23.341Z,1694184623.341 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:50:23.341Z,1694184623.341 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:50:23.341Z,1694184623.341 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:50:23.741Z,1694184623.741 [Default:CheckIn:D] Stopped
2023-09-08T14:50:23.741Z,1694184623.741 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:50:24.149Z,1694184624.149 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.895140 min
2023-09-08T14:50:24.149Z,1694184624.149 [Default:CheckIn:E] Stopped
2023-09-08T14:50:24.149Z,1694184624.149 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:50:24.149Z,1694184624.149 [Default:CheckIn] Stopped
2023-09-08T14:50:24.149Z,1694184624.149 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:50:24.150Z,1694184624.150 [Default:CheckIn](INFO): Running loop #4
2023-09-08T14:50:24.150Z,1694184624.150 [Default:CheckIn] Running Loop=4
2023-09-08T14:50:24.150Z,1694184624.150 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:50:24.150Z,1694184624.150 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:50:26.152Z,1694184626.152 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145025.00,A,4145.52892,N,08116.65020,W,0.019,0.00,080923,,,D*79
2023-09-08T14:50:26.154Z,1694184626.154 [NAL9602](INFO): GPS fix at 20230908T145025: (41.758815, -81.277503)
2023-09-08T14:50:26.192Z,1694184626.192 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:50:26.192Z,1694184626.192 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:50:36.316Z,1694184636.316 [NAL9602](INFO): SBD MO Status=0, MOMSN=3520, MT Status=0, MTMSN=0
2023-09-08T14:50:36.316Z,1694184636.316 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:50:39.648Z,1694184639.648 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0022.lzma
2023-09-08T14:50:40.651Z,1694184640.651 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0022.lzma.bak
2023-09-08T14:50:40.651Z,1694184640.651 [DataOverHttps](INFO): SBD MOMSN=18750397
2023-09-08T14:51:05.416Z,1694184665.416 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0023.lzma
2023-09-08T14:51:06.418Z,1694184666.418 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0023.lzma.bak
2023-09-08T14:51:06.419Z,1694184666.419 [DataOverHttps](INFO): SBD MOMSN=18750400
2023-09-08T14:51:07.114Z,1694184667.114 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:51:10.361Z,1694184670.361 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:51:10.361Z,1694184670.361 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:51:10.361Z,1694184670.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:56:11.008Z,1694184971.008 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:56:11.008Z,1694184971.008 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:56:11.008Z,1694184971.008 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:56:11.008Z,1694184971.008 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:56:11.416Z,1694184971.416 [Default:CheckIn:D] Stopped
2023-09-08T14:56:11.416Z,1694184971.416 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:56:11.825Z,1694184971.825 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.689726 min
2023-09-08T14:56:11.825Z,1694184971.825 [Default:CheckIn:E] Stopped
2023-09-08T14:56:11.825Z,1694184971.825 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:56:11.825Z,1694184971.825 [Default:CheckIn] Stopped
2023-09-08T14:56:11.825Z,1694184971.825 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:56:11.826Z,1694184971.826 [Default:CheckIn](INFO): Running loop #5
2023-09-08T14:56:11.826Z,1694184971.826 [Default:CheckIn] Running Loop=5
2023-09-08T14:56:11.826Z,1694184971.826 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:56:11.826Z,1694184971.826 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:56:13.828Z,1694184973.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145613.00,A,4145.52895,N,08116.65040,W,0.000,0.00,080923,,,D*73
2023-09-08T14:56:13.830Z,1694184973.830 [NAL9602](INFO): GPS fix at 20230908T145613: (41.758816, -81.277507)
2023-09-08T14:56:13.841Z,1694184973.841 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:56:13.841Z,1694184973.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:56:22.752Z,1694184982.752 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0025.lzma
2023-09-08T14:56:23.754Z,1694184983.754 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0025.lzma.bak
2023-09-08T14:56:23.755Z,1694184983.755 [DataOverHttps](INFO): SBD MOMSN=18750416
2023-09-08T14:56:37.752Z,1694184997.752 [NAL9602](INFO): SBD MO Status=0, MOMSN=3521, MT Status=0, MTMSN=0
2023-09-08T14:56:37.752Z,1694184997.752 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:56:51.800Z,1694185011.800 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T141321/Express0026.lzma
2023-09-08T14:56:52.802Z,1694185012.802 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0026.lzma.bak
2023-09-08T14:56:52.803Z,1694185012.803 [DataOverHttps](INFO): SBD MOMSN=18750419
2023-09-08T14:56:56.865Z,1694185016.865 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:56:56.865Z,1694185016.865 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:56:56.865Z,1694185016.865 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:57:08.519Z,1694185028.519 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:01:57.472Z,1694185317.472 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:01:57.472Z,1694185317.472 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:01:57.472Z,1694185317.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:01:57.473Z,1694185317.473 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:01:57.880Z,1694185317.880 [Default:CheckIn:D] Stopped
2023-09-08T15:01:57.880Z,1694185317.880 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:01:58.273Z,1694185318.273 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.464128 min
2023-09-08T15:01:58.273Z,1694185318.273 [Default:CheckIn:E] Stopped
2023-09-08T15:01:58.273Z,1694185318.273 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:01:58.274Z,1694185318.274 [Default:CheckIn] Stopped
2023-09-08T15:01:58.274Z,1694185318.274 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:01:58.274Z,1694185318.274 [Default:CheckIn](INFO): Running loop #6
2023-09-08T15:01:58.274Z,1694185318.274 [Default:CheckIn] Running Loop=6
2023-09-08T15:01:58.274Z,1694185318.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:01:58.274Z,1694185318.274 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:02:00.284Z,1694185320.284 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150159.00,A,4145.53003,N,08116.65176,W,0.019,313.80,080923,,,D*7D
2023-09-08T15:02:00.287Z,1694185320.287 [NAL9602](INFO): GPS fix at 20230908T150159: (41.758834, -81.277529)
2023-09-08T15:02:00.298Z,1694185320.298 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:02:00.298Z,1694185320.298 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:02:09.080Z,1694185329.080 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0028.lzma
2023-09-08T15:02:10.083Z,1694185330.083 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0028.lzma.bak
2023-09-08T15:02:10.083Z,1694185330.083 [DataOverHttps](INFO): SBD MOMSN=18750430
2023-09-08T15:02:28.484Z,1694185348.484 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0029.lzma
2023-09-08T15:02:29.487Z,1694185349.487 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0029.lzma.bak
2023-09-08T15:02:29.487Z,1694185349.487 [DataOverHttps](INFO): SBD MOMSN=18750433
2023-09-08T15:02:35.980Z,1694185355.980 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:02:35.980Z,1694185355.980 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:02:35.980Z,1694185355.980 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:02:39.168Z,1694185359.168 [NAL9602](INFO): SBD MO Status=2, MOMSN=3522, MT Status=2, MTMSN=0
2023-09-08T15:02:39.168Z,1694185359.168 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T15:03:33.775Z,1694185413.775 [NAL9602](INFO): SBD MO Status=0, MOMSN=3522, MT Status=0, MTMSN=0
2023-09-08T15:03:33.775Z,1694185413.775 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:04:04.483Z,1694185444.483 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:07:36.717Z,1694185656.717 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:07:36.717Z,1694185656.717 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:07:36.717Z,1694185656.717 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:07:36.718Z,1694185656.718 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:07:37.100Z,1694185657.100 [Default:CheckIn:D] Stopped
2023-09-08T15:07:37.100Z,1694185657.100 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:07:37.506Z,1694185657.506 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.117794 min
2023-09-08T15:07:37.507Z,1694185657.507 [Default:CheckIn:E] Stopped
2023-09-08T15:07:37.507Z,1694185657.507 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:07:37.507Z,1694185657.507 [Default:CheckIn] Stopped
2023-09-08T15:07:37.507Z,1694185657.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:07:37.507Z,1694185657.507 [Default:CheckIn](INFO): Running loop #7
2023-09-08T15:07:37.507Z,1694185657.507 [Default:CheckIn] Running Loop=7
2023-09-08T15:07:37.507Z,1694185657.507 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:07:37.507Z,1694185657.507 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:07:39.508Z,1694185659.508 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150738.00,A,4145.52974,N,08116.65192,W,0.019,313.80,080923,,,D*7E
2023-09-08T15:07:39.518Z,1694185659.518 [NAL9602](INFO): GPS fix at 20230908T150738: (41.758829, -81.277532)
2023-09-08T15:07:39.528Z,1694185659.528 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:07:39.529Z,1694185659.529 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:07:48.332Z,1694185668.332 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0031.lzma
2023-09-08T15:07:49.335Z,1694185669.335 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0031.lzma.bak
2023-09-08T15:07:49.335Z,1694185669.335 [DataOverHttps](INFO): SBD MOMSN=18750453
2023-09-08T15:07:53.239Z,1694185673.239 [NAL9602](INFO): SBD MO Status=0, MOMSN=3523, MT Status=0, MTMSN=0
2023-09-08T15:07:53.240Z,1694185673.240 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:08:07.832Z,1694185687.832 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0032.lzma
2023-09-08T15:08:08.835Z,1694185688.835 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0032.lzma.bak
2023-09-08T15:08:08.835Z,1694185688.835 [DataOverHttps](INFO): SBD MOMSN=18750458
2023-09-08T15:08:12.662Z,1694185692.662 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:08:12.662Z,1694185692.662 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:08:12.662Z,1694185692.662 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:08:23.946Z,1694185703.946 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:13:13.331Z,1694185993.331 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:13:13.331Z,1694185993.331 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:13:13.331Z,1694185993.331 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:13:13.331Z,1694185993.331 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:13:13.743Z,1694185993.743 [Default:CheckIn:D] Stopped
2023-09-08T15:13:13.743Z,1694185993.743 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:13:14.177Z,1694185994.177 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.728512 min
2023-09-08T15:13:14.177Z,1694185994.177 [Default:CheckIn:E] Stopped
2023-09-08T15:13:14.177Z,1694185994.177 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:13:14.177Z,1694185994.177 [Default:CheckIn] Stopped
2023-09-08T15:13:14.177Z,1694185994.177 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:13:14.177Z,1694185994.177 [Default:CheckIn](INFO): Running loop #8
2023-09-08T15:13:14.177Z,1694185994.177 [Default:CheckIn] Running Loop=8
2023-09-08T15:13:14.178Z,1694185994.178 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:13:14.178Z,1694185994.178 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:13:16.136Z,1694185996.136 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151315.00,A,4145.52995,N,08116.65225,W,0.019,313.80,080923,,,D*74
2023-09-08T15:13:16.138Z,1694185996.138 [NAL9602](INFO): GPS fix at 20230908T151315: (41.758832, -81.277537)
2023-09-08T15:13:16.149Z,1694185996.149 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:13:16.149Z,1694185996.149 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:13:18.132Z,1694185998.132 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0034.lzma
2023-09-08T15:13:19.162Z,1694185999.162 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0034.lzma.bak
2023-09-08T15:13:19.163Z,1694185999.163 [DataOverHttps](INFO): SBD MOMSN=18750529
2023-09-08T15:13:37.908Z,1694186017.908 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T141321/Express0035.lzma
2023-09-08T15:13:38.911Z,1694186018.911 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0035.lzma.bak
2023-09-08T15:13:38.911Z,1694186018.911 [DataOverHttps](INFO): SBD MOMSN=18750532
2023-09-08T15:13:42.830Z,1694186022.830 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:13:42.830Z,1694186022.830 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:13:42.830Z,1694186022.830 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:13:46.033Z,1694186026.033 [NAL9602](INFO): SBD MO Status=2, MOMSN=3524, MT Status=2, MTMSN=0
2023-09-08T15:13:46.034Z,1694186026.034 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T15:14:47.096Z,1694186087.096 [NAL9602](INFO): SBD MO Status=0, MOMSN=3524, MT Status=0, MTMSN=0
2023-09-08T15:14:47.096Z,1694186087.096 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:15:17.858Z,1694186117.858 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:18:43.557Z,1694186323.557 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:18:43.558Z,1694186323.558 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:18:43.558Z,1694186323.558 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:18:43.558Z,1694186323.558 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:18:43.977Z,1694186323.977 [Default:CheckIn:D] Stopped
2023-09-08T15:18:43.977Z,1694186323.977 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:18:44.370Z,1694186324.370 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.232406 min
2023-09-08T15:18:44.370Z,1694186324.370 [Default:CheckIn:E] Stopped
2023-09-08T15:18:44.371Z,1694186324.371 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:18:44.371Z,1694186324.371 [Default:CheckIn] Stopped
2023-09-08T15:18:44.371Z,1694186324.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:18:44.371Z,1694186324.371 [Default:CheckIn](INFO): Running loop #9
2023-09-08T15:18:44.371Z,1694186324.371 [Default:CheckIn] Running Loop=9
2023-09-08T15:18:44.371Z,1694186324.371 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:18:44.371Z,1694186324.371 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:18:46.344Z,1694186326.344 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151845.00,A,4145.53006,N,08116.65125,W,0.019,313.80,080923,,,D*7B
2023-09-08T15:18:46.346Z,1694186326.346 [NAL9602](INFO): GPS fix at 20230908T151845: (41.758834, -81.277521)
2023-09-08T15:18:46.375Z,1694186326.375 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:18:46.375Z,1694186326.375 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:18:55.176Z,1694186335.176 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0037.lzma
2023-09-08T15:18:56.178Z,1694186336.178 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0037.lzma.bak
2023-09-08T15:18:56.179Z,1694186336.179 [DataOverHttps](INFO): SBD MOMSN=18750564
2023-09-08T15:19:19.552Z,1694186359.552 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0038.lzma
2023-09-08T15:19:20.555Z,1694186360.555 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0038.lzma.bak
2023-09-08T15:19:20.555Z,1694186360.555 [DataOverHttps](INFO): SBD MOMSN=18750603
2023-09-08T15:19:24.401Z,1694186364.401 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:19:24.401Z,1694186364.401 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:19:24.401Z,1694186364.401 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:19:44.576Z,1694186384.576 [NAL9602](INFO): SBD MO Status=0, MOMSN=3525, MT Status=0, MTMSN=0
2023-09-08T15:19:44.576Z,1694186384.576 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:20:15.278Z,1694186415.278 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:24:24.818Z,1694186664.818 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:24:24.818Z,1694186664.818 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:24:24.818Z,1694186664.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:24:24.818Z,1694186664.818 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:24:25.221Z,1694186665.221 [Default:CheckIn:D] Stopped
2023-09-08T15:24:25.221Z,1694186665.221 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:24:25.639Z,1694186665.639 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.919816 min
2023-09-08T15:24:25.639Z,1694186665.639 [Default:CheckIn:E] Stopped
2023-09-08T15:24:25.639Z,1694186665.639 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:24:25.639Z,1694186665.639 [Default:CheckIn] Stopped
2023-09-08T15:24:25.639Z,1694186665.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:24:25.640Z,1694186665.640 [Default:CheckIn](INFO): Running loop #10
2023-09-08T15:24:25.640Z,1694186665.640 [Default:CheckIn] Running Loop=10
2023-09-08T15:24:25.640Z,1694186665.640 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:24:25.640Z,1694186665.640 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:24:27.636Z,1694186667.636 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152427.00,A,4145.52966,N,08116.65185,W,0.039,313.80,080923,,,D*76
2023-09-08T15:24:27.638Z,1694186667.638 [NAL9602](INFO): GPS fix at 20230908T152427: (41.758828, -81.277531)
2023-09-08T15:24:27.661Z,1694186667.661 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:24:27.661Z,1694186667.661 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:24:43.480Z,1694186683.480 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T141321/Courier0040.lzma
2023-09-08T15:24:44.483Z,1694186684.483 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0040.lzma.bak
2023-09-08T15:24:44.483Z,1694186684.483 [DataOverHttps](INFO): SBD MOMSN=18750630
2023-09-08T15:25:07.132Z,1694186707.132 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0041.lzma
2023-09-08T15:25:08.135Z,1694186708.135 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0041.lzma.bak
2023-09-08T15:25:08.135Z,1694186708.135 [DataOverHttps](INFO): SBD MOMSN=18750633
2023-09-08T15:25:12.298Z,1694186712.298 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:25:12.298Z,1694186712.298 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:25:12.299Z,1694186712.299 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:25:35.280Z,1694186735.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=3526, MT Status=0, MTMSN=0
2023-09-08T15:25:35.280Z,1694186735.280 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:26:05.977Z,1694186765.977 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:28:26.338Z,1694186906.338 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-08T15:28:26.340Z,1694186906.340 [BPC1](INFO): Received data from all battery sticks.
2023-09-08T15:30:13.022Z,1694187013.022 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:30:13.022Z,1694187013.022 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:30:13.022Z,1694187013.022 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:30:13.022Z,1694187013.022 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:30:13.426Z,1694187013.426 [Default:CheckIn:D] Stopped
2023-09-08T15:30:13.426Z,1694187013.426 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:30:13.840Z,1694187013.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.723230 min
2023-09-08T15:30:13.840Z,1694187013.840 [Default:CheckIn:E] Stopped
2023-09-08T15:30:13.840Z,1694187013.840 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:30:13.840Z,1694187013.840 [Default:CheckIn] Stopped
2023-09-08T15:30:13.840Z,1694187013.840 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:30:13.841Z,1694187013.841 [Default:CheckIn](INFO): Running loop #11
2023-09-08T15:30:13.841Z,1694187013.841 [Default:CheckIn] Running Loop=11
2023-09-08T15:30:13.841Z,1694187013.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:30:13.841Z,1694187013.841 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:30:15.835Z,1694187015.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153015.00,A,4145.53032,N,08116.65239,W,0.019,313.80,080923,,,D*7D
2023-09-08T15:30:15.837Z,1694187015.837 [NAL9602](INFO): GPS fix at 20230908T153015: (41.758839, -81.277540)
2023-09-08T15:30:15.848Z,1694187015.848 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:30:15.848Z,1694187015.848 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:30:24.748Z,1694187024.748 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20230908T141321/Courier0043.lzma
2023-09-08T15:30:28.207Z,1694187028.207 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0043.lzma.bak
2023-09-08T15:30:28.208Z,1694187028.208 [DataOverHttps](INFO): SBD MOMSN=18750679
2023-09-08T15:30:34.848Z,1694187034.848 [NAL9602](INFO): SBD MO Status=2, MOMSN=3527, MT Status=2, MTMSN=0
2023-09-08T15:30:34.848Z,1694187034.848 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T15:30:55.041Z,1694187055.041 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0044.lzma
2023-09-08T15:30:56.043Z,1694187056.043 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0044.lzma.bak
2023-09-08T15:30:56.043Z,1694187056.043 [DataOverHttps](INFO): SBD MOMSN=18750691
2023-09-08T15:31:00.056Z,1694187060.056 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:31:00.056Z,1694187060.056 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:31:00.056Z,1694187060.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:31:09.302Z,1694187069.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=3527, MT Status=2, MTMSN=0
2023-09-08T15:31:09.302Z,1694187069.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T15:31:25.058Z,1694187085.058 [NAL9602](INFO): SBD MO Status=2, MOMSN=3527, MT Status=2, MTMSN=0
2023-09-08T15:31:25.058Z,1694187085.058 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T15:32:03.842Z,1694187123.842 [NAL9602](INFO): SBD MO Status=2, MOMSN=3527, MT Status=2, MTMSN=0
2023-09-08T15:32:03.843Z,1694187123.843 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T15:32:49.100Z,1694187169.100 [NAL9602](INFO): SBD MO Status=0, MOMSN=3527, MT Status=0, MTMSN=0
2023-09-08T15:32:49.100Z,1694187169.100 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:33:19.810Z,1694187199.810 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:36:00.656Z,1694187360.656 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:36:00.656Z,1694187360.656 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:36:00.656Z,1694187360.656 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:36:00.656Z,1694187360.656 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:36:01.047Z,1694187361.047 [Default:CheckIn:D] Stopped
2023-09-08T15:36:01.047Z,1694187361.047 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:36:01.456Z,1694187361.456 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.516915 min
2023-09-08T15:36:01.456Z,1694187361.456 [Default:CheckIn:E] Stopped
2023-09-08T15:36:01.456Z,1694187361.456 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:36:01.457Z,1694187361.457 [Default:CheckIn] Stopped
2023-09-08T15:36:01.457Z,1694187361.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:36:01.457Z,1694187361.457 [Default:CheckIn](INFO): Running loop #12
2023-09-08T15:36:01.457Z,1694187361.457 [Default:CheckIn] Running Loop=12
2023-09-08T15:36:01.457Z,1694187361.457 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:36:01.457Z,1694187361.457 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:36:03.472Z,1694187363.472 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153602.00,A,4145.53161,N,08116.65370,W,0.039,313.80,080923,,,D*74
2023-09-08T15:36:03.474Z,1694187363.474 [NAL9602](INFO): GPS fix at 20230908T153602: (41.758860, -81.277562)
2023-09-08T15:36:03.484Z,1694187363.484 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:36:03.485Z,1694187363.485 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:36:12.864Z,1694187372.864 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T141321/Courier0046.lzma
2023-09-08T15:36:13.867Z,1694187373.867 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0046.lzma.bak
2023-09-08T15:36:13.867Z,1694187373.867 [DataOverHttps](INFO): SBD MOMSN=18750737
2023-09-08T15:36:14.372Z,1694187374.372 [NAL9602](INFO): SBD MO Status=0, MOMSN=3528, MT Status=0, MTMSN=0
2023-09-08T15:36:14.372Z,1694187374.372 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:36:35.521Z,1694187395.521 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T141321/Express0047.lzma
2023-09-08T15:36:36.523Z,1694187396.523 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0047.lzma.bak
2023-09-08T15:36:36.523Z,1694187396.523 [DataOverHttps](INFO): SBD MOMSN=18750740
2023-09-08T15:36:42.788Z,1694187402.788 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:36:42.830Z,1694187402.830 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:36:42.830Z,1694187402.830 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:36:44.802Z,1694187404.802 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:40:41.555Z,1694187641.555 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T154040
2023-09-08T15:40:51.613Z,1694187651.613 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 41.76107 degree;set transit.Longitude -81.27857 degree;set transit.Depth 1 m;set transit.MaxDepth 3 m" g0uw 1 2
2023-09-08T15:40:51.693Z,1694187651.693 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 41.76107 degree;set transit.Longitude -81.27857 degree;set transit.Depth 1 m;set transit.MaxDepth 3 m" g0uw 1 2.000000
2023-09-08T15:40:51.694Z,1694187651.694 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=g0uw
2023-09-08T15:40:51.695Z,1694187651.695 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='g0uw'): "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 41.76107 degree;set transit.Longitude -81.27857 degree;set transit.Depth 1 m;set transit.MaxDepth 3 m", ASAP
2023-09-08T15:40:52.614Z,1694187652.614 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T154051
2023-09-08T15:40:59.932Z,1694187659.932 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;run" g0uw 2 2
2023-09-08T15:40:59.948Z,1694187659.948 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;run" g0uw 2 2.000000
2023-09-08T15:40:59.948Z,1694187659.948 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=g0uw
2023-09-08T15:40:59.949Z,1694187659.949 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='g0uw'): "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;run", ASAP
2023-09-08T15:41:00.063Z,1694187660.063 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-09-08T15:41:00.064Z,1694187660.064 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-09-08T15:41:00.064Z,1694187660.064 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-09-08T15:41:00.143Z,1694187660.143 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-09-08T15:41:00.146Z,1694187660.146 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-09-08T15:41:00.149Z,1694187660.149 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-09-08T15:41:00.151Z,1694187660.151 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-09-08T15:41:00.154Z,1694187660.154 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-09-08T15:41:00.158Z,1694187660.158 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-09-08T15:41:00.161Z,1694187660.161 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-09-08T15:41:00.163Z,1694187660.163 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-09-08T15:41:00.167Z,1694187660.167 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-09-08T15:41:00.170Z,1694187660.170 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-09-08T15:41:00.173Z,1694187660.173 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-09-08T15:41:00.175Z,1694187660.175 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-09-08T15:41:00.178Z,1694187660.178 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-09-08T15:41:00.178Z,1694187660.178 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-09-08T15:41:00.179Z,1694187660.179 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-09-08T15:41:00.296Z,1694187660.296 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-09-08T15:41:00.299Z,1694187660.299 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-09-08T15:41:00.350Z,1694187660.350 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-09-08T15:41:00.353Z,1694187660.353 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-09-08T15:41:00.355Z,1694187660.355 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-09-08T15:41:00.369Z,1694187660.369 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-09-08T15:41:00.372Z,1694187660.372 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-09-08T15:41:00.399Z,1694187660.399 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-09-08T15:41:00.424Z,1694187660.424 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T15:41:00.467Z,1694187660.467 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-09-08T15:41:00.468Z,1694187660.468 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-09-08T15:41:00.502Z,1694187660.502 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-09-08T15:41:00.517Z,1694187660.517 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-09-08T15:41:00.519Z,1694187660.519 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-09-08T15:41:00.522Z,1694187660.522 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-09-08T15:41:00.524Z,1694187660.524 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-09-08T15:41:00.543Z,1694187660.543 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-09-08T15:41:00.552Z,1694187660.552 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-09-08T15:41:00.597Z,1694187660.597 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-08T15:41:00.597Z,1694187660.597 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-08T15:41:00.611Z,1694187660.611 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2023-09-08T15:41:00.613Z,1694187660.613 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-08T15:41:00.616Z,1694187660.616 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-08T15:41:00.620Z,1694187660.620 [transit:MassHold.Pitch](DEBUG): Construct.
2023-09-08T15:41:00.654Z,1694187660.654 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-09-08T15:41:00.660Z,1694187660.660 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-09-08T15:41:00.678Z,1694187660.678 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-09-08T15:41:00.731Z,1694187660.731 [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
insert Insert/BackseatDriver.tl
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-09-08T15:41:00.731Z,1694187660.731 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-09-08T15:41:09.739Z,1694187669.739 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute
2023-09-08T15:41:09.740Z,1694187669.740 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2023-09-08T15:41:09.741Z,1694187669.741 [CommandExec](IMPORTANT): got command set transit.Latitude 41.76107 degree
2023-09-08T15:41:09.742Z,1694187669.742 [CommandExec](IMPORTANT): got command set transit.Longitude -81.27857 degree
2023-09-08T15:41:09.742Z,1694187669.742 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-09-08T15:41:09.743Z,1694187669.743 [CommandExec](IMPORTANT): got command set transit.MaxDepth 3 meter
2023-09-08T15:41:13.123Z,1694187673.123 [CommandExec](IMPORTANT): got command set transit.MinOffshore .01 kilometer
2023-09-08T15:41:13.124Z,1694187673.124 [CommandExec](IMPORTANT): got command set transit.MinAltitude 2 meter
2023-09-08T15:41:13.124Z,1694187673.124 [CommandExec](IMPORTANT): got command run
2023-09-08T15:41:13.127Z,1694187673.127 [CommandExec](IMPORTANT): Running
2023-09-08T15:41:13.264Z,1694187673.264 [Default] Stopped
2023-09-08T15:41:13.264Z,1694187673.264 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T15:41:13.264Z,1694187673.264 [Default:B.GoToSurface] Stopped
2023-09-08T15:41:13.265Z,1694187673.265 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T15:41:13.265Z,1694187673.265 [Default:CheckIn] Stopped
2023-09-08T15:41:13.265Z,1694187673.265 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:41:13.265Z,1694187673.265 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:41:13.265Z,1694187673.265 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:41:13.265Z,1694187673.265 [MissionManager](IMPORTANT): Started mission transit
2023-09-08T15:41:13.265Z,1694187673.265 [transit] Running Loop=1
2023-09-08T15:41:13.266Z,1694187673.266 [transit](DEBUG): Aggregate::initialize transit
2023-09-08T15:41:13.266Z,1694187673.266 [transit:StandardEnvelopes] Running Loop=1
2023-09-08T15:41:13.266Z,1694187673.266 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-09-08T15:41:13.266Z,1694187673.266 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-08T15:41:13.266Z,1694187673.266 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-09-08T15:41:13.266Z,1694187673.266 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-08T15:41:13.266Z,1694187673.266 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-09-08T15:41:13.266Z,1694187673.266 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-08T15:41:13.267Z,1694187673.267 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-09-08T15:41:13.267Z,1694187673.267 [transit:BackseatDriver] Running Loop=1
2023-09-08T15:41:13.267Z,1694187673.267 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2023-09-08T15:41:13.267Z,1694187673.267 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-08T15:41:13.267Z,1694187673.267 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-08T15:41:13.267Z,1694187673.267 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-09-08T15:41:13.267Z,1694187673.267 [transit:MassHold.Pitch] Running Loop=1
2023-09-08T15:41:13.267Z,1694187673.267 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-09-08T15:41:13.268Z,1694187673.268 [transit:B] Running Loop=1
2023-09-08T15:41:13.268Z,1694187673.268 [transit:MassHold.Pitch] Running Loop=1
2023-09-08T15:41:13.269Z,1694187673.269 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-08T15:41:13.269Z,1694187673.269 [transit:BackseatDriver] Running Loop=1
2023-09-08T15:41:13.269Z,1694187673.269 [transit:StandardEnvelopes] Running Loop=1
2023-09-08T15:41:13.269Z,1694187673.269 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-08T15:41:13.278Z,1694187673.278 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-08T15:41:13.279Z,1694187673.279 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-08T15:41:13.279Z,1694187673.279 [transit:B] Stopped
2023-09-08T15:41:13.279Z,1694187673.279 [transit:C] Running Loop=1
2023-09-08T15:41:13.681Z,1694187673.681 [transit:C] Stopped
2023-09-08T15:41:13.681Z,1694187673.681 [transit:E] Running Loop=1
2023-09-08T15:41:14.088Z,1694187674.088 [transit:E] Stopped
2023-09-08T15:41:14.088Z,1694187674.088 [transit:F] Running Loop=1
2023-09-08T15:41:14.472Z,1694187674.472 [transit:F] Stopped
2023-09-08T15:41:14.472Z,1694187674.472 [transit:G] Running Loop=1
2023-09-08T15:41:14.881Z,1694187674.881 [transit:G] Stopped
2023-09-08T15:41:14.881Z,1694187674.881 [transit:Transit] Running Loop=1
2023-09-08T15:41:14.881Z,1694187674.881 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-09-08T15:41:14.881Z,1694187674.881 [transit:Transit:A.Pitch] Running Loop=1
2023-09-08T15:41:14.881Z,1694187674.881 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-09-08T15:41:14.881Z,1694187674.881 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-08T15:41:14.881Z,1694187674.881 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-09-08T15:41:14.882Z,1694187674.882 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-09-08T15:41:14.882Z,1694187674.882 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-09-08T15:41:14.883Z,1694187674.883 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 41.761070,-81.278572
2023-09-08T15:41:15.286Z,1694187675.286 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-08T15:41:15.287Z,1694187675.287 [transit:Transit:A.Pitch] Running Loop=1
2023-09-08T15:42:11.830Z,1694187731.830 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T154211
2023-09-08T15:42:19.512Z,1694187739.512 [DataOverHttps](INFO): Received command: stop
2023-09-08T15:42:19.573Z,1694187739.573 [CommandExec](IMPORTANT): got command stop
2023-09-08T15:42:19.574Z,1694187739.574 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-09-08T15:42:19.947Z,1694187739.947 [MissionManager](INFO): MissionManager is completed.
2023-09-08T15:42:19.947Z,1694187739.947 [MissionManager](INFO): Uninitializing Mission transit
2023-09-08T15:42:19.947Z,1694187739.947 [transit] Stopped
2023-09-08T15:42:19.947Z,1694187739.947 [transit](DEBUG): Aggregate::uninitialize transit
2023-09-08T15:42:19.947Z,1694187739.947 [transit:StandardEnvelopes] Stopped
2023-09-08T15:42:19.947Z,1694187739.947 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2023-09-08T15:42:19.947Z,1694187739.947 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2023-09-08T15:42:19.947Z,1694187739.947 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2023-09-08T15:42:19.948Z,1694187739.948 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2023-09-08T15:42:19.948Z,1694187739.948 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2023-09-08T15:42:19.948Z,1694187739.948 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2023-09-08T15:42:19.948Z,1694187739.948 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2023-09-08T15:42:19.948Z,1694187739.948 [transit:BackseatDriver] Stopped
2023-09-08T15:42:19.948Z,1694187739.948 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2023-09-08T15:42:19.948Z,1694187739.948 [transit:BackseatDriver:A.BackseatDriver] Stopped
2023-09-08T15:42:19.948Z,1694187739.948 [transit:BuoyancyHold.Buoyancy] Stopped
2023-09-08T15:42:19.948Z,1694187739.948 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-09-08T15:42:19.948Z,1694187739.948 [transit:MassHold.Pitch] Stopped
2023-09-08T15:42:19.948Z,1694187739.948 [transit:Transit] Stopped
2023-09-08T15:42:19.949Z,1694187739.949 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2023-09-08T15:42:19.949Z,1694187739.949 [transit:Transit:A.Pitch] Stopped
2023-09-08T15:42:19.949Z,1694187739.949 [transit:Transit:B.SetSpeed] Stopped
2023-09-08T15:42:19.949Z,1694187739.949 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2023-09-08T15:42:19.949Z,1694187739.949 [transit:Transit:Wpt1.Waypoint] Stopped
2023-09-08T15:42:19.949Z,1694187739.949 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2023-09-08T15:42:20.342Z,1694187740.342 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T15:42:20.342Z,1694187740.342 [Default] Running Loop=1
2023-09-08T15:42:20.342Z,1694187740.342 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T15:42:20.342Z,1694187740.342 [Default:B.GoToSurface] Running Loop=1
2023-09-08T15:42:20.342Z,1694187740.342 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T15:42:20.342Z,1694187740.342 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T15:42:20.343Z,1694187740.343 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T15:42:20.343Z,1694187740.343 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T15:42:20.343Z,1694187740.343 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T15:42:20.344Z,1694187740.344 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T15:42:20.344Z,1694187740.344 [Default:A.Wait] Running Loop=1
2023-09-08T15:42:20.344Z,1694187740.344 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:42:33.672Z,1694187753.672 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T15:42:33.672Z,1694187753.672 [Default:A.Wait] Stopped
2023-09-08T15:42:33.672Z,1694187753.672 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:42:34.080Z,1694187754.080 [Default:CheckIn] Running Loop=1
2023-09-08T15:42:34.081Z,1694187754.081 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:42:34.081Z,1694187754.081 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:42:36.079Z,1694187756.079 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154235.00,A,4145.53072,N,08116.65191,W,0.039,313.80,080923,,,D*7D
2023-09-08T15:42:36.081Z,1694187756.081 [NAL9602](INFO): GPS fix at 20230908T154235: (41.758845, -81.277532)
2023-09-08T15:42:36.108Z,1694187756.108 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:42:36.121Z,1694187756.121 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:42:48.980Z,1694187768.980 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0049.lzma
2023-09-08T15:42:49.984Z,1694187769.984 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0049.lzma.bak
2023-09-08T15:42:49.984Z,1694187769.984 [DataOverHttps](IMPORTANT): SBD MOMSN=18750759, MTMSN=20230908T154249
2023-09-08T15:42:53.255Z,1694187773.255 [NAL9602](INFO): SBD MO Status=0, MOMSN=3529, MT Status=0, MTMSN=0
2023-09-08T15:42:53.255Z,1694187773.255 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:43:00.513Z,1694187780.513 [DataOverHttps](INFO): Received command: schedule resume
2023-09-08T15:43:00.564Z,1694187780.564 [CommandExec](IMPORTANT): got command schedule resume
2023-09-08T15:43:00.565Z,1694187780.565 [CommandExec](IMPORTANT): Scheduling is resumed
2023-09-08T15:43:12.767Z,1694187792.767 [DataOverHttps](INFO): Sending 773 bytes from file Logs/20230908T141321/Express0050.lzma
2023-09-08T15:43:13.767Z,1694187793.767 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0050.lzma.bak
2023-09-08T15:43:13.767Z,1694187793.767 [DataOverHttps](INFO): SBD MOMSN=18750764
2023-09-08T15:43:23.993Z,1694187803.993 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:43:30.788Z,1694187810.788 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230908T141321/Courier0052.lzma
2023-09-08T15:43:31.791Z,1694187811.791 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0052.lzma.bak
2023-09-08T15:43:31.791Z,1694187811.791 [DataOverHttps](INFO): SBD MOMSN=18750795
2023-09-08T15:43:50.368Z,1694187830.368 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20230908T141321/Express0054.lzma
2023-09-08T15:43:51.371Z,1694187831.371 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0054.lzma.bak
2023-09-08T15:43:51.371Z,1694187831.371 [DataOverHttps](INFO): SBD MOMSN=18750797
2023-09-08T15:43:55.159Z,1694187835.159 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:43:55.160Z,1694187835.160 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:43:55.160Z,1694187835.160 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:48:55.792Z,1694188135.792 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:48:55.793Z,1694188135.793 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:48:55.793Z,1694188135.793 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:48:55.793Z,1694188135.793 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:48:56.192Z,1694188136.192 [Default:CheckIn:D] Stopped
2023-09-08T15:48:56.192Z,1694188136.192 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:48:56.600Z,1694188136.600 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.597498 min
2023-09-08T15:48:56.600Z,1694188136.600 [Default:CheckIn:E] Stopped
2023-09-08T15:48:56.600Z,1694188136.600 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:48:56.601Z,1694188136.601 [Default:CheckIn] Stopped
2023-09-08T15:48:56.601Z,1694188136.601 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:48:56.601Z,1694188136.601 [Default:CheckIn](INFO): Running loop #2
2023-09-08T15:48:56.601Z,1694188136.601 [Default:CheckIn] Running Loop=2
2023-09-08T15:48:56.601Z,1694188136.601 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:48:56.601Z,1694188136.601 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:48:58.612Z,1694188138.612 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154858.00,A,4145.53027,N,08116.65247,W,0.000,313.80,080923,,,D*7E
2023-09-08T15:48:58.614Z,1694188138.614 [NAL9602](INFO): GPS fix at 20230908T154858: (41.758838, -81.277541)
2023-09-08T15:48:58.625Z,1694188138.625 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:48:58.625Z,1694188138.625 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:49:07.264Z,1694188147.264 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0058.lzma
2023-09-08T15:49:08.267Z,1694188148.267 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0058.lzma.bak
2023-09-08T15:49:08.267Z,1694188148.267 [DataOverHttps](INFO): SBD MOMSN=18750822
2023-09-08T15:49:23.658Z,1694188163.658 [NAL9602](INFO): SBD MO Status=0, MOMSN=3530, MT Status=0, MTMSN=0
2023-09-08T15:49:23.658Z,1694188163.658 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:49:29.052Z,1694188169.052 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T141321/Express0059.lzma
2023-09-08T15:49:30.055Z,1694188170.055 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0059.lzma.bak
2023-09-08T15:49:30.055Z,1694188170.055 [DataOverHttps](INFO): SBD MOMSN=18750830
2023-09-08T15:49:33.802Z,1694188173.802 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:49:33.803Z,1694188173.803 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:49:33.803Z,1694188173.803 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:49:54.362Z,1694188194.362 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:54:34.554Z,1694188474.554 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:54:34.554Z,1694188474.554 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:54:34.554Z,1694188474.554 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:54:34.555Z,1694188474.555 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:54:34.954Z,1694188474.954 [Default:CheckIn:D] Stopped
2023-09-08T15:54:34.954Z,1694188474.954 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:54:35.326Z,1694188475.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.243539 min
2023-09-08T15:54:35.326Z,1694188475.326 [Default:CheckIn:E] Stopped
2023-09-08T15:54:35.326Z,1694188475.326 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:54:35.326Z,1694188475.326 [Default:CheckIn] Stopped
2023-09-08T15:54:35.326Z,1694188475.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:54:35.327Z,1694188475.327 [Default:CheckIn](INFO): Running loop #3
2023-09-08T15:54:35.327Z,1694188475.327 [Default:CheckIn] Running Loop=3
2023-09-08T15:54:35.327Z,1694188475.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:54:35.327Z,1694188475.327 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:54:37.331Z,1694188477.331 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155436.00,A,4145.53045,N,08116.65247,W,0.019,0.00,080923,,,D*7E
2023-09-08T15:54:37.334Z,1694188477.334 [NAL9602](INFO): GPS fix at 20230908T155436: (41.758841, -81.277541)
2023-09-08T15:54:37.365Z,1694188477.365 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:54:37.365Z,1694188477.365 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:54:48.348Z,1694188488.348 [NAL9602](INFO): SBD MO Status=0, MOMSN=3531, MT Status=0, MTMSN=0
2023-09-08T15:54:48.348Z,1694188488.348 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:54:53.676Z,1694188493.676 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T141321/Courier0061.lzma
2023-09-08T15:54:54.679Z,1694188494.679 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0061.lzma.bak
2023-09-08T15:54:54.679Z,1694188494.679 [DataOverHttps](INFO): SBD MOMSN=18750884
2023-09-08T15:55:13.244Z,1694188513.244 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T141321/Express0062.lzma
2023-09-08T15:55:14.247Z,1694188514.247 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0062.lzma.bak
2023-09-08T15:55:14.247Z,1694188514.247 [DataOverHttps](INFO): SBD MOMSN=18750889
2023-09-08T15:55:18.261Z,1694188518.261 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:55:18.261Z,1694188518.261 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:55:18.261Z,1694188518.261 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:55:19.050Z,1694188519.050 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:00:19.042Z,1694188819.042 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:00:19.043Z,1694188819.043 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:00:19.043Z,1694188819.043 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:00:19.043Z,1694188819.043 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:00:19.447Z,1694188819.447 [Default:CheckIn:D] Stopped
2023-09-08T16:00:19.447Z,1694188819.447 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:00:19.861Z,1694188819.861 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.985083 min
2023-09-08T16:00:19.862Z,1694188819.862 [Default:CheckIn:E] Stopped
2023-09-08T16:00:19.862Z,1694188819.862 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:00:19.862Z,1694188819.862 [Default:CheckIn] Stopped
2023-09-08T16:00:19.862Z,1694188819.862 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:00:19.862Z,1694188819.862 [Default:CheckIn](INFO): Running loop #4
2023-09-08T16:00:19.862Z,1694188819.862 [Default:CheckIn] Running Loop=4
2023-09-08T16:00:19.862Z,1694188819.862 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:00:19.862Z,1694188819.862 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:00:21.863Z,1694188821.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160021.00,A,4145.53075,N,08116.65381,W,0.058,0.00,080923,,,D*77
2023-09-08T16:00:21.865Z,1694188821.865 [NAL9602](INFO): GPS fix at 20230908T160021: (41.758846, -81.277563)
2023-09-08T16:00:21.892Z,1694188821.892 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:00:21.892Z,1694188821.892 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:00:31.520Z,1694188831.520 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0064.lzma
2023-09-08T16:00:32.523Z,1694188832.523 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0064.lzma.bak
2023-09-08T16:00:32.523Z,1694188832.523 [DataOverHttps](INFO): SBD MOMSN=18750900
2023-09-08T16:00:35.231Z,1694188835.231 [NAL9602](INFO): SBD MO Status=0, MOMSN=3532, MT Status=0, MTMSN=0
2023-09-08T16:00:35.232Z,1694188835.232 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:00:55.752Z,1694188855.752 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0065.lzma
2023-09-08T16:00:58.552Z,1694188858.552 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0065.lzma.bak
2023-09-08T16:00:58.552Z,1694188858.552 [DataOverHttps](INFO): SBD MOMSN=18750903
2023-09-08T16:01:01.989Z,1694188861.989 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:01:01.990Z,1694188861.990 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:01:01.990Z,1694188861.990 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:01:05.998Z,1694188865.998 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:06:02.705Z,1694189162.705 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:06:02.705Z,1694189162.705 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:06:02.705Z,1694189162.705 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:06:02.705Z,1694189162.705 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:06:03.128Z,1694189163.128 [Default:CheckIn:D] Stopped
2023-09-08T16:06:03.128Z,1694189163.128 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:06:03.560Z,1694189163.560 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.713100 min
2023-09-08T16:06:03.560Z,1694189163.560 [Default:CheckIn:E] Stopped
2023-09-08T16:06:03.560Z,1694189163.560 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:06:03.561Z,1694189163.561 [Default:CheckIn] Stopped
2023-09-08T16:06:03.561Z,1694189163.561 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:06:03.561Z,1694189163.561 [Default:CheckIn](INFO): Running loop #5
2023-09-08T16:06:03.561Z,1694189163.561 [Default:CheckIn] Running Loop=5
2023-09-08T16:06:03.561Z,1694189163.561 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:06:03.561Z,1694189163.561 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:06:05.512Z,1694189165.512 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160605.00,A,4145.53090,N,08116.65253,W,0.019,0.00,080923,,,D*77
2023-09-08T16:06:05.514Z,1694189165.514 [NAL9602](INFO): GPS fix at 20230908T160605: (41.758848, -81.277542)
2023-09-08T16:06:05.549Z,1694189165.549 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:06:05.549Z,1694189165.549 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:06:14.740Z,1694189174.740 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0067.lzma
2023-09-08T16:06:15.742Z,1694189175.742 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0067.lzma.bak
2023-09-08T16:06:15.743Z,1694189175.743 [DataOverHttps](INFO): SBD MOMSN=18750910
2023-09-08T16:06:34.635Z,1694189194.635 [NAL9602](INFO): SBD MO Status=2, MOMSN=3533, MT Status=2, MTMSN=0
2023-09-08T16:06:34.636Z,1694189194.636 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:06:39.409Z,1694189199.409 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0068.lzma
2023-09-08T16:06:40.411Z,1694189200.411 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0068.lzma.bak
2023-09-08T16:06:40.411Z,1694189200.411 [DataOverHttps](INFO): SBD MOMSN=18750913
2023-09-08T16:06:47.230Z,1694189207.230 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:06:47.231Z,1694189207.231 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:06:47.231Z,1694189207.231 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:07:00.507Z,1694189220.507 [NAL9602](INFO): SBD MO Status=2, MOMSN=3533, MT Status=2, MTMSN=0
2023-09-08T16:07:00.508Z,1694189220.508 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:07:18.336Z,1694189238.336 [NAL9602](INFO): SBD MO Status=2, MOMSN=3533, MT Status=2, MTMSN=0
2023-09-08T16:07:18.336Z,1694189238.336 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:08:23.784Z,1694189303.784 [NAL9602](INFO): SBD MO Status=0, MOMSN=3533, MT Status=0, MTMSN=0
2023-09-08T16:08:23.784Z,1694189303.784 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:08:54.485Z,1694189334.485 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:11:47.888Z,1694189507.888 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:11:47.888Z,1694189507.888 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:11:47.888Z,1694189507.888 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:11:47.889Z,1694189507.889 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:11:48.282Z,1694189508.282 [Default:CheckIn:D] Stopped
2023-09-08T16:11:48.282Z,1694189508.282 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:11:48.702Z,1694189508.702 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.465670 min
2023-09-08T16:11:48.702Z,1694189508.702 [Default:CheckIn:E] Stopped
2023-09-08T16:11:48.702Z,1694189508.702 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:11:48.702Z,1694189508.702 [Default:CheckIn] Stopped
2023-09-08T16:11:48.703Z,1694189508.703 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:11:48.703Z,1694189508.703 [Default:CheckIn](INFO): Running loop #6
2023-09-08T16:11:48.703Z,1694189508.703 [Default:CheckIn] Running Loop=6
2023-09-08T16:11:48.703Z,1694189508.703 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:11:48.703Z,1694189508.703 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:11:50.692Z,1694189510.692 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161150.00,A,4145.53064,N,08116.65154,W,0.019,0.00,080923,,,D*7E
2023-09-08T16:11:50.702Z,1694189510.702 [NAL9602](INFO): GPS fix at 20230908T161150: (41.758844, -81.277526)
2023-09-08T16:11:50.713Z,1694189510.713 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:11:50.713Z,1694189510.713 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:11:59.280Z,1694189519.280 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0070.lzma
2023-09-08T16:12:00.283Z,1694189520.283 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0070.lzma.bak
2023-09-08T16:12:00.283Z,1694189520.283 [DataOverHttps](INFO): SBD MOMSN=18750929
2023-09-08T16:12:04.019Z,1694189524.019 [NAL9602](INFO): SBD MO Status=0, MOMSN=3534, MT Status=0, MTMSN=0
2023-09-08T16:12:04.020Z,1694189524.020 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:12:24.048Z,1694189544.048 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0071.lzma
2023-09-08T16:12:25.051Z,1694189545.051 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0071.lzma.bak
2023-09-08T16:12:25.051Z,1694189545.051 [DataOverHttps](INFO): SBD MOMSN=18750933
2023-09-08T16:12:30.393Z,1694189550.393 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:12:30.393Z,1694189550.393 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:12:30.393Z,1694189550.393 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:12:34.438Z,1694189554.438 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:17:31.098Z,1694189851.098 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:17:31.099Z,1694189851.099 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:17:31.099Z,1694189851.099 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:17:31.099Z,1694189851.099 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:17:31.516Z,1694189851.516 [Default:CheckIn:D] Stopped
2023-09-08T16:17:31.516Z,1694189851.516 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:17:31.925Z,1694189851.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.186230 min
2023-09-08T16:17:31.925Z,1694189851.925 [Default:CheckIn:E] Stopped
2023-09-08T16:17:31.925Z,1694189851.925 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:17:31.925Z,1694189851.925 [Default:CheckIn] Stopped
2023-09-08T16:17:31.925Z,1694189851.925 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:17:31.926Z,1694189851.926 [Default:CheckIn](INFO): Running loop #7
2023-09-08T16:17:31.926Z,1694189851.926 [Default:CheckIn] Running Loop=7
2023-09-08T16:17:31.926Z,1694189851.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:17:31.926Z,1694189851.926 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:17:33.928Z,1694189853.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161733.00,A,4145.53018,N,08116.65221,W,0.019,0.00,080923,,,D*77
2023-09-08T16:17:33.934Z,1694189853.934 [NAL9602](INFO): GPS fix at 20230908T161733: (41.758836, -81.277537)
2023-09-08T16:17:34.008Z,1694189854.008 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:17:34.008Z,1694189854.008 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:17:36.556Z,1694189856.556 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T141321/Courier0073.lzma
2023-09-08T16:17:37.563Z,1694189857.563 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0073.lzma.bak
2023-09-08T16:17:37.563Z,1694189857.563 [DataOverHttps](INFO): SBD MOMSN=18751004
2023-09-08T16:17:57.372Z,1694189877.372 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0074.lzma
2023-09-08T16:17:58.375Z,1694189878.375 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0074.lzma.bak
2023-09-08T16:17:58.375Z,1694189878.375 [DataOverHttps](INFO): SBD MOMSN=18751007
2023-09-08T16:17:59.427Z,1694189879.427 [NAL9602](INFO): SBD MO Status=2, MOMSN=3535, MT Status=2, MTMSN=0
2023-09-08T16:17:59.428Z,1694189879.428 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:18:02.670Z,1694189882.670 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:18:02.670Z,1694189882.670 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:18:02.670Z,1694189882.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:18:25.272Z,1694189905.272 [NAL9602](INFO): SBD MO Status=2, MOMSN=3535, MT Status=2, MTMSN=0
2023-09-08T16:18:25.272Z,1694189905.272 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:18:53.203Z,1694189933.203 [NAL9602](INFO): SBD MO Status=2, MOMSN=3535, MT Status=2, MTMSN=0
2023-09-08T16:18:53.203Z,1694189933.203 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:19:09.764Z,1694189949.764 [NAL9602](INFO): SBD MO Status=2, MOMSN=3535, MT Status=2, MTMSN=0
2023-09-08T16:19:09.764Z,1694189949.764 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:19:29.967Z,1694189969.967 [NAL9602](INFO): SBD MO Status=0, MOMSN=3535, MT Status=0, MTMSN=0
2023-09-08T16:19:29.967Z,1694189969.967 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:20:00.665Z,1694190000.665 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:23:03.307Z,1694190183.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:23:03.307Z,1694190183.307 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:23:03.308Z,1694190183.308 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:23:03.308Z,1694190183.308 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:23:03.687Z,1694190183.687 [Default:CheckIn:D] Stopped
2023-09-08T16:23:03.687Z,1694190183.687 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:23:04.100Z,1694190184.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.722420 min
2023-09-08T16:23:04.100Z,1694190184.100 [Default:CheckIn:E] Stopped
2023-09-08T16:23:04.100Z,1694190184.100 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:23:04.100Z,1694190184.100 [Default:CheckIn] Stopped
2023-09-08T16:23:04.100Z,1694190184.100 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:23:04.101Z,1694190184.101 [Default:CheckIn](INFO): Running loop #8
2023-09-08T16:23:04.101Z,1694190184.101 [Default:CheckIn] Running Loop=8
2023-09-08T16:23:04.101Z,1694190184.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:23:04.101Z,1694190184.101 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:23:06.104Z,1694190186.104 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162305.00,A,4145.52981,N,08116.65235,W,0.058,0.00,080923,,,D*7D
2023-09-08T16:23:06.106Z,1694190186.106 [NAL9602](INFO): GPS fix at 20230908T162305: (41.758830, -81.277539)
2023-09-08T16:23:06.149Z,1694190186.149 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:23:06.150Z,1694190186.150 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:23:16.816Z,1694190196.816 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0076.lzma
2023-09-08T16:23:17.819Z,1694190197.819 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0076.lzma.bak
2023-09-08T16:23:17.819Z,1694190197.819 [DataOverHttps](INFO): SBD MOMSN=18751017
2023-09-08T16:23:27.931Z,1694190207.931 [NAL9602](INFO): SBD MO Status=0, MOMSN=3536, MT Status=0, MTMSN=0
2023-09-08T16:23:27.932Z,1694190207.932 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:23:38.756Z,1694190218.756 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T141321/Express0077.lzma
2023-09-08T16:23:39.759Z,1694190219.759 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0077.lzma.bak
2023-09-08T16:23:39.759Z,1694190219.759 [DataOverHttps](INFO): SBD MOMSN=18751021
2023-09-08T16:23:43.720Z,1694190223.720 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:23:43.725Z,1694190223.725 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:23:43.725Z,1694190223.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:23:58.634Z,1694190238.634 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:28:44.432Z,1694190524.432 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:28:44.441Z,1694190524.441 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:28:44.441Z,1694190524.441 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:28:44.441Z,1694190524.441 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:28:44.835Z,1694190524.835 [Default:CheckIn:D] Stopped
2023-09-08T16:28:44.835Z,1694190524.835 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:28:45.249Z,1694190525.249 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.408211 min
2023-09-08T16:28:45.249Z,1694190525.249 [Default:CheckIn:E] Stopped
2023-09-08T16:28:45.249Z,1694190525.249 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:28:45.249Z,1694190525.249 [Default:CheckIn] Stopped
2023-09-08T16:28:45.249Z,1694190525.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:28:45.250Z,1694190525.250 [Default:CheckIn](INFO): Running loop #9
2023-09-08T16:28:45.250Z,1694190525.250 [Default:CheckIn] Running Loop=9
2023-09-08T16:28:45.250Z,1694190525.250 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:28:45.250Z,1694190525.250 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:28:47.224Z,1694190527.224 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162846.00,A,4145.53067,N,08116.65196,W,0.019,0.00,080923,,,D*7E
2023-09-08T16:28:47.226Z,1694190527.226 [NAL9602](INFO): GPS fix at 20230908T162846: (41.758845, -81.277533)
2023-09-08T16:28:47.236Z,1694190527.236 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:28:47.237Z,1694190527.237 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:28:55.828Z,1694190535.828 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0079.lzma
2023-09-08T16:28:56.831Z,1694190536.831 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0079.lzma.bak
2023-09-08T16:28:56.831Z,1694190536.831 [DataOverHttps](INFO): SBD MOMSN=18751038
2023-09-08T16:28:57.727Z,1694190537.727 [NAL9602](INFO): SBD MO Status=0, MOMSN=3537, MT Status=0, MTMSN=0
2023-09-08T16:28:57.728Z,1694190537.728 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:29:18.345Z,1694190558.345 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0080.lzma
2023-09-08T16:29:19.343Z,1694190559.343 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0080.lzma.bak
2023-09-08T16:29:19.343Z,1694190559.343 [DataOverHttps](INFO): SBD MOMSN=18751041
2023-09-08T16:29:23.253Z,1694190563.253 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:29:23.253Z,1694190563.253 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:29:23.253Z,1694190563.253 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:29:28.470Z,1694190568.470 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:34:23.962Z,1694190863.962 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:34:23.963Z,1694190863.963 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:34:23.963Z,1694190863.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:34:23.963Z,1694190863.963 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:34:24.352Z,1694190864.352 [Default:CheckIn:D] Stopped
2023-09-08T16:34:24.352Z,1694190864.352 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:34:24.751Z,1694190864.751 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.066833 min
2023-09-08T16:34:24.751Z,1694190864.751 [Default:CheckIn:E] Stopped
2023-09-08T16:34:24.751Z,1694190864.751 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:34:24.752Z,1694190864.752 [Default:CheckIn] Stopped
2023-09-08T16:34:24.752Z,1694190864.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:34:24.752Z,1694190864.752 [Default:CheckIn](INFO): Running loop #10
2023-09-08T16:34:24.752Z,1694190864.752 [Default:CheckIn] Running Loop=10
2023-09-08T16:34:24.752Z,1694190864.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:34:24.752Z,1694190864.752 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:34:26.780Z,1694190866.780 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163426.00,A,4145.53236,N,08116.65284,W,0.039,0.00,080923,,,D*71
2023-09-08T16:34:26.782Z,1694190866.782 [NAL9602](INFO): GPS fix at 20230908T163426: (41.758873, -81.277547)
2023-09-08T16:34:26.793Z,1694190866.793 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:34:26.793Z,1694190866.793 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:34:38.692Z,1694190878.692 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0082.lzma
2023-09-08T16:34:39.695Z,1694190879.695 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0082.lzma.bak
2023-09-08T16:34:39.695Z,1694190879.695 [DataOverHttps](INFO): SBD MOMSN=18751051
2023-09-08T16:34:48.223Z,1694190888.223 [NAL9602](INFO): SBD MO Status=2, MOMSN=3538, MT Status=2, MTMSN=0
2023-09-08T16:34:48.223Z,1694190888.223 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:35:00.652Z,1694190900.652 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0083.lzma
2023-09-08T16:35:01.655Z,1694190901.655 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0083.lzma.bak
2023-09-08T16:35:01.655Z,1694190901.655 [DataOverHttps](INFO): SBD MOMSN=18751054
2023-09-08T16:35:08.095Z,1694190908.095 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:35:08.095Z,1694190908.095 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:35:08.095Z,1694190908.095 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:35:13.360Z,1694190913.360 [NAL9602](INFO): SBD MO Status=2, MOMSN=3538, MT Status=2, MTMSN=0
2023-09-08T16:35:13.360Z,1694190913.360 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:35:34.179Z,1694190934.179 [NAL9602](INFO): SBD MO Status=2, MOMSN=3538, MT Status=2, MTMSN=0
2023-09-08T16:35:34.180Z,1694190934.180 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:35:49.140Z,1694190949.140 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-08T16:35:49.143Z,1694190949.143 [BPC1](INFO): Received data from all battery sticks.
2023-09-08T16:35:51.146Z,1694190951.146 [NAL9602](INFO): SBD MO Status=2, MOMSN=3538, MT Status=2, MTMSN=0
2023-09-08T16:35:51.146Z,1694190951.146 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T16:36:01.251Z,1694190961.251 [NAL9602](INFO): SBD MO Status=0, MOMSN=3538, MT Status=0, MTMSN=0
2023-09-08T16:36:01.251Z,1694190961.251 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:36:31.950Z,1694190991.950 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:40:08.537Z,1694191208.537 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:40:08.537Z,1694191208.537 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:40:08.537Z,1694191208.537 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:40:08.538Z,1694191208.538 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:40:08.957Z,1694191208.957 [Default:CheckIn:D] Stopped
2023-09-08T16:40:08.957Z,1694191208.957 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:40:09.336Z,1694191209.336 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.810246 min
2023-09-08T16:40:09.337Z,1694191209.337 [Default:CheckIn:E] Stopped
2023-09-08T16:40:09.337Z,1694191209.337 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:40:09.337Z,1694191209.337 [Default:CheckIn] Stopped
2023-09-08T16:40:09.337Z,1694191209.337 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:40:09.337Z,1694191209.337 [Default:CheckIn](INFO): Running loop #11
2023-09-08T16:40:09.337Z,1694191209.337 [Default:CheckIn] Running Loop=11
2023-09-08T16:40:09.337Z,1694191209.337 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:40:09.337Z,1694191209.337 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:40:11.352Z,1694191211.352 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164010.00,A,4145.53141,N,08116.65302,W,0.039,0.00,080923,,,D*7B
2023-09-08T16:40:11.363Z,1694191211.363 [NAL9602](INFO): GPS fix at 20230908T164010: (41.758857, -81.277550)
2023-09-08T16:40:11.397Z,1694191211.397 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:40:11.397Z,1694191211.397 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:40:14.984Z,1694191214.984 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20230908T141321/Courier0085.lzma
2023-09-08T16:40:15.991Z,1694191215.991 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0085.lzma.bak
2023-09-08T16:40:15.991Z,1694191215.991 [DataOverHttps](INFO): SBD MOMSN=18751123
2023-09-08T16:40:30.404Z,1694191230.404 [NAL9602](INFO): SBD MO Status=0, MOMSN=3539, MT Status=0, MTMSN=0
2023-09-08T16:40:30.404Z,1694191230.404 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:40:39.781Z,1694191239.781 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T141321/Express0086.lzma
2023-09-08T16:40:40.776Z,1694191240.776 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0086.lzma.bak
2023-09-08T16:40:40.777Z,1694191240.777 [DataOverHttps](INFO): SBD MOMSN=18751129
2023-09-08T16:40:45.836Z,1694191245.836 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:40:45.837Z,1694191245.837 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:40:45.837Z,1694191245.837 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:41:01.178Z,1694191261.178 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:45:46.479Z,1694191546.479 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:45:46.480Z,1694191546.480 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:45:46.480Z,1694191546.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:45:46.480Z,1694191546.480 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:45:46.888Z,1694191546.888 [Default:CheckIn:D] Stopped
2023-09-08T16:45:46.888Z,1694191546.888 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:45:47.297Z,1694191547.297 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.442436 min
2023-09-08T16:45:47.297Z,1694191547.297 [Default:CheckIn:E] Stopped
2023-09-08T16:45:47.297Z,1694191547.297 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:45:47.297Z,1694191547.297 [Default:CheckIn] Stopped
2023-09-08T16:45:47.297Z,1694191547.297 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:45:47.298Z,1694191547.298 [Default:CheckIn](INFO): Running loop #12
2023-09-08T16:45:47.298Z,1694191547.298 [Default:CheckIn] Running Loop=12
2023-09-08T16:45:47.298Z,1694191547.298 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:45:47.298Z,1694191547.298 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:45:49.299Z,1694191549.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164548.00,A,4145.52891,N,08116.65264,W,0.039,0.00,080923,,,D*77
2023-09-08T16:45:49.301Z,1694191549.301 [NAL9602](INFO): GPS fix at 20230908T164548: (41.758815, -81.277544)
2023-09-08T16:45:49.328Z,1694191549.328 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:45:49.333Z,1694191549.333 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:45:58.332Z,1694191558.332 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T141321/Courier0088.lzma
2023-09-08T16:45:59.335Z,1694191559.335 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0088.lzma.bak
2023-09-08T16:45:59.335Z,1694191559.335 [DataOverHttps](INFO): SBD MOMSN=18751139
2023-09-08T16:46:04.651Z,1694191564.651 [NAL9602](INFO): SBD MO Status=0, MOMSN=3540, MT Status=0, MTMSN=0
2023-09-08T16:46:04.651Z,1694191564.651 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:46:18.128Z,1694191578.128 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0089.lzma
2023-09-08T16:46:19.131Z,1694191579.131 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0089.lzma.bak
2023-09-08T16:46:19.131Z,1694191579.131 [DataOverHttps](INFO): SBD MOMSN=18751142
2023-09-08T16:46:23.273Z,1694191583.273 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:46:23.273Z,1694191583.273 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:46:23.273Z,1694191583.273 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:46:35.374Z,1694191595.374 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:51:23.989Z,1694191883.989 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:51:23.989Z,1694191883.989 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:51:23.989Z,1694191883.989 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:51:23.989Z,1694191883.989 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:51:24.390Z,1694191884.390 [Default:CheckIn:D] Stopped
2023-09-08T16:51:24.391Z,1694191884.391 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:51:24.783Z,1694191884.783 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.067472 min
2023-09-08T16:51:24.783Z,1694191884.783 [Default:CheckIn:E] Stopped
2023-09-08T16:51:24.783Z,1694191884.783 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:51:24.784Z,1694191884.784 [Default:CheckIn] Stopped
2023-09-08T16:51:24.784Z,1694191884.784 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:51:24.784Z,1694191884.784 [Default:CheckIn](INFO): Running loop #13
2023-09-08T16:51:24.784Z,1694191884.784 [Default:CheckIn] Running Loop=13
2023-09-08T16:51:24.784Z,1694191884.784 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:51:24.784Z,1694191884.784 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:51:26.796Z,1694191886.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165126.00,A,4145.53042,N,08116.65290,W,0.019,0.00,080923,,,D*74
2023-09-08T16:51:26.799Z,1694191886.799 [NAL9602](INFO): GPS fix at 20230908T165126: (41.758840, -81.277548)
2023-09-08T16:51:26.891Z,1694191886.891 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:51:26.891Z,1694191886.891 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:51:36.608Z,1694191896.608 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0091.lzma
2023-09-08T16:51:37.611Z,1694191897.611 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0091.lzma.bak
2023-09-08T16:51:37.611Z,1694191897.611 [DataOverHttps](INFO): SBD MOMSN=18751216
2023-09-08T16:51:56.216Z,1694191916.216 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0092.lzma
2023-09-08T16:51:57.227Z,1694191917.227 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0092.lzma.bak
2023-09-08T16:51:57.227Z,1694191917.227 [DataOverHttps](INFO): SBD MOMSN=18751220
2023-09-08T16:52:01.280Z,1694191921.280 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:52:01.280Z,1694191921.280 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:52:01.313Z,1694191921.313 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:52:18.615Z,1694191938.615 [NAL9602](INFO): SBD MO Status=0, MOMSN=3541, MT Status=0, MTMSN=0
2023-09-08T16:52:18.616Z,1694191938.616 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:52:49.015Z,1694191969.015 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T16:57:02.017Z,1694192222.017 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T16:57:02.017Z,1694192222.017 [Default:CheckIn:C.Wait] Stopped
2023-09-08T16:57:02.018Z,1694192222.018 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T16:57:02.018Z,1694192222.018 [Default:CheckIn:D] Running Loop=1
2023-09-08T16:57:02.439Z,1694192222.439 [Default:CheckIn:D] Stopped
2023-09-08T16:57:02.439Z,1694192222.439 [Default:CheckIn:E] Running Loop=1
2023-09-08T16:57:02.819Z,1694192222.819 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.701611 min
2023-09-08T16:57:02.819Z,1694192222.819 [Default:CheckIn:E] Stopped
2023-09-08T16:57:02.819Z,1694192222.819 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T16:57:02.820Z,1694192222.820 [Default:CheckIn] Stopped
2023-09-08T16:57:02.820Z,1694192222.820 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T16:57:02.820Z,1694192222.820 [Default:CheckIn](INFO): Running loop #14
2023-09-08T16:57:02.820Z,1694192222.820 [Default:CheckIn] Running Loop=14
2023-09-08T16:57:02.820Z,1694192222.820 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T16:57:02.820Z,1694192222.820 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T16:57:04.816Z,1694192224.816 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165704.00,A,4145.53102,N,08116.65250,W,0.019,0.00,080923,,,D*7B
2023-09-08T16:57:04.818Z,1694192224.818 [NAL9602](INFO): GPS fix at 20230908T165704: (41.758850, -81.277542)
2023-09-08T16:57:04.835Z,1694192224.835 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T16:57:04.835Z,1694192224.835 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T16:57:17.696Z,1694192237.696 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T141321/Courier0094.lzma
2023-09-08T16:57:18.699Z,1694192238.699 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0094.lzma.bak
2023-09-08T16:57:18.699Z,1694192238.699 [DataOverHttps](INFO): SBD MOMSN=18751229
2023-09-08T16:57:19.032Z,1694192239.032 [NAL9602](INFO): SBD MO Status=0, MOMSN=3542, MT Status=0, MTMSN=0
2023-09-08T16:57:19.032Z,1694192239.032 [NAL9602](INFO): No messages in MT queue
2023-09-08T16:57:37.168Z,1694192257.168 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0095.lzma
2023-09-08T16:57:38.171Z,1694192258.171 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0095.lzma.bak
2023-09-08T16:57:38.171Z,1694192258.171 [DataOverHttps](INFO): SBD MOMSN=18751232
2023-09-08T16:57:42.896Z,1694192262.896 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T16:57:42.896Z,1694192262.896 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T16:57:42.896Z,1694192262.896 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T16:57:49.734Z,1694192269.734 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T17:02:43.548Z,1694192563.548 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T17:02:43.581Z,1694192563.581 [Default:CheckIn:C.Wait] Stopped
2023-09-08T17:02:43.581Z,1694192563.581 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T17:02:43.581Z,1694192563.581 [Default:CheckIn:D] Running Loop=1
2023-09-08T17:02:43.949Z,1694192563.949 [Default:CheckIn:D] Stopped
2023-09-08T17:02:43.949Z,1694192563.949 [Default:CheckIn:E] Running Loop=1
2023-09-08T17:02:44.376Z,1694192564.376 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.393457 min
2023-09-08T17:02:44.385Z,1694192564.385 [Default:CheckIn:E] Stopped
2023-09-08T17:02:44.385Z,1694192564.385 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T17:02:44.385Z,1694192564.385 [Default:CheckIn] Stopped
2023-09-08T17:02:44.385Z,1694192564.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T17:02:44.385Z,1694192564.385 [Default:CheckIn](INFO): Running loop #15
2023-09-08T17:02:44.385Z,1694192564.385 [Default:CheckIn] Running Loop=15
2023-09-08T17:02:44.385Z,1694192564.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T17:02:44.385Z,1694192564.385 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T17:02:46.356Z,1694192566.356 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170245.00,A,4145.53051,N,08116.65192,W,0.000,0.00,080923,,,D*7D
2023-09-08T17:02:46.358Z,1694192566.358 [NAL9602](INFO): GPS fix at 20230908T170245: (41.758842, -81.277532)
2023-09-08T17:02:46.389Z,1694192566.389 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T17:02:46.389Z,1694192566.389 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T17:02:55.396Z,1694192575.396 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T141321/Courier0097.lzma
2023-09-08T17:02:56.399Z,1694192576.399 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0097.lzma.bak
2023-09-08T17:02:56.399Z,1694192576.399 [DataOverHttps](INFO): SBD MOMSN=18751247
2023-09-08T17:03:20.408Z,1694192600.408 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230908T141321/Express0098.lzma
2023-09-08T17:03:21.411Z,1694192601.411 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0098.lzma.bak
2023-09-08T17:03:21.411Z,1694192601.411 [DataOverHttps](INFO): SBD MOMSN=18751250
2023-09-08T17:03:21.975Z,1694192601.975 [NAL9602](INFO): SBD MO Status=2, MOMSN=3543, MT Status=2, MTMSN=0
2023-09-08T17:03:21.976Z,1694192601.976 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T17:03:25.645Z,1694192605.645 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T17:03:25.646Z,1694192605.646 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T17:03:25.646Z,1694192605.646 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T17:03:37.328Z,1694192617.328 [NAL9602](INFO): SBD MO Status=2, MOMSN=3543, MT Status=2, MTMSN=0
2023-09-08T17:03:37.328Z,1694192617.328 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T17:04:07.219Z,1694192647.219 [NAL9602](INFO): SBD MO Status=0, MOMSN=3543, MT Status=0, MTMSN=0
2023-09-08T17:04:07.219Z,1694192647.219 [NAL9602](INFO): No messages in MT queue
2023-09-08T17:04:37.922Z,1694192677.922 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T17:08:26.252Z,1694192906.252 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T17:08:26.252Z,1694192906.252 [Default:CheckIn:C.Wait] Stopped
2023-09-08T17:08:26.252Z,1694192906.252 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T17:08:26.265Z,1694192906.265 [Default:CheckIn:D] Running Loop=1
2023-09-08T17:08:26.655Z,1694192906.655 [Default:CheckIn:D] Stopped
2023-09-08T17:08:26.655Z,1694192906.655 [Default:CheckIn:E] Running Loop=1
2023-09-08T17:08:27.064Z,1694192907.064 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.105216 min
2023-09-08T17:08:27.064Z,1694192907.064 [Default:CheckIn:E] Stopped
2023-09-08T17:08:27.064Z,1694192907.064 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T17:08:27.065Z,1694192907.065 [Default:CheckIn] Stopped
2023-09-08T17:08:27.065Z,1694192907.065 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T17:08:27.065Z,1694192907.065 [Default:CheckIn](INFO): Running loop #16
2023-09-08T17:08:27.065Z,1694192907.065 [Default:CheckIn] Running Loop=16
2023-09-08T17:08:27.065Z,1694192907.065 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T17:08:27.065Z,1694192907.065 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T17:08:29.077Z,1694192909.077 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170828.00,A,4145.53028,N,08116.65178,W,0.019,0.00,080923,,,D*7E
2023-09-08T17:08:29.079Z,1694192909.079 [NAL9602](INFO): GPS fix at 20230908T170828: (41.758838, -81.277530)
2023-09-08T17:08:29.090Z,1694192909.090 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T17:08:29.090Z,1694192909.090 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T17:08:40.188Z,1694192920.188 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230908T141321/Courier0100.lzma
2023-09-08T17:08:41.191Z,1694192921.191 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0100.lzma.bak
2023-09-08T17:08:41.191Z,1694192921.191 [DataOverHttps](INFO): SBD MOMSN=18751254
2023-09-08T17:08:59.019Z,1694192939.019 [NAL9602](INFO): SBD MO Status=0, MOMSN=3544, MT Status=0, MTMSN=0
2023-09-08T17:08:59.020Z,1694192939.020 [NAL9602](INFO): No messages in MT queue
2023-09-08T17:09:03.754Z,1694192943.754 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T141321/Express0101.lzma
2023-09-08T17:09:14.788Z,1694192954.788 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T141321/Express0101.lzma
2023-09-08T17:09:15.791Z,1694192955.791 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0101.lzma.bak
2023-09-08T17:09:15.791Z,1694192955.791 [DataOverHttps](INFO): SBD MOMSN=18751257
2023-09-08T17:09:20.137Z,1694192960.137 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T17:09:20.138Z,1694192960.138 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T17:09:20.138Z,1694192960.138 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T17:09:29.786Z,1694192969.786 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T17:14:20.801Z,1694193260.801 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T17:14:20.801Z,1694193260.801 [Default:CheckIn:C.Wait] Stopped
2023-09-08T17:14:20.801Z,1694193260.801 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T17:14:20.801Z,1694193260.801 [Default:CheckIn:D] Running Loop=1
2023-09-08T17:14:21.216Z,1694193261.216 [Default:CheckIn:D] Stopped
2023-09-08T17:14:21.216Z,1694193261.216 [Default:CheckIn:E] Running Loop=1
2023-09-08T17:14:21.608Z,1694193261.608 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.014567 min
2023-09-08T17:14:21.608Z,1694193261.608 [Default:CheckIn:E] Stopped
2023-09-08T17:14:21.608Z,1694193261.608 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T17:14:21.608Z,1694193261.608 [Default:CheckIn] Stopped
2023-09-08T17:14:21.608Z,1694193261.608 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T17:14:21.609Z,1694193261.609 [Default:CheckIn](INFO): Running loop #17
2023-09-08T17:14:21.609Z,1694193261.609 [Default:CheckIn] Running Loop=17
2023-09-08T17:14:21.609Z,1694193261.609 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T17:14:21.609Z,1694193261.609 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T17:14:23.624Z,1694193263.624 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171423.00,A,4145.52974,N,08116.65267,W,0.019,0.00,080923,,,D*74
2023-09-08T17:14:23.626Z,1694193263.626 [NAL9602](INFO): GPS fix at 20230908T171423: (41.758829, -81.277545)
2023-09-08T17:14:23.636Z,1694193263.636 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T17:14:23.637Z,1694193263.637 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T17:14:34.185Z,1694193274.185 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T141321/Courier0103.lzma
2023-09-08T17:14:35.187Z,1694193275.187 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0103.lzma.bak
2023-09-08T17:14:35.187Z,1694193275.187 [DataOverHttps](INFO): SBD MOMSN=18751268
2023-09-08T17:14:44.275Z,1694193284.275 [NAL9602](INFO): SBD MO Status=2, MOMSN=3545, MT Status=2, MTMSN=0
2023-09-08T17:14:44.275Z,1694193284.275 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T17:14:59.685Z,1694193299.685 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0104.lzma
2023-09-08T17:15:00.687Z,1694193300.687 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0104.lzma.bak
2023-09-08T17:15:00.687Z,1694193300.687 [DataOverHttps](INFO): SBD MOMSN=18751274
2023-09-08T17:15:03.675Z,1694193303.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=3545, MT Status=0, MTMSN=0
2023-09-08T17:15:03.675Z,1694193303.675 [NAL9602](INFO): No messages in MT queue
2023-09-08T17:15:08.617Z,1694193308.617 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T17:15:08.617Z,1694193308.617 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T17:15:08.617Z,1694193308.617 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T17:15:34.473Z,1694193334.473 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T17:20:09.219Z,1694193609.219 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T17:20:09.219Z,1694193609.219 [Default:CheckIn:C.Wait] Stopped
2023-09-08T17:20:09.219Z,1694193609.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T17:20:09.220Z,1694193609.220 [Default:CheckIn:D] Running Loop=1
2023-09-08T17:20:09.625Z,1694193609.625 [Default:CheckIn:D] Stopped
2023-09-08T17:20:09.625Z,1694193609.625 [Default:CheckIn:E] Running Loop=1
2023-09-08T17:20:10.037Z,1694193610.037 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.821379 min
2023-09-08T17:20:10.037Z,1694193610.037 [Default:CheckIn:E] Stopped
2023-09-08T17:20:10.037Z,1694193610.037 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T17:20:10.038Z,1694193610.038 [Default:CheckIn] Stopped
2023-09-08T17:20:10.038Z,1694193610.038 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T17:20:10.038Z,1694193610.038 [Default:CheckIn](INFO): Running loop #18
2023-09-08T17:20:10.038Z,1694193610.038 [Default:CheckIn] Running Loop=18
2023-09-08T17:20:10.038Z,1694193610.038 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T17:20:10.038Z,1694193610.038 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T17:20:12.024Z,1694193612.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172011.00,A,4145.53073,N,08116.65238,W,0.039,31.44,080923,,,D*47
2023-09-08T17:20:12.026Z,1694193612.026 [NAL9602](INFO): GPS fix at 20230908T172011: (41.758845, -81.277540)
2023-09-08T17:20:12.066Z,1694193612.066 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T17:20:12.066Z,1694193612.066 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T17:20:14.699Z,1694193614.699 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230908T141321/Courier0106.lzma
2023-09-08T17:20:15.711Z,1694193615.711 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Courier0106.lzma.bak
2023-09-08T17:20:15.711Z,1694193615.711 [DataOverHttps](INFO): SBD MOMSN=18751284
2023-09-08T17:20:28.588Z,1694193628.588 [NAL9602](INFO): SBD MO Status=0, MOMSN=3546, MT Status=0, MTMSN=0
2023-09-08T17:20:28.588Z,1694193628.588 [NAL9602](INFO): No messages in MT queue
2023-09-08T17:20:38.681Z,1694193638.681 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230908T141321/Express0107.lzma
2023-09-08T17:20:39.651Z,1694193639.651 [DataOverHttps](INFO): Moved sent file to Logs/20230908T141321/Express0107.lzma.bak
2023-09-08T17:20:39.651Z,1694193639.651 [DataOverHttps](INFO): SBD MOMSN=18751287
2023-09-08T17:20:44.864Z,1694193644.864 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T17:20:44.869Z,1694193644.869 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T17:20:44.869Z,1694193644.869 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T17:20:59.372Z,1694193659.372 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T17:24:00.534Z,1694193840.534 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T172359