2023-09-09T23:19:41.394Z,1694301581.394 [Supervisor](DEBUG): Initializing supervisor.
2023-09-09T23:19:41.398Z,1694301581.398 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-09T23:19:41.399Z,1694301581.399 [SyncHandler](INFO): Protected caller Thread ID is 837
2023-09-09T23:19:41.399Z,1694301581.399 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-09T23:19:41.400Z,1694301581.400 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-09T23:19:41.400Z,1694301581.400 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2023-09-09T23:19:41.404Z,1694301581.404 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-09T23:19:41.422Z,1694301581.422 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-09T23:19:41.422Z,1694301581.422 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-09T23:19:41.423Z,1694301581.423 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2023-09-09T23:19:41.427Z,1694301581.427 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-09T23:19:41.428Z,1694301581.428 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-09T23:19:41.428Z,1694301581.428 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2023-09-09T23:19:41.430Z,1694301581.430 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-09T23:19:41.431Z,1694301581.431 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-09T23:19:41.432Z,1694301581.432 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2023-09-09T23:19:41.435Z,1694301581.435 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-09T23:19:41.436Z,1694301581.436 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-09T23:19:41.440Z,1694301581.440 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-09T23:19:41.877Z,1694301581.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-09T23:19:41.879Z,1694301581.879 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-09T23:19:42.127Z,1694301582.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-09T23:19:42.128Z,1694301582.128 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-09T23:19:42.209Z,1694301582.209 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-09T23:19:42.422Z,1694301582.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-09T23:19:42.423Z,1694301582.423 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-09T23:19:42.502Z,1694301582.502 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-09T23:19:42.598Z,1694301582.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-09T23:19:42.600Z,1694301582.600 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-09T23:19:42.947Z,1694301582.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-09T23:19:42.948Z,1694301582.948 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-09T23:19:43.077Z,1694301583.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-09T23:19:43.078Z,1694301583.078 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-09T23:19:43.553Z,1694301583.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-09T23:19:43.554Z,1694301583.554 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-09T23:19:43.743Z,1694301583.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-09T23:19:43.745Z,1694301583.745 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-09T23:19:44.167Z,1694301584.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-09T23:19:44.169Z,1694301584.169 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-09T23:19:44.455Z,1694301584.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-09T23:19:44.457Z,1694301584.457 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-09T23:19:44.566Z,1694301584.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-09T23:19:44.568Z,1694301584.568 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-09T23:19:45.677Z,1694301585.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-09T23:19:45.677Z,1694301585.677 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-09T23:19:45.999Z,1694301585.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-09T23:19:46.001Z,1694301586.001 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-09T23:19:46.228Z,1694301586.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-09T23:19:46.230Z,1694301586.230 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-09-09T23:19:46.232Z,1694301586.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-09-09T23:19:46.353Z,1694301586.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-09-09T23:19:46.436Z,1694301586.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-09-09T23:19:46.569Z,1694301586.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-09-09T23:19:46.653Z,1694301586.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-09-09T23:19:46.914Z,1694301586.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-09T23:19:46.915Z,1694301586.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-09-09T23:19:47.017Z,1694301587.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-09-09T23:19:47.123Z,1694301587.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-09-09T23:19:47.239Z,1694301587.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-09-09T23:19:47.383Z,1694301587.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-09-09T23:19:47.481Z,1694301587.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-09-09T23:19:47.577Z,1694301587.577 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-09T23:19:47.596Z,1694301587.596 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-09T23:19:47.612Z,1694301587.612 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-09T23:19:47.614Z,1694301587.614 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-09T23:19:47.739Z,1694301587.739 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-09T23:19:47.740Z,1694301587.740 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-09T23:19:47.809Z,1694301587.809 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-09T23:19:47.868Z,1694301587.868 [VerticalControl] Loaded
2023-09-09T23:19:47.869Z,1694301587.869 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-09T23:19:47.871Z,1694301587.871 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-09T23:19:47.912Z,1694301587.912 [HorizontalControl] Loaded
2023-09-09T23:19:47.913Z,1694301587.913 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-09T23:19:47.915Z,1694301587.915 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-09T23:19:47.918Z,1694301587.918 [SpeedControl] Loaded
2023-09-09T23:19:47.918Z,1694301587.918 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-09T23:19:47.921Z,1694301587.921 [LoopControl](DEBUG): Construct LoopControl.
2023-09-09T23:19:47.922Z,1694301587.922 [LoopControl] Loaded
2023-09-09T23:19:47.922Z,1694301587.922 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-09T23:19:47.922Z,1694301587.922 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-09T23:19:47.923Z,1694301587.923 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-09T23:19:48.105Z,1694301588.105 [BuoyancyServo] Loaded
2023-09-09T23:19:48.105Z,1694301588.105 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-09T23:19:48.125Z,1694301588.125 [ElevatorServo] Loaded
2023-09-09T23:19:48.125Z,1694301588.125 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-09T23:19:48.144Z,1694301588.144 [MassServo] Loaded
2023-09-09T23:19:48.144Z,1694301588.144 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-09T23:19:48.162Z,1694301588.162 [RudderServo] Loaded
2023-09-09T23:19:48.163Z,1694301588.163 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-09T23:19:48.177Z,1694301588.177 [ThrusterHE] Loaded
2023-09-09T23:19:48.177Z,1694301588.177 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-09T23:19:48.178Z,1694301588.178 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-09T23:19:48.178Z,1694301588.178 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-09T23:19:48.248Z,1694301588.248 [DepthRateCalculator] Loaded
2023-09-09T23:19:48.248Z,1694301588.248 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-09T23:19:48.253Z,1694301588.253 [PitchRateCalculator] Loaded
2023-09-09T23:19:48.253Z,1694301588.253 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-09T23:19:48.263Z,1694301588.263 [SpeedCalculator] Loaded
2023-09-09T23:19:48.263Z,1694301588.263 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-09T23:19:48.267Z,1694301588.267 [YawRateCalculator] Loaded
2023-09-09T23:19:48.268Z,1694301588.268 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-09T23:19:48.286Z,1694301588.286 [ElevatorOffsetCalculator] Loaded
2023-09-09T23:19:48.286Z,1694301588.286 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-09T23:19:48.287Z,1694301588.287 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-09T23:19:48.287Z,1694301588.287 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-09T23:19:48.426Z,1694301588.426 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-09-09T23:19:48.426Z,1694301588.426 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-09-09T23:19:48.440Z,1694301588.440 [NavChart] Loaded
2023-09-09T23:19:48.440Z,1694301588.440 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-09T23:19:48.446Z,1694301588.446 [UniversalFixResidualReporter] Loaded
2023-09-09T23:19:48.446Z,1694301588.446 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-09T23:19:48.446Z,1694301588.446 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-09T23:19:48.447Z,1694301588.447 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-09T23:19:49.518Z,1694301589.518 [AHRS_M2] Loaded
2023-09-09T23:19:49.518Z,1694301589.518 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-09T23:19:49.768Z,1694301589.768 [BackseatComponent] Loaded
2023-09-09T23:19:49.768Z,1694301589.768 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-09T23:19:49.770Z,1694301589.770 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E84E0
2023-09-09T23:19:49.770Z,1694301589.770 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924
2023-09-09T23:19:49.773Z,1694301589.773 [LcmUniversalReporter] Loaded
2023-09-09T23:19:49.773Z,1694301589.773 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-09T23:19:49.779Z,1694301589.779 [BioacousticsDataBridge] Loaded
2023-09-09T23:19:49.780Z,1694301589.780 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2023-09-09T23:19:50.607Z,1694301590.607 [BPC1] Loaded
2023-09-09T23:19:50.607Z,1694301590.607 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-09T23:19:50.751Z,1694301590.751 [DataOverHttps] Loaded
2023-09-09T23:19:50.751Z,1694301590.751 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-09T23:19:50.752Z,1694301590.752 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409184E0
2023-09-09T23:19:50.752Z,1694301590.752 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2023-09-09T23:19:50.773Z,1694301590.773 [Depth_Keller] Loaded
2023-09-09T23:19:50.773Z,1694301590.773 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-09T23:19:50.778Z,1694301590.778 [DropWeight] Loaded
2023-09-09T23:19:50.779Z,1694301590.779 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-09-09T23:19:50.840Z,1694301590.840 [NAL9602] Loaded
2023-09-09T23:19:50.841Z,1694301590.841 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-09T23:19:50.870Z,1694301590.870 [Onboard] Loaded
2023-09-09T23:19:50.871Z,1694301590.871 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-09T23:19:50.872Z,1694301590.872 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409484E0
2023-09-09T23:19:50.872Z,1694301590.872 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2023-09-09T23:19:50.884Z,1694301590.884 [Power24vConverter] Loaded
2023-09-09T23:19:50.884Z,1694301590.884 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-09T23:19:50.898Z,1694301590.898 [Radio_Surface] Loaded
2023-09-09T23:19:50.898Z,1694301590.898 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-09T23:19:50.899Z,1694301590.899 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409784E0
2023-09-09T23:19:50.899Z,1694301590.899 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2023-09-09T23:19:50.913Z,1694301590.913 [Sonardyne_Nano] Loaded
2023-09-09T23:19:50.913Z,1694301590.913 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-09-09T23:19:50.914Z,1694301590.914 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-09T23:19:50.914Z,1694301590.914 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-09T23:19:51.589Z,1694301591.589 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-09T23:19:51.590Z,1694301591.590 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-09T23:19:52.022Z,1694301592.022 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-09T23:19:52.023Z,1694301592.023 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-09T23:19:52.066Z,1694301592.066 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-09T23:19:52.066Z,1694301592.066 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-09T23:19:52.284Z,1694301592.284 [CTD_Seabird] Loaded
2023-09-09T23:19:52.285Z,1694301592.285 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-09T23:19:52.286Z,1694301592.286 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2023-09-09T23:19:52.286Z,1694301592.286 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2023-09-09T23:19:52.306Z,1694301592.306 [PAR_Licor] Loaded
2023-09-09T23:19:52.306Z,1694301592.306 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-09T23:19:52.347Z,1694301592.347 [WetLabsBB2FL] Loaded
2023-09-09T23:19:52.348Z,1694301592.348 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-09T23:19:52.348Z,1694301592.348 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2023-09-09T23:19:52.349Z,1694301592.349 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2023-09-09T23:19:52.350Z,1694301592.350 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-09T23:19:52.350Z,1694301592.350 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-09T23:19:52.721Z,1694301592.721 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-09T23:19:52.722Z,1694301592.722 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-09T23:19:52.887Z,1694301592.887 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-09T23:19:52.896Z,1694301592.896 [SBIT] Loaded
2023-09-09T23:19:52.897Z,1694301592.897 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-09T23:19:52.900Z,1694301592.900 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-09T23:19:52.913Z,1694301592.913 [IBIT] Loaded
2023-09-09T23:19:52.914Z,1694301592.914 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-09T23:19:52.919Z,1694301592.919 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-09T23:19:53.023Z,1694301593.023 [CBIT] Loaded
2023-09-09T23:19:53.024Z,1694301593.024 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-09T23:19:53.024Z,1694301593.024 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-09T23:19:53.030Z,1694301593.030 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-09T23:19:53.034Z,1694301593.034 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-09T23:19:53.045Z,1694301593.045 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-09T23:19:53.046Z,1694301593.046 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-09-09T23:19:53.046Z,1694301593.046 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2023-09-09T23:19:53.050Z,1694301593.050 [Supervisor](INFO): Main Thread ID is 830
2023-09-09T23:19:53.050Z,1694301593.050 [Supervisor](DEBUG): Running supervisor.
2023-09-09T23:19:53.051Z,1694301593.051 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2023-09-09T23:19:53.052Z,1694301593.052 [CommandExec](INFO): Initializing the command executive.
2023-09-09T23:19:53.053Z,1694301593.053 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2023-09-09T23:19:53.055Z,1694301593.055 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2023-09-09T23:19:53.056Z,1694301593.056 [controlThread](DEBUG): Initializing ControlThread
2023-09-09T23:19:53.056Z,1694301593.056 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-09T23:19:53.058Z,1694301593.058 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-09T23:19:53.059Z,1694301593.059 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-09T23:19:53.059Z,1694301593.059 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-09T23:19:53.061Z,1694301593.061 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-09T23:19:53.061Z,1694301593.061 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-09T23:19:53.062Z,1694301593.062 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-09T23:19:53.062Z,1694301593.062 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-09T23:19:53.062Z,1694301593.062 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-09T23:19:53.064Z,1694301593.064 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-09T23:19:53.064Z,1694301593.064 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-09T23:19:53.073Z,1694301593.073 [SBIT](INFO): Initialize SBIT Component.
2023-09-09T23:19:53.074Z,1694301593.074 [SBIT](IMPORTANT): git: 2023-09-07
2023-09-09T23:19:53.074Z,1694301593.074 [SBIT](INFO): git hash: de73b65bba9e058a7fe4bf2bc7b950ee8b764c06
2023-09-09T23:19:53.074Z,1694301593.074 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-09T23:19:53.075Z,1694301593.075 [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-09T23:19:53.077Z,1694301593.077 [SBIT](INFO): Beginning SBIT in 114.000000 seconds.
2023-09-09T23:19:53.077Z,1694301593.077 [IBIT](INFO): Initialize IBIT Component.
2023-09-09T23:19:53.078Z,1694301593.078 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-09T23:19:53.079Z,1694301593.079 [logger ThreadHandler](INFO): Handler Thread ID is 934
2023-09-09T23:19:53.089Z,1694301593.089 [CBIT](DEBUG): Initialized mux pins.
2023-09-09T23:19:53.089Z,1694301593.089 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-09T23:19:53.097Z,1694301593.097 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2023-09-09T23:19:53.109Z,1694301593.109 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936
2023-09-09T23:19:53.110Z,1694301593.110 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-09T23:19:53.113Z,1694301593.113 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-09T23:19:53.114Z,1694301593.114 [CBIT](DEBUG): Initializing heartbeat.
2023-09-09T23:19:53.121Z,1694301593.121 [Onboard ThreadHandler](INFO): Handler Thread ID is 937
2023-09-09T23:19:53.139Z,1694301593.139 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938
2023-09-09T23:19:53.157Z,1694301593.158 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2023-09-09T23:19:53.158Z,1694301593.158 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-09T23:19:53.163Z,1694301593.163 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2023-09-09T23:19:53.165Z,1694301593.165 [WetLabsBB2FL](INFO): Powering up
2023-09-09T23:19:53.167Z,1694301593.167 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2023-09-09T23:19:53.176Z,1694301593.176 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-09T23:19:53.177Z,1694301593.177 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-09-09T23:19:53.177Z,1694301593.177 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-09T23:19:53.177Z,1694301593.177 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-09-09T23:19:53.177Z,1694301593.177 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-09T23:19:53.178Z,1694301593.178 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-09-09T23:19:53.178Z,1694301593.178 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-09T23:19:53.178Z,1694301593.178 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-09-09T23:19:53.178Z,1694301593.178 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-09T23:19:53.178Z,1694301593.178 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-09-09T23:19:53.178Z,1694301593.178 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-09T23:19:53.179Z,1694301593.179 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-09-09T23:19:53.179Z,1694301593.179 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-09T23:19:53.179Z,1694301593.179 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000
2023-09-09T23:19:53.179Z,1694301593.179 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-09T23:19:53.179Z,1694301593.179 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000
2023-09-09T23:19:53.179Z,1694301593.179 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-09T23:19:53.179Z,1694301593.179 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-09-09T23:19:53.180Z,1694301593.180 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-09T23:19:53.180Z,1694301593.180 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-09-09T23:19:53.180Z,1694301593.180 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-09T23:19:53.180Z,1694301593.180 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-09-09T23:19:53.180Z,1694301593.180 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-09T23:19:53.180Z,1694301593.180 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-09-09T23:19:53.181Z,1694301593.181 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-09T23:19:53.181Z,1694301593.181 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-09-09T23:19:53.185Z,1694301593.185 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-09T23:19:53.185Z,1694301593.185 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-09T23:19:53.225Z,1694301593.225 [CBIT](DEBUG): Backplane powered.
2023-09-09T23:19:53.226Z,1694301593.226 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-09T23:19:53.262Z,1694301593.262 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-09T23:19:53.272Z,1694301593.272 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-09-09T23:19:53.273Z,1694301593.273 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-09T23:19:53.351Z,1694301593.351 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-09T23:19:53.352Z,1694301593.352 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-09T23:19:53.363Z,1694301593.363 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-09T23:19:53.390Z,1694301593.390 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-09T23:19:53.417Z,1694301593.417 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-09T23:19:53.423Z,1694301593.423 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-09T23:19:53.442Z,1694301593.442 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-09-09T23:19:53.447Z,1694301593.447 [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-09T23:19:53.466Z,1694301593.466 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-09T23:19:53.545Z,1694301593.545 [Radio_Surface](INFO): Powering up
2023-09-09T23:19:53.654Z,1694301593.654 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1926.123779 decibar, 0.000000 m
2023-09-09T23:19:53.655Z,1694301593.655 [Power24vConverter](INFO): Powering up.
2023-09-09T23:19:53.656Z,1694301593.656 [Sonardyne_Nano](INFO): Initializing.
2023-09-09T23:19:53.713Z,1694301593.713 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-09-09T23:19:53.741Z,1694301593.741 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-09T23:19:53.749Z,1694301593.749 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-09T23:19:53.750Z,1694301593.750 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-09T23:19:53.757Z,1694301593.757 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-09T23:19:53.758Z,1694301593.758 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-09T23:19:53.765Z,1694301593.765 [MassServo](DEBUG): Initializing MassServo.
2023-09-09T23:19:53.766Z,1694301593.766 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-09T23:19:53.773Z,1694301593.773 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-09T23:19:53.774Z,1694301593.774 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-09T23:19:53.781Z,1694301593.781 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-09T23:19:55.273Z,1694301595.273 [WetLabsBB2FL](INFO): Powering down
2023-09-09T23:19:55.880Z,1694301595.880 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-09-09T23:19:57.118Z,1694301597.118 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2201
2023-09-09T23:19:59.370Z,1694301599.370 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-09T23:20:00.189Z,1694301600.189 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-09-09T23:20:20.931Z,1694301620.931 [NAL9602](INFO): Powering up NAL9602
2023-09-09T23:20:31.851Z,1694301631.851 [NAL9602](INFO): NAL9602 initialized
2023-09-09T23:20:47.600Z,1694301647.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=3883, MT Status=2, MTMSN=0
2023-09-09T23:20:47.600Z,1694301647.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-09T23:21:47.812Z,1694301707.812 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-09T23:21:47.816Z,1694301707.816 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-09T23:21:58.736Z,1694301718.736 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006388
CHAN A1 (24V): -0.006795
CHAN A2 (12V): -0.004752
CHAN A3 (5V): -0.002702
CHAN B0 (3.3V): -0.000702
CHAN B1 (3.15aV): -0.001438
CHAN B2 (3.15bV): -0.001338
CHAN B3 (GND): -0.000147
OPEN: -0.004534
Full Scale: +/- 1 mA
2023-09-09T23:22:00.320Z,1694301720.320 [NAL9602](INFO): SBD MO Status=2, MOMSN=3883, MT Status=2, MTMSN=0
2023-09-09T23:22:00.320Z,1694301720.320 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-09T23:22:24.560Z,1694301744.560 [NAL9602](INFO): SBD MO Status=0, MOMSN=3883, MT Status=0, MTMSN=0
2023-09-09T23:22:24.560Z,1694301744.560 [NAL9602](INFO): No messages in MT queue
2023-09-09T23:22:25.774Z,1694301745.774 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232224.00,A,4144.75905,N,08116.63531,W,0.253,0.00,090923,,,A*79
2023-09-09T23:22:25.798Z,1694301745.798 [NAL9602](INFO): GPS fix at 20230909T232224: (41.745984, -81.277255)
2023-09-09T23:22:41.957Z,1694301761.957 [SBIT](IMPORTANT): SBIT PASSED
2023-09-09T23:22:41.957Z,1694301761.957 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-09T23:22:41.958Z,1694301761.958 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-09-09T23:22:41.958Z,1694301761.958 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-09-09T23:22:41.958Z,1694301761.958 [SBIT](IMPORTANT): Express linearApproximation BackseatComponent.handled_message 1.000000 bool;

2023-09-09T23:22:41.959Z,1694301761.959 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius;

2023-09-09T23:22:41.959Z,1694301761.959 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-09-09T23:22:41.959Z,1694301761.959 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-09-09T23:22:41.959Z,1694301761.959 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter;

2023-09-09T23:22:41.959Z,1694301761.959 [SBIT](IMPORTANT): Express none platform_roll_angle;

2023-09-09T23:22:41.959Z,1694301761.959 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2023-09-09T23:22:41.959Z,1694301761.959 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=5 minute;

2023-09-09T23:22:41.960Z,1694301761.960 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-09-09T23:22:41.960Z,1694301761.960 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=485.304848 cubic_centimeter;

2023-09-09T23:22:41.960Z,1694301761.960 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-09-09T23:22:41.960Z,1694301761.960 [SBIT](IMPORTANT): VerticalControl.massDefault=23.563789 millimeter;

2023-09-09T23:22:42.353Z,1694301762.353 [MissionManager](IMPORTANT): Started mission Startup
2023-09-09T23:22:42.354Z,1694301762.354 [Startup] Running Loop=1
2023-09-09T23:22:42.354Z,1694301762.354 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-09T23:22:42.354Z,1694301762.354 [Startup:A.GoToSurface] Running Loop=1
2023-09-09T23:22:42.354Z,1694301762.354 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-09T23:22:42.355Z,1694301762.355 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-09T23:22:42.355Z,1694301762.355 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-09T23:22:42.355Z,1694301762.355 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-09T23:22:42.356Z,1694301762.356 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-09T23:22:42.356Z,1694301762.356 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-09T23:22:42.357Z,1694301762.357 [Startup:StartupSatComms] Running Loop=1
2023-09-09T23:22:42.357Z,1694301762.357 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-09T23:22:42.358Z,1694301762.358 [Startup:StartupSatComms:A] Running Loop=1
2023-09-09T23:22:42.751Z,1694301762.751 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-09T23:22:44.364Z,1694301764.364 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232242.00,A,4144.75836,N,08116.63708,W,0.175,0.00,090923,,,A*77
2023-09-09T23:22:44.366Z,1694301764.366 [NAL9602](INFO): GPS fix at 20230909T232242: (41.745973, -81.277285)
2023-09-09T23:22:44.377Z,1694301764.377 [Startup:StartupSatComms:A] Stopped
2023-09-09T23:22:44.377Z,1694301764.377 [Startup:StartupSatComms:B] Running Loop=1
2023-09-09T23:22:44.799Z,1694301764.799 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-09T23:23:14.508Z,1694301794.508 [NAL9602](INFO): SBD MO Status=2, MOMSN=3884, MT Status=2, MTMSN=0
2023-09-09T23:23:14.508Z,1694301794.508 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-09T23:23:20.387Z,1694301800.387 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004364
2023-09-09T23:23:29.065Z,1694301809.065 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230909T165057/Courier0207.lzma
2023-09-09T23:23:30.067Z,1694301810.067 [DataOverHttps](INFO): Moved sent file to Logs/20230909T165057/Courier0207.lzma.bak
2023-09-09T23:23:30.067Z,1694301810.067 [DataOverHttps](INFO): SBD MOMSN=18756235
2023-09-09T23:23:44.393Z,1694301824.393 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-09T23:22:44.4Z
2023-09-09T23:23:44.393Z,1694301824.393 [Startup:StartupSatComms:B] Stopped
2023-09-09T23:23:44.394Z,1694301824.394 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-09T23:23:44.394Z,1694301824.394 [Startup:StartupSatComms] Stopped
2023-09-09T23:23:44.394Z,1694301824.394 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-09T23:23:44.394Z,1694301824.394 [Startup](INFO): Completed Startup
2023-09-09T23:23:44.395Z,1694301824.395 [MissionManager](INFO): Startup is completed.
2023-09-09T23:23:44.395Z,1694301824.395 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-09T23:23:44.395Z,1694301824.395 [Startup] Stopped
2023-09-09T23:23:44.395Z,1694301824.395 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-09T23:23:44.395Z,1694301824.395 [Startup:A.GoToSurface] Stopped
2023-09-09T23:23:44.395Z,1694301824.395 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-09T23:23:44.829Z,1694301824.829 [MissionManager](IMPORTANT): Started mission Default
2023-09-09T23:23:44.829Z,1694301824.829 [Default] Running Loop=1
2023-09-09T23:23:44.829Z,1694301824.829 [Default](DEBUG): Aggregate::initialize Default
2023-09-09T23:23:44.829Z,1694301824.829 [Default:B.GoToSurface] Running Loop=1
2023-09-09T23:23:44.830Z,1694301824.830 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-09T23:23:44.830Z,1694301824.830 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-09T23:23:44.830Z,1694301824.830 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-09T23:23:44.830Z,1694301824.830 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-09T23:23:44.831Z,1694301824.831 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-09T23:23:44.831Z,1694301824.831 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-09T23:23:44.831Z,1694301824.831 [Default:A.Wait] Running Loop=1
2023-09-09T23:23:44.831Z,1694301824.831 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:23:49.169Z,1694301829.169 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20230909T231941/Courier0000.lzma
2023-09-09T23:23:50.171Z,1694301830.171 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0000.lzma.bak
2023-09-09T23:23:50.171Z,1694301830.171 [DataOverHttps](INFO): SBD MOMSN=18756237
2023-09-09T23:23:50.848Z,1694301830.848 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-09T23:23:58.142Z,1694301838.142 [Default:A.Wait](INFO): Done Waiting.
2023-09-09T23:23:58.142Z,1694301838.142 [Default:A.Wait] Stopped
2023-09-09T23:23:58.142Z,1694301838.142 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-09T23:23:58.538Z,1694301838.538 [Default:CheckIn] Running Loop=1
2023-09-09T23:23:58.538Z,1694301838.538 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-09T23:23:58.538Z,1694301838.538 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-09T23:23:58.941Z,1694301838.941 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-09T23:24:00.548Z,1694301840.548 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232358.00,A,4144.75881,N,08116.63729,W,0.097,0.00,090923,,,A*7F
2023-09-09T23:24:00.550Z,1694301840.550 [NAL9602](INFO): GPS fix at 20230909T232358: (41.745980, -81.277288)
2023-09-09T23:24:00.561Z,1694301840.561 [Default:CheckIn:Read_GPS] Stopped
2023-09-09T23:24:00.561Z,1694301840.561 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-09T23:24:00.986Z,1694301840.986 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-09T23:24:11.197Z,1694301851.197 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230909T231941/Courier0004.lzma
2023-09-09T23:24:12.199Z,1694301852.199 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0004.lzma.bak
2023-09-09T23:24:12.199Z,1694301852.199 [DataOverHttps](INFO): SBD MOMSN=18756241
2023-09-09T23:24:34.886Z,1694301874.886 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230909T165057/Express0208.lzma
2023-09-09T23:24:35.887Z,1694301875.887 [DataOverHttps](INFO): Moved sent file to Logs/20230909T165057/Express0208.lzma.bak
2023-09-09T23:24:35.887Z,1694301875.887 [DataOverHttps](INFO): SBD MOMSN=18756244
2023-09-09T23:24:54.674Z,1694301894.674 [DataOverHttps](INFO): Sending 1119 bytes from file Logs/20230909T231941/Express0001.lzma
2023-09-09T23:24:55.675Z,1694301895.675 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0001.lzma.bak
2023-09-09T23:24:55.675Z,1694301895.675 [DataOverHttps](INFO): SBD MOMSN=18756252
2023-09-09T23:25:17.568Z,1694301917.568 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20230909T231941/Express0005.lzma
2023-09-09T23:25:18.571Z,1694301918.571 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0005.lzma.bak
2023-09-09T23:25:18.571Z,1694301918.571 [DataOverHttps](INFO): SBD MOMSN=18756278
2023-09-09T23:25:22.280Z,1694301922.280 [Default:CheckIn:Read_Iridium] Stopped
2023-09-09T23:25:22.280Z,1694301922.280 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-09T23:25:22.280Z,1694301922.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:25:35.172Z,1694301935.172 [NAL9602](INFO): SBD MO Status=2, MOMSN=3884, MT Status=2, MTMSN=0
2023-09-09T23:25:35.172Z,1694301935.172 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-09T23:28:00.411Z,1694302080.411 [CommandExec](IMPORTANT): got command failComponent
2023-09-09T23:28:00.411Z,1694302080.411 [CommandExec](IMPORTANT): Failed components:
2023-09-09T23:28:00.412Z,1694302080.412 [CommandExec](IMPORTANT): No failed Components.
2023-09-09T23:28:15.018Z,1694302095.018 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-09T23:28:15.018Z,1694302095.018 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-09T23:28:15.018Z,1694302095.018 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/run_backseat_on_surface.tx
2023-09-09T23:28:15.079Z,1694302095.079 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min
2023-09-09T23:28:15.080Z,1694302095.080 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2023-09-09T23:28:15.080Z,1694302095.080 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2023-09-09T23:28:15.438Z,1694302095.438 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectChlActive = 0 bool
2023-09-09T23:28:15.440Z,1694302095.440 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.TimeWindowPeakReport = nan min
2023-09-09T23:28:15.443Z,1694302095.443 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestChlPeakReportActive = 0 bool
2023-09-09T23:28:15.447Z,1694302095.447 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestSaltPeakReportActive = 0 bool
2023-09-09T23:28:15.474Z,1694302095.474 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestOilPeakReportActive = 0 bool
2023-09-09T23:28:15.476Z,1694302095.476 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PatchTracking = 0 bool
2023-09-09T23:28:15.479Z,1694302095.479 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DetectTrough = 0 bool
2023-09-09T23:28:15.498Z,1694302095.498 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.FilterWidthHorizontal = 3.000000 count
2023-09-09T23:28:15.501Z,1694302095.501 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesSlidingwindow = 100.000000 count
2023-09-09T23:28:15.516Z,1694302095.516 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionHorizontal = 80.000000 %
2023-09-09T23:28:15.518Z,1694302095.518 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectNO3Active = 0 bool
2023-09-09T23:28:15.541Z,1694302095.541 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectOilActive = 0 bool
2023-09-09T23:28:15.544Z,1694302095.544 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectFDOMActive = 0 bool
2023-09-09T23:28:15.546Z,1694302095.546 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectSalinityActive = 0 bool
2023-09-09T23:28:15.549Z,1694302095.549 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-09-09T23:28:15.552Z,1694302095.552 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledAanderaaO2 = 0 bool
2023-09-09T23:28:15.575Z,1694302095.575 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledNeilBrown = 0 bool
2023-09-09T23:28:15.579Z,1694302095.579 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledSeabird = 1 bool
2023-09-09T23:28:15.615Z,1694302095.615 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsBB2FL = 1 bool
2023-09-09T23:28:15.619Z,1694302095.619 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-09-09T23:28:15.634Z,1694302095.634 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsUBAT = 0 bool
2023-09-09T23:28:15.637Z,1694302095.637 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.LowPassWindowLength = 20.000000 count
2023-09-09T23:28:15.640Z,1694302095.640 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.MedianFilterLen = 5.000000 count
2023-09-09T23:28:15.655Z,1694302095.655 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakShallowBound = nan m
2023-09-09T23:28:15.658Z,1694302095.658 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDeepBound = nan m
2023-09-09T23:28:15.681Z,1694302095.681 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-09-09T23:28:15.684Z,1694302095.684 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlThreshTriggerActive = 0 bool
2023-09-09T23:28:15.705Z,1694302095.705 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlLowerThresh = nan ug/l
2023-09-09T23:28:15.708Z,1694302095.708 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlUpperThresh = nan ug/l
2023-09-09T23:28:15.719Z,1694302095.719 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesThresh = 5.000000 count
2023-09-09T23:28:15.735Z,1694302095.735 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChl = nan ug/l
2023-09-09T23:28:15.746Z,1694302095.746 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlDepth = nan m
2023-09-09T23:28:15.765Z,1694302095.765 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlTemperature = nan degC
2023-09-09T23:28:15.768Z,1694302095.768 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLatitude = nan arcdeg
2023-09-09T23:28:15.785Z,1694302095.785 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLongitude = nan arcdeg
2023-09-09T23:28:15.788Z,1694302095.788 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3 = nan umol/l
2023-09-09T23:28:15.791Z,1694302095.791 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Depth = nan m
2023-09-09T23:28:15.807Z,1694302095.807 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Latitude = nan arcdeg
2023-09-09T23:28:15.812Z,1694302095.812 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Longitude = nan arcdeg
2023-09-09T23:28:15.823Z,1694302095.823 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOil = nan kg/m3
2023-09-09T23:28:15.835Z,1694302095.835 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilDepth = nan m
2023-09-09T23:28:15.847Z,1694302095.847 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLatitude = nan arcdeg
2023-09-09T23:28:15.862Z,1694302095.862 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLongitude = nan arcdeg
2023-09-09T23:28:15.865Z,1694302095.865 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOM = nan ppb
2023-09-09T23:28:15.872Z,1694302095.872 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMDepth = nan m
2023-09-09T23:28:15.875Z,1694302095.875 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMTemperature = nan degC
2023-09-09T23:28:15.895Z,1694302095.895 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLatitude = nan arcdeg
2023-09-09T23:28:15.913Z,1694302095.913 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLongitude = nan arcdeg
2023-09-09T23:28:15.916Z,1694302095.916 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchFDOMDistance = nan m
2023-09-09T23:28:15.923Z,1694302095.923 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSalt = nan psu
2023-09-09T23:28:15.931Z,1694302095.931 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltDepth = nan m
2023-09-09T23:28:15.934Z,1694302095.934 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltTemperature = nan degC
2023-09-09T23:28:15.953Z,1694302095.953 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLatitude = nan arcdeg
2023-09-09T23:28:15.956Z,1694302095.956 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLongitude = nan arcdeg
2023-09-09T23:28:15.969Z,1694302095.969 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChl = nan ug/l
2023-09-09T23:28:15.972Z,1694302095.972 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDepth = nan m
2023-09-09T23:28:15.979Z,1694302095.979 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLatitude = nan arcdeg
2023-09-09T23:28:16.002Z,1694302096.002 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLongitude = nan arcdeg
2023-09-09T23:28:16.005Z,1694302096.005 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDistance = nan m
2023-09-09T23:28:16.020Z,1694302096.020 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSalt = nan psu
2023-09-09T23:28:16.024Z,1694302096.024 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDepth = nan m
2023-09-09T23:28:16.036Z,1694302096.036 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLatitude = nan arcdeg
2023-09-09T23:28:16.047Z,1694302096.047 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLongitude = nan arcdeg
2023-09-09T23:28:16.052Z,1694302096.052 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDistance = nan m
2023-09-09T23:28:16.064Z,1694302096.064 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOil = nan psu
2023-09-09T23:28:16.108Z,1694302096.108 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDepth = nan m
2023-09-09T23:28:16.116Z,1694302096.116 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLatitude = nan arcdeg
2023-09-09T23:28:16.135Z,1694302096.135 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLongitude = nan arcdeg
2023-09-09T23:28:16.168Z,1694302096.168 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDistance = nan m
2023-09-09T23:28:16.185Z,1694302096.185 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.TriggeredOnChl = 0 bool
2023-09-09T23:28:16.188Z,1694302096.188 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.ChlTriggering = nan ug/l
2023-09-09T23:28:16.283Z,1694302096.283 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-09T23:28:16.315Z,1694302096.315 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-09T23:28:16.349Z,1694302096.349 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-09T23:28:16.380Z,1694302096.380 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-09T23:28:16.407Z,1694302096.407 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-09T23:28:16.438Z,1694302096.438 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-09T23:28:16.502Z,1694302096.502 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-09T23:28:16.556Z,1694302096.556 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-09T23:28:16.591Z,1694302096.591 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-09-09T23:28:16.614Z,1694302096.614 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-09T23:28:16.615Z,1694302096.615 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-09T23:28:16.649Z,1694302096.649 [MissionManager](INFO): DefineArg run_backseat_on_surface:BackseatDriver.EnableBackseat = 0 bool
2023-09-09T23:28:16.652Z,1694302096.652 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-09T23:28:16.670Z,1694302096.670 [run_backseat_on_surface:D.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-09T23:28:16.699Z,1694302096.699 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-09T23:28:16.701Z,1694302096.701 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-09T23:28:16.727Z,1694302096.727 [run_backseat_on_surface:G.Execute](DEBUG): Construct Execute.
2023-09-09T23:28:16.810Z,1694302096.810 [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-09T23:28:16.842Z,1694302096.842 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.tl
2023-09-09T23:28:16.917Z,1694302096.917 [Default] Stopped
2023-09-09T23:28:16.927Z,1694302096.927 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-09T23:28:16.927Z,1694302096.927 [Default:B.GoToSurface] Stopped
2023-09-09T23:28:16.927Z,1694302096.927 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-09T23:28:16.927Z,1694302096.927 [Default:CheckIn] Stopped
2023-09-09T23:28:16.927Z,1694302096.927 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-09T23:28:16.927Z,1694302096.927 [Default:CheckIn:C.Wait] Stopped
2023-09-09T23:28:16.927Z,1694302096.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-09T23:28:16.928Z,1694302096.928 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface] Running Loop=1
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface:Science] Running Loop=1
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface:Science](DEBUG): Aggregate::initialize run_backseat_on_surface:Science
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface:Science:A] Running Loop=1
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface:Science:B] Running Loop=1
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface:Science:C] Running Loop=1
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface:Science:D] Running Loop=1
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface:Science:E] Running Loop=1
2023-09-09T23:28:16.928Z,1694302096.928 [run_backseat_on_surface:Science:F] Running Loop=1
2023-09-09T23:28:16.929Z,1694302096.929 [run_backseat_on_surface:Science:Read_Oil] Running Loop=1
2023-09-09T23:28:16.929Z,1694302096.929 [run_backseat_on_surface:Science:PeakDetectChl] Running Loop=1
2023-09-09T23:28:16.933Z,1694302096.933 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectChl
2023-09-09T23:28:16.933Z,1694302096.933 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-09-09T23:28:16.933Z,1694302096.933 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-09T23:28:16.935Z,1694302096.935 [run_backseat_on_surface:Science:HighestChlPeakReport] Running Loop=1
2023-09-09T23:28:16.935Z,1694302096.935 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-09-09T23:28:16.936Z,1694302096.936 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-09T23:28:16.936Z,1694302096.936 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-09T23:28:16.936Z,1694302096.936 [run_backseat_on_surface:Science:PeakDetectNO3] Running Loop=1
2023-09-09T23:28:16.936Z,1694302096.936 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectNO3
2023-09-09T23:28:16.936Z,1694302096.936 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-09-09T23:28:16.936Z,1694302096.936 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-09T23:28:16.942Z,1694302096.942 [run_backseat_on_surface:Science:PeakDetectOil] Running Loop=1
2023-09-09T23:28:16.943Z,1694302096.943 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectOil
2023-09-09T23:28:16.943Z,1694302096.943 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-09-09T23:28:16.943Z,1694302096.943 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-09T23:28:16.945Z,1694302096.945 [run_backseat_on_surface:Science:HighestOilPeakReport] Running Loop=1
2023-09-09T23:28:16.945Z,1694302096.945 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-09-09T23:28:16.947Z,1694302096.947 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-09T23:28:16.947Z,1694302096.947 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-09T23:28:16.948Z,1694302096.948 [run_backseat_on_surface:Science:PeakDetectFDOM] Running Loop=1
2023-09-09T23:28:16.948Z,1694302096.948 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-09-09T23:28:16.948Z,1694302096.948 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-09-09T23:28:16.948Z,1694302096.948 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-09T23:28:16.966Z,1694302096.966 [run_backseat_on_surface:Science:PeakDetectSalinity] Running Loop=1
2023-09-09T23:28:16.966Z,1694302096.966 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-09-09T23:28:16.966Z,1694302096.966 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-09-09T23:28:16.966Z,1694302096.966 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-09T23:28:16.968Z,1694302096.968 [run_backseat_on_surface:Science:HighestSaltPeakReport] Running Loop=1
2023-09-09T23:28:16.968Z,1694302096.968 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-09-09T23:28:16.968Z,1694302096.968 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-09T23:28:16.968Z,1694302096.968 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-09T23:28:16.969Z,1694302096.969 [run_backseat_on_surface:Science:ChlThreshTrigger] Running Loop=1
2023-09-09T23:28:16.969Z,1694302096.969 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:ChlThreshTrigger
2023-09-09T23:28:16.973Z,1694302096.973 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-09-09T23:28:16.973Z,1694302096.973 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-09-09T23:28:16.974Z,1694302096.974 [run_backseat_on_surface:Science:OceanCurrent] Running Loop=1
2023-09-09T23:28:16.974Z,1694302096.974 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:OceanCurrent
2023-09-09T23:28:16.974Z,1694302096.974 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-09-09T23:28:16.974Z,1694302096.974 [run_backseat_on_surface:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-09-09T23:28:16.974Z,1694302096.974 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2023-09-09T23:28:16.974Z,1694302096.974 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::initialize run_backseat_on_surface:BackseatDriver
2023-09-09T23:28:16.974Z,1694302096.974 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-09T23:28:16.974Z,1694302096.974 [run_backseat_on_surface:D.GoToSurface] Running Loop=1
2023-09-09T23:28:16.974Z,1694302096.974 [run_backseat_on_surface:D.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-09T23:28:16.975Z,1694302096.975 [run_backseat_on_surface:D.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-09T23:28:16.975Z,1694302096.975 [run_backseat_on_surface:D.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-09T23:28:16.975Z,1694302096.975 [run_backseat_on_surface:D.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-09T23:28:16.976Z,1694302096.976 [run_backseat_on_surface:D.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-09T23:28:16.976Z,1694302096.976 [run_backseat_on_surface:D.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-09T23:28:16.976Z,1694302096.976 [run_backseat_on_surface:C] Running Loop=1
2023-09-09T23:28:16.993Z,1694302096.993 [run_backseat_on_surface:C] Stopped
2023-09-09T23:28:16.994Z,1694302096.994 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2023-09-09T23:28:16.994Z,1694302096.994 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2023-09-09T23:28:17.005Z,1694302097.005 [BackseatComponent](INFO): Powering up
2023-09-09T23:28:17.006Z,1694302097.006 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-09-09T23:28:17.122Z,1694302097.122 [run_backseat_on_surface:Science] Running Loop=1
2023-09-09T23:28:17.123Z,1694302097.123 [run_backseat_on_surface:Science:ChlThreshTrigger] Stopped
2023-09-09T23:28:17.123Z,1694302097.123 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:ChlThreshTrigger
2023-09-09T23:28:17.123Z,1694302097.123 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-09-09T23:28:17.123Z,1694302097.123 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-09-09T23:28:17.123Z,1694302097.123 [run_backseat_on_surface:Science:HighestSaltPeakReport] Stopped
2023-09-09T23:28:17.123Z,1694302097.123 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:PeakDetectSalinity] Stopped
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:PeakDetectFDOM] Stopped
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:HighestOilPeakReport] Stopped
2023-09-09T23:28:17.124Z,1694302097.124 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-09-09T23:28:17.125Z,1694302097.125 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-09T23:28:17.125Z,1694302097.125 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-09T23:28:17.125Z,1694302097.125 [run_backseat_on_surface:Science:PeakDetectOil] Stopped
2023-09-09T23:28:17.128Z,1694302097.128 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectOil
2023-09-09T23:28:17.128Z,1694302097.128 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-09-09T23:28:17.128Z,1694302097.128 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-09T23:28:17.128Z,1694302097.128 [run_backseat_on_surface:Science:PeakDetectNO3] Stopped
2023-09-09T23:28:17.128Z,1694302097.128 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectNO3
2023-09-09T23:28:17.129Z,1694302097.129 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-09-09T23:28:17.129Z,1694302097.129 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-09T23:28:17.129Z,1694302097.129 [run_backseat_on_surface:Science:HighestChlPeakReport] Stopped
2023-09-09T23:28:17.141Z,1694302097.141 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-09-09T23:28:17.141Z,1694302097.141 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-09T23:28:17.141Z,1694302097.141 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-09T23:28:17.141Z,1694302097.141 [run_backseat_on_surface:Science:PeakDetectChl] Stopped
2023-09-09T23:28:17.141Z,1694302097.141 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectChl
2023-09-09T23:28:17.142Z,1694302097.142 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-09-09T23:28:17.142Z,1694302097.142 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-09T23:28:17.142Z,1694302097.142 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-09-09T23:28:17.142Z,1694302097.142 [run_backseat_on_surface:Science:Read_Oil] Stopped
2023-09-09T23:28:17.142Z,1694302097.142 [run_backseat_on_surface:Science:F] Stopped
2023-09-09T23:28:17.142Z,1694302097.142 [run_backseat_on_surface:Science:E] Running Loop=1
2023-09-09T23:28:17.143Z,1694302097.143 [run_backseat_on_surface:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-09-09T23:28:17.143Z,1694302097.143 [run_backseat_on_surface:Science:D] Stopped
2023-09-09T23:28:17.143Z,1694302097.143 [run_backseat_on_surface:Science:C] Running Loop=1
2023-09-09T23:28:17.144Z,1694302097.144 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-09-09T23:28:17.144Z,1694302097.144 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-09-09T23:28:17.144Z,1694302097.144 [run_backseat_on_surface:Science:B] Running Loop=1
2023-09-09T23:28:17.145Z,1694302097.145 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-09-09T23:28:17.146Z,1694302097.146 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-09-09T23:28:17.146Z,1694302097.146 [run_backseat_on_surface:Science:A] Stopped
2023-09-09T23:28:17.346Z,1694302097.346 [run_backseat_on_surface:CheckIn] Running Loop=1
2023-09-09T23:28:17.346Z,1694302097.346 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2023-09-09T23:28:17.346Z,1694302097.346 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2023-09-09T23:28:17.463Z,1694302097.463 [WetLabsBB2FL](INFO): Powering up
2023-09-09T23:28:17.741Z,1694302097.741 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-09T23:28:18.931Z,1694302098.931 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-09T23:28:18.936Z,1694302098.936 [BPC1](INFO): Received data from all battery sticks.
2023-09-09T23:28:20.287Z,1694302100.287 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2023-09-09T23:29:03.006Z,1694302143.006 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-09-09T23:29:06.486Z,1694302146.486 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20230909T232903
2023-09-09T23:29:06.649Z,1694302146.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232906.00,A,4144.75987,N,08116.63400,W,0.039,0.00,090923,,,A*75
2023-09-09T23:29:06.651Z,1694302146.651 [NAL9602](INFO): GPS fix at 20230909T232906: (41.745998, -81.277233)
2023-09-09T23:29:06.670Z,1694302146.670 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2023-09-09T23:29:06.671Z,1694302146.671 [run_backseat_on_surface:CheckIn:Read_Iridium] Running Loop=1
2023-09-09T23:29:07.085Z,1694302147.085 [run_backseat_on_surface:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-09T23:29:09.200Z,1694302149.200 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2023-09-09T23:29:09.200Z,1694302149.200 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2023-09-09T23:29:09.203Z,1694302149.203 [BackseatComponent](INFO): Created universal data reader for latitude with code: 23
2023-09-09T23:29:09.204Z,1694302149.204 [BackseatComponent](INFO): Requesting data from latitude (23). Requested size is: 2
2023-09-09T23:29:09.206Z,1694302149.206 [BackseatComponent](INFO): Created universal data reader for longitude with code: 26
2023-09-09T23:29:09.207Z,1694302149.207 [BackseatComponent](INFO): Requesting data from longitude (26). Requested size is: 3
2023-09-09T23:29:09.209Z,1694302149.209 [BackseatComponent](INFO): Created universal data reader for platform_orientation with code: 52
2023-09-09T23:29:09.210Z,1694302149.210 [BackseatComponent](INFO): Requesting data from platform_orientation (52). Requested size is: 4
2023-09-09T23:29:09.212Z,1694302149.212 [BackseatComponent](INFO): Created universal data reader for platform_pitch_angle with code: 54
2023-09-09T23:29:09.221Z,1694302149.221 [BackseatComponent](INFO): Requesting data from platform_pitch_angle (54). Requested size is: 5
2023-09-09T23:29:09.224Z,1694302149.224 [BackseatComponent](INFO): Created universal data reader for platform_roll_angle with code: 59
2023-09-09T23:29:09.224Z,1694302149.224 [BackseatComponent](INFO): Requesting data from platform_roll_angle (59). Requested size is: 6
2023-09-09T23:29:09.227Z,1694302149.227 [BackseatComponent](INFO): Created universal data reader for platform_x_velocity_wrt_sea_water with code: 70
2023-09-09T23:29:09.227Z,1694302149.227 [BackseatComponent](INFO): Requesting data from platform_x_velocity_wrt_sea_water (70). Requested size is: 7
2023-09-09T23:29:18.085Z,1694302158.085 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230909T231941/Courier0007.lzma
2023-09-09T23:29:19.087Z,1694302159.087 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0007.lzma.bak
2023-09-09T23:29:19.087Z,1694302159.087 [DataOverHttps](INFO): SBD MOMSN=18756284
2023-09-09T23:29:38.436Z,1694302178.436 [DataOverHttps](INFO): Sending 771 bytes from file Logs/20230909T231941/Express0008.lzma
2023-09-09T23:29:39.442Z,1694302179.442 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-09T23:29:41.974Z,1694302181.974 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0008.lzma.bak
2023-09-09T23:29:41.974Z,1694302181.974 [DataOverHttps](INFO): SBD MOMSN=18756288
2023-09-09T23:29:45.544Z,1694302185.544 [run_backseat_on_surface:CheckIn:Read_Iridium] Stopped
2023-09-09T23:29:45.545Z,1694302185.545 [run_backseat_on_surface:CheckIn:C.Wait] Running Loop=1
2023-09-09T23:29:45.545Z,1694302185.545 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:30:10.378Z,1694302210.378 [CommandExec](IMPORTANT): got command stop
2023-09-09T23:30:10.378Z,1694302210.378 [CommandExec](IMPORTANT): Scheduling is paused
2023-09-09T23:30:10.378Z,1694302210.378 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-09-09T23:30:10.566Z,1694302210.566 [MissionManager](INFO): MissionManager is completed.
2023-09-09T23:30:10.566Z,1694302210.566 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface
2023-09-09T23:30:10.567Z,1694302210.566 [run_backseat_on_surface] Stopped
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:Science] Stopped
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:Science](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:Science:B] Stopped
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:Science:C] Stopped
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:Science:E] Stopped
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:Science:OceanCurrent] Stopped
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:OceanCurrent
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:Science:OceanCurrent:A.] Stopped
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:BackseatDriver] Stopped
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::uninitialize run_backseat_on_surface:BackseatDriver
2023-09-09T23:30:10.567Z,1694302210.567 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Stopped
2023-09-09T23:30:10.568Z,1694302210.568 [run_backseat_on_surface:D.GoToSurface] Stopped
2023-09-09T23:30:10.568Z,1694302210.568 [run_backseat_on_surface:D.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-09T23:30:10.568Z,1694302210.568 [run_backseat_on_surface:CheckIn] Stopped
2023-09-09T23:30:10.568Z,1694302210.568 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2023-09-09T23:30:10.568Z,1694302210.568 [run_backseat_on_surface:CheckIn:C.Wait] Stopped
2023-09-09T23:30:10.568Z,1694302210.568 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-09T23:30:10.970Z,1694302210.970 [MissionManager](IMPORTANT): Started mission Default
2023-09-09T23:30:10.971Z,1694302210.971 [Default] Running Loop=1
2023-09-09T23:30:10.971Z,1694302210.971 [Default](DEBUG): Aggregate::initialize Default
2023-09-09T23:30:10.971Z,1694302210.971 [Default:B.GoToSurface] Running Loop=1
2023-09-09T23:30:10.971Z,1694302210.971 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-09T23:30:10.971Z,1694302210.971 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-09T23:30:10.972Z,1694302210.972 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-09T23:30:10.972Z,1694302210.972 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-09T23:30:10.972Z,1694302210.972 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-09T23:30:10.972Z,1694302210.972 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-09T23:30:10.973Z,1694302210.973 [Default:A.Wait] Running Loop=1
2023-09-09T23:30:10.973Z,1694302210.973 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:30:11.037Z,1694302211.037 [WetLabsBB2FL](INFO): Powering down
2023-09-09T23:30:11.544Z,1694302211.544 [BackseatComponent](INFO): Sent LCM shutdown request.
2023-09-09T23:30:11.545Z,1694302211.545 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2023-09-09T23:30:24.293Z,1694302224.293 [Default:A.Wait](INFO): Done Waiting.
2023-09-09T23:30:24.293Z,1694302224.293 [Default:A.Wait] Stopped
2023-09-09T23:30:24.293Z,1694302224.293 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-09T23:30:24.700Z,1694302224.700 [Default:CheckIn] Running Loop=1
2023-09-09T23:30:24.701Z,1694302224.701 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-09T23:30:24.701Z,1694302224.701 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-09T23:30:26.708Z,1694302226.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233026.00,A,4144.76172,N,08116.63450,W,0.039,0.00,090923,,,A*7B
2023-09-09T23:30:26.710Z,1694302226.710 [NAL9602](INFO): GPS fix at 20230909T233026: (41.746029, -81.277242)
2023-09-09T23:30:26.721Z,1694302226.721 [Default:CheckIn:Read_GPS] Stopped
2023-09-09T23:30:26.721Z,1694302226.721 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-09T23:30:29.706Z,1694302229.706 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2023-09-09T23:30:29.706Z,1694302229.706 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2023-09-09T23:30:29.707Z,1694302229.707 [LcmUniversalReporter](INFO): Deactivating messaging.
2023-09-09T23:30:30.354Z,1694302230.354 [Power24vConverter](INFO): Powering down.
2023-09-09T23:30:38.621Z,1694302238.621 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230909T231941/Courier0010.lzma
2023-09-09T23:30:39.623Z,1694302239.623 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0010.lzma.bak
2023-09-09T23:30:39.623Z,1694302239.623 [DataOverHttps](INFO): SBD MOMSN=18756301
2023-09-09T23:30:57.462Z,1694302257.462 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-09-09T23:30:57.541Z,1694302257.541 [NAL9602](ERROR): received: 
+CSQ:1

OK84, 2, 0, 0, 0

OK
2023-09-09T23:31:00.745Z,1694302260.745 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20230909T231941/Express0011.lzma
2023-09-09T23:31:01.747Z,1694302261.747 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0011.lzma.bak
2023-09-09T23:31:01.747Z,1694302261.747 [DataOverHttps](INFO): SBD MOMSN=18756304
2023-09-09T23:31:09.280Z,1694302269.280 [Default:CheckIn:Read_Iridium] Stopped
2023-09-09T23:31:09.280Z,1694302269.280 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-09T23:31:09.280Z,1694302269.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:31:35.932Z,1694302295.932 [NAL9602](INFO): SBD MO Status=2, MOMSN=3884, MT Status=2, MTMSN=0
2023-09-09T23:31:35.932Z,1694302295.932 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-09T23:31:54.112Z,1694302314.112 [NAL9602](INFO): SBD MO Status=1, MOMSN=3884, MT Status=0, MTMSN=0
2023-09-09T23:31:54.112Z,1694302314.112 [NAL9602](INFO): No messages in MT queue
2023-09-09T23:32:24.810Z,1694302344.810 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-09T23:36:09.882Z,1694302569.882 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-09T23:36:09.882Z,1694302569.882 [Default:CheckIn:C.Wait] Stopped
2023-09-09T23:36:09.882Z,1694302569.882 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-09T23:36:09.883Z,1694302569.883 [Default:CheckIn:D] Running Loop=1
2023-09-09T23:36:10.266Z,1694302570.266 [Default:CheckIn:D] Stopped
2023-09-09T23:36:10.266Z,1694302570.266 [Default:CheckIn:E] Running Loop=1
2023-09-09T23:36:10.670Z,1694302570.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.988254 min 
2023-09-09T23:36:10.670Z,1694302570.670 [Default:CheckIn:E] Stopped
2023-09-09T23:36:10.670Z,1694302570.670 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-09T23:36:10.671Z,1694302570.671 [Default:CheckIn] Stopped
2023-09-09T23:36:10.671Z,1694302570.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-09T23:36:10.671Z,1694302570.671 [Default:CheckIn](INFO): Running loop #2
2023-09-09T23:36:10.671Z,1694302570.671 [Default:CheckIn] Running Loop=2
2023-09-09T23:36:10.671Z,1694302570.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-09T23:36:10.671Z,1694302570.671 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-09T23:36:12.667Z,1694302572.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233612.00,A,4144.75414,N,08116.63557,W,0.097,0.00,090923,,,D*7B
2023-09-09T23:36:12.669Z,1694302572.669 [NAL9602](INFO): GPS fix at 20230909T233612: (41.745902, -81.277259)
2023-09-09T23:36:12.700Z,1694302572.700 [Default:CheckIn:Read_GPS] Stopped
2023-09-09T23:36:12.700Z,1694302572.700 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-09T23:36:14.773Z,1694302574.773 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230909T231941/Courier0013.lzma
2023-09-09T23:36:15.779Z,1694302575.779 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0013.lzma.bak
2023-09-09T23:36:15.779Z,1694302575.779 [DataOverHttps](INFO): SBD MOMSN=18756311
2023-09-09T23:36:37.041Z,1694302597.041 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20230909T231941/Express0014.lzma
2023-09-09T23:36:38.043Z,1694302598.043 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0014.lzma.bak
2023-09-09T23:36:38.044Z,1694302598.044 [DataOverHttps](INFO): SBD MOMSN=18756314
2023-09-09T23:36:43.841Z,1694302603.841 [Default:CheckIn:Read_Iridium] Stopped
2023-09-09T23:36:43.841Z,1694302603.841 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-09T23:36:43.860Z,1694302603.860 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:40:27.284Z,1694302827.284 [NAL9602](INFO): SBD MO Status=2, MOMSN=3885, MT Status=2, MTMSN=0
2023-09-09T23:40:27.284Z,1694302827.284 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-09T23:40:50.760Z,1694302850.760 [NAL9602](INFO): SBD MO Status=1, MOMSN=3885, MT Status=0, MTMSN=0
2023-09-09T23:40:50.760Z,1694302850.760 [NAL9602](INFO): No messages in MT queue
2023-09-09T23:41:21.465Z,1694302881.465 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-09T23:41:44.515Z,1694302904.515 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-09T23:41:44.515Z,1694302904.515 [Default:CheckIn:C.Wait] Stopped
2023-09-09T23:41:44.515Z,1694302904.515 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-09T23:41:44.516Z,1694302904.516 [Default:CheckIn:D] Running Loop=1
2023-09-09T23:41:44.936Z,1694302904.936 [Default:CheckIn:D] Stopped
2023-09-09T23:41:44.936Z,1694302904.936 [Default:CheckIn:E] Running Loop=1
2023-09-09T23:41:45.321Z,1694302905.321 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.566083 min 
2023-09-09T23:41:45.321Z,1694302905.321 [Default:CheckIn:E] Stopped
2023-09-09T23:41:45.321Z,1694302905.321 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-09T23:41:45.321Z,1694302905.321 [Default:CheckIn] Stopped
2023-09-09T23:41:45.322Z,1694302905.322 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-09T23:41:45.322Z,1694302905.322 [Default:CheckIn](INFO): Running loop #3
2023-09-09T23:41:45.322Z,1694302905.322 [Default:CheckIn] Running Loop=3
2023-09-09T23:41:45.322Z,1694302905.322 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-09T23:41:45.322Z,1694302905.322 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-09T23:41:47.328Z,1694302907.328 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234146.00,A,4144.75707,N,08116.63587,W,0.117,310.95,090923,,,D*71
2023-09-09T23:41:47.330Z,1694302907.330 [NAL9602](INFO): GPS fix at 20230909T234146: (41.745951, -81.277265)
2023-09-09T23:41:47.358Z,1694302907.358 [Default:CheckIn:Read_GPS] Stopped
2023-09-09T23:41:47.358Z,1694302907.358 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-09T23:41:49.736Z,1694302909.736 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230909T231941/Courier0016.lzma
2023-09-09T23:41:50.743Z,1694302910.743 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0016.lzma.bak
2023-09-09T23:41:50.743Z,1694302910.743 [DataOverHttps](INFO): SBD MOMSN=18756317
2023-09-09T23:42:09.305Z,1694302929.305 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230909T231941/Express0017.lzma
2023-09-09T23:42:17.648Z,1694302937.648 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230909T231941/Express0017.lzma
2023-09-09T23:42:18.651Z,1694302938.651 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0017.lzma.bak
2023-09-09T23:42:18.651Z,1694302938.651 [DataOverHttps](INFO): SBD MOMSN=18756320
2023-09-09T23:42:24.572Z,1694302944.572 [Default:CheckIn:Read_Iridium] Stopped
2023-09-09T23:42:24.572Z,1694302944.572 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-09T23:42:24.572Z,1694302944.572 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:42:26.172Z,1694302946.172 [NAL9602](INFO): SBD MO Status=1, MOMSN=3886, MT Status=0, MTMSN=0
2023-09-09T23:42:26.172Z,1694302946.172 [NAL9602](INFO): No messages in MT queue
2023-09-09T23:42:56.890Z,1694302976.890 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-09T23:47:25.220Z,1694303245.220 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-09T23:47:25.220Z,1694303245.220 [Default:CheckIn:C.Wait] Stopped
2023-09-09T23:47:25.220Z,1694303245.220 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-09T23:47:25.221Z,1694303245.221 [Default:CheckIn:D] Running Loop=1
2023-09-09T23:47:25.655Z,1694303245.655 [Default:CheckIn:D] Stopped
2023-09-09T23:47:25.655Z,1694303245.655 [Default:CheckIn:E] Running Loop=1
2023-09-09T23:47:26.018Z,1694303246.018 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.244731 min 
2023-09-09T23:47:26.018Z,1694303246.018 [Default:CheckIn:E] Stopped
2023-09-09T23:47:26.018Z,1694303246.018 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-09T23:47:26.018Z,1694303246.018 [Default:CheckIn] Stopped
2023-09-09T23:47:26.018Z,1694303246.018 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-09T23:47:26.018Z,1694303246.018 [Default:CheckIn](INFO): Running loop #4
2023-09-09T23:47:26.019Z,1694303246.019 [Default:CheckIn] Running Loop=4
2023-09-09T23:47:26.019Z,1694303246.019 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-09T23:47:26.019Z,1694303246.019 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-09T23:47:28.024Z,1694303248.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234727.00,A,4144.75843,N,08116.63570,W,0.136,230.52,090923,,,D*7C
2023-09-09T23:47:28.026Z,1694303248.026 [NAL9602](INFO): GPS fix at 20230909T234727: (41.745974, -81.277262)
2023-09-09T23:47:28.036Z,1694303248.036 [Default:CheckIn:Read_GPS] Stopped
2023-09-09T23:47:28.037Z,1694303248.037 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-09T23:47:36.572Z,1694303256.572 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230909T231941/Courier0019.lzma
2023-09-09T23:47:37.575Z,1694303257.575 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0019.lzma.bak
2023-09-09T23:47:37.575Z,1694303257.575 [DataOverHttps](INFO): SBD MOMSN=18756327
2023-09-09T23:47:56.205Z,1694303276.205 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230909T231941/Express0020.lzma
2023-09-09T23:47:57.207Z,1694303277.207 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0020.lzma.bak
2023-09-09T23:47:57.207Z,1694303277.207 [DataOverHttps](INFO): SBD MOMSN=18756330
2023-09-09T23:48:01.182Z,1694303281.182 [Default:CheckIn:Read_Iridium] Stopped
2023-09-09T23:48:01.182Z,1694303281.182 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-09T23:48:01.183Z,1694303281.183 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:48:39.532Z,1694303319.532 [NAL9602](INFO): SBD MO Status=1, MOMSN=3887, MT Status=0, MTMSN=0
2023-09-09T23:48:39.532Z,1694303319.532 [NAL9602](INFO): No messages in MT queue
2023-09-09T23:49:10.240Z,1694303350.240 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-09T23:53:01.804Z,1694303581.804 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-09T23:53:01.804Z,1694303581.804 [Default:CheckIn:C.Wait] Stopped
2023-09-09T23:53:01.804Z,1694303581.804 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-09T23:53:01.804Z,1694303581.804 [Default:CheckIn:D] Running Loop=1
2023-09-09T23:53:02.233Z,1694303582.233 [Default:CheckIn:D] Stopped
2023-09-09T23:53:02.233Z,1694303582.233 [Default:CheckIn:E] Running Loop=1
2023-09-09T23:53:02.673Z,1694303582.673 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.854299 min 
2023-09-09T23:53:02.673Z,1694303582.673 [Default:CheckIn:E] Stopped
2023-09-09T23:53:02.673Z,1694303582.673 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-09T23:53:02.673Z,1694303582.673 [Default:CheckIn] Stopped
2023-09-09T23:53:02.673Z,1694303582.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-09T23:53:02.673Z,1694303582.673 [Default:CheckIn](INFO): Running loop #5
2023-09-09T23:53:02.673Z,1694303582.673 [Default:CheckIn] Running Loop=5
2023-09-09T23:53:02.673Z,1694303582.673 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-09T23:53:02.674Z,1694303582.674 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-09T23:53:04.624Z,1694303584.624 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235303.00,A,4144.74922,N,08116.61818,W,2.760,169.84,090923,,,D*7A
2023-09-09T23:53:04.626Z,1694303584.626 [NAL9602](INFO): GPS fix at 20230909T235303: (41.745820, -81.276970)
2023-09-09T23:53:04.645Z,1694303584.645 [Default:CheckIn:Read_GPS] Stopped
2023-09-09T23:53:04.646Z,1694303584.646 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-09T23:53:16.380Z,1694303596.380 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230909T231941/Courier0022.lzma
2023-09-09T23:53:17.383Z,1694303597.383 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0022.lzma.bak
2023-09-09T23:53:17.383Z,1694303597.383 [DataOverHttps](INFO): SBD MOMSN=18756335
2023-09-09T23:53:22.336Z,1694303602.336 [NAL9602](INFO): SBD MO Status=1, MOMSN=3888, MT Status=0, MTMSN=0
2023-09-09T23:53:22.337Z,1694303602.337 [NAL9602](INFO): No messages in MT queue
2023-09-09T23:53:36.321Z,1694303616.321 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20230909T231941/Express0023.lzma
2023-09-09T23:53:37.323Z,1694303617.323 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0023.lzma.bak
2023-09-09T23:53:37.323Z,1694303617.323 [DataOverHttps](INFO): SBD MOMSN=18756338
2023-09-09T23:53:41.346Z,1694303621.346 [Default:CheckIn:Read_Iridium] Stopped
2023-09-09T23:53:41.347Z,1694303621.347 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-09T23:53:41.347Z,1694303621.347 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:53:53.038Z,1694303633.038 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-09T23:58:42.057Z,1694303922.057 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-09T23:58:42.057Z,1694303922.057 [Default:CheckIn:C.Wait] Stopped
2023-09-09T23:58:42.057Z,1694303922.057 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-09T23:58:42.058Z,1694303922.058 [Default:CheckIn:D] Running Loop=1
2023-09-09T23:58:42.471Z,1694303922.471 [Default:CheckIn:D] Stopped
2023-09-09T23:58:42.471Z,1694303922.471 [Default:CheckIn:E] Running Loop=1
2023-09-09T23:58:42.874Z,1694303922.874 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.525000 min 
2023-09-09T23:58:42.875Z,1694303922.875 [Default:CheckIn:E] Stopped
2023-09-09T23:58:42.875Z,1694303922.875 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-09T23:58:42.875Z,1694303922.875 [Default:CheckIn] Stopped
2023-09-09T23:58:42.875Z,1694303922.875 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-09T23:58:42.875Z,1694303922.875 [Default:CheckIn](INFO): Running loop #6
2023-09-09T23:58:42.875Z,1694303922.875 [Default:CheckIn] Running Loop=6
2023-09-09T23:58:42.875Z,1694303922.875 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-09T23:58:42.875Z,1694303922.875 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-09T23:58:44.844Z,1694303924.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235844.00,A,4145.07438,N,08116.69660,W,2.741,355.13,090923,,,D*78
2023-09-09T23:58:44.846Z,1694303924.846 [NAL9602](INFO): GPS fix at 20230909T235844: (41.751240, -81.278277)
2023-09-09T23:58:44.857Z,1694303924.857 [Default:CheckIn:Read_GPS] Stopped
2023-09-09T23:58:44.857Z,1694303924.857 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-09T23:58:53.848Z,1694303933.848 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230909T231941/Courier0025.lzma
2023-09-09T23:58:54.851Z,1694303934.851 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0025.lzma.bak
2023-09-09T23:58:54.851Z,1694303934.851 [DataOverHttps](INFO): SBD MOMSN=18756353
2023-09-09T23:59:07.895Z,1694303947.895 [NAL9602](INFO): SBD MO Status=1, MOMSN=3889, MT Status=0, MTMSN=0
2023-09-09T23:59:07.895Z,1694303947.895 [NAL9602](INFO): No messages in MT queue
2023-09-09T23:59:16.345Z,1694303956.345 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230909T231941/Express0026.lzma
2023-09-09T23:59:17.347Z,1694303957.347 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0026.lzma.bak
2023-09-09T23:59:17.347Z,1694303957.347 [DataOverHttps](INFO): SBD MOMSN=18756360
2023-09-09T23:59:23.301Z,1694303963.301 [Default:CheckIn:Read_Iridium] Stopped
2023-09-09T23:59:23.302Z,1694303963.302 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-09T23:59:23.302Z,1694303963.302 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-09T23:59:38.622Z,1694303978.622 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-10T00:04:23.915Z,1694304263.915 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-10T00:04:23.915Z,1694304263.915 [Default:CheckIn:C.Wait] Stopped
2023-09-10T00:04:23.915Z,1694304263.915 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-10T00:04:23.915Z,1694304263.915 [Default:CheckIn:D] Running Loop=1
2023-09-10T00:04:24.324Z,1694304264.324 [Default:CheckIn:D] Stopped
2023-09-10T00:04:24.324Z,1694304264.324 [Default:CheckIn:E] Running Loop=1
2023-09-10T00:04:24.733Z,1694304264.733 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.222550 min 
2023-09-10T00:04:24.733Z,1694304264.733 [Default:CheckIn:E] Stopped
2023-09-10T00:04:24.733Z,1694304264.733 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-10T00:04:24.733Z,1694304264.733 [Default:CheckIn] Stopped
2023-09-10T00:04:24.733Z,1694304264.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-10T00:04:24.734Z,1694304264.734 [Default:CheckIn](INFO): Running loop #7
2023-09-10T00:04:24.734Z,1694304264.734 [Default:CheckIn] Running Loop=7
2023-09-10T00:04:24.734Z,1694304264.734 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-10T00:04:24.734Z,1694304264.734 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-10T00:04:26.740Z,1694304266.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000426.00,A,4145.50870,N,08116.73079,W,4.413,358.25,100923,,,D*71
2023-09-10T00:04:26.750Z,1694304266.750 [NAL9602](INFO): GPS fix at 20230910T000426: (41.758478, -81.278847)
2023-09-10T00:04:26.761Z,1694304266.761 [Default:CheckIn:Read_GPS] Stopped
2023-09-10T00:04:26.761Z,1694304266.761 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-10T00:04:38.556Z,1694304278.556 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230909T231941/Courier0028.lzma
2023-09-10T00:04:41.849Z,1694304281.849 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0028.lzma.bak
2023-09-10T00:04:41.850Z,1694304281.850 [DataOverHttps](INFO): SBD MOMSN=18756389
2023-09-10T00:04:59.604Z,1694304299.604 [NAL9602](INFO): SBD MO Status=1, MOMSN=3890, MT Status=0, MTMSN=0
2023-09-10T00:04:59.604Z,1694304299.604 [NAL9602](INFO): No messages in MT queue
2023-09-10T00:04:59.913Z,1694304299.913 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230909T231941/Express0029.lzma
2023-09-10T00:05:00.915Z,1694304300.915 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0029.lzma.bak
2023-09-10T00:05:00.915Z,1694304300.915 [DataOverHttps](INFO): SBD MOMSN=18756392
2023-09-10T00:05:06.948Z,1694304306.948 [Default:CheckIn:Read_Iridium] Stopped
2023-09-10T00:05:06.949Z,1694304306.949 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-10T00:05:06.949Z,1694304306.949 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-10T00:05:30.402Z,1694304330.402 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-10T00:07:19.830Z,1694304439.830 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T000719
2023-09-10T00:07:27.141Z,1694304447.141 [DataOverHttps](INFO): Received command: ibit
2023-09-10T00:07:27.190Z,1694304447.190 [CommandExec](IMPORTANT): got command ibit
2023-09-10T00:07:27.245Z,1694304447.245 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-09-10T00:07:27.246Z,1694304447.246 [IBIT](IMPORTANT): Beginning control surface checks.
2023-09-10T00:07:27.249Z,1694304447.249 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-10T00:07:28.841Z,1694304448.841 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000728.00,A,4145.53173,N,08116.65280,W,0.019,326.73,100923,,,D*76
2023-09-10T00:07:28.843Z,1694304448.843 [NAL9602](INFO): GPS fix at 20230910T000728: (41.758862, -81.277547)
2023-09-10T00:07:30.126Z,1694304450.126 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-09-10T00:07:38.144Z,1694304458.144 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006510
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.004224
CHAN A3 (5V): -0.002574
CHAN B0 (3.3V): -0.000878
CHAN B1 (3.15aV): -0.001599
CHAN B2 (3.15bV): -0.001005
CHAN B3 (GND): -0.000195
OPEN: 0.004417
Full Scale: +/- 1 mA
2023-09-10T00:07:50.651Z,1694304470.651 [NAL9602](INFO): SBD MO Status=1, MOMSN=3891, MT Status=0, MTMSN=0
2023-09-10T00:07:50.652Z,1694304470.652 [NAL9602](INFO): No messages in MT queue
2023-09-10T00:07:51.860Z,1694304471.860 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000751.00,A,4145.53080,N,08116.65236,W,0.019,326.73,100923,,,D*78
2023-09-10T00:07:51.863Z,1694304471.863 [NAL9602](INFO): GPS fix at 20230910T000751: (41.758847, -81.277539)
2023-09-10T00:07:54.688Z,1694304474.688 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000754.00,A,4145.53075,N,08116.65245,W,0.000,326.73,100923,,,D*7B
2023-09-10T00:07:54.690Z,1694304474.690 [NAL9602](INFO): GPS fix at 20230910T000754: (41.758846, -81.277541)
2023-09-10T00:07:57.924Z,1694304477.924 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000757.00,A,4145.53083,N,08116.65254,W,0.019,326.73,100923,,,D*79
2023-09-10T00:07:57.927Z,1694304477.927 [NAL9602](INFO): GPS fix at 20230910T000757: (41.758847, -81.277542)
2023-09-10T00:08:00.748Z,1694304480.748 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000800.00,A,4145.53090,N,08116.65263,W,0.058,326.73,100923,,,D*77
2023-09-10T00:08:00.750Z,1694304480.750 [NAL9602](INFO): GPS fix at 20230910T000800: (41.758848, -81.277544)
2023-09-10T00:08:03.984Z,1694304483.984 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000803.00,A,4145.53092,N,08116.65278,W,0.000,326.73,100923,,,D*71
2023-09-10T00:08:03.986Z,1694304483.986 [NAL9602](INFO): GPS fix at 20230910T000803: (41.758849, -81.277546)
2023-09-10T00:08:06.812Z,1694304486.812 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000806.00,A,4145.53095,N,08116.65292,W,0.019,326.73,100923,,,D*7F
2023-09-10T00:08:06.823Z,1694304486.823 [NAL9602](INFO): GPS fix at 20230910T000806: (41.758849, -81.277549)
2023-09-10T00:08:10.040Z,1694304490.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000809.00,A,4145.53107,N,08116.65302,W,0.058,326.73,100923,,,D*77
2023-09-10T00:08:10.042Z,1694304490.042 [NAL9602](INFO): GPS fix at 20230910T000809: (41.758851, -81.277550)
2023-09-10T00:08:12.870Z,1694304492.870 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000812.00,A,4145.53122,N,08116.65308,W,0.019,326.73,100923,,,D*75
2023-09-10T00:08:12.872Z,1694304492.872 [NAL9602](INFO): GPS fix at 20230910T000812: (41.758854, -81.277551)
2023-09-10T00:08:13.717Z,1694304493.717 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 41.758854 Longitude: -81.277550
2023-09-10T00:08:14.098Z,1694304494.098 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.294001
2023-09-10T00:08:14.098Z,1694304494.098 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-09-10T00:08:14.099Z,1694304494.099 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-09-10T00:08:14.506Z,1694304494.506 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-09-10T00:08:14.506Z,1694304494.506 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-09-10T00:08:14.507Z,1694304494.507 [IBIT](IMPORTANT): Pressure:10.193256 PSI
2023-09-10T00:08:14.507Z,1694304494.507 [IBIT](IMPORTANT): Humidity:5.164205 %
2023-09-10T00:08:14.914Z,1694304494.914 [IBIT](IMPORTANT): Vehicle Pitch:-3.963031 degrees
2023-09-10T00:08:14.914Z,1694304494.914 [IBIT](IMPORTANT): Vehicle  Roll:1.192496 degrees
2023-09-10T00:08:14.915Z,1694304494.915 [IBIT](IMPORTANT): Vehicle  Heading:180.441559 degrees
2023-09-10T00:08:15.305Z,1694304495.305 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-09-10T00:08:15.305Z,1694304495.305 [IBIT](IMPORTANT): buoyancyNeutral: 485.304840 cc
2023-09-10T00:08:15.306Z,1694304495.306 [IBIT](IMPORTANT): massDefault: 2.356379 cm
2023-09-10T00:08:15.306Z,1694304495.306 [IBIT](IMPORTANT): stopDepth: 100.000000 m
2023-09-10T00:08:15.306Z,1694304495.306 [IBIT](IMPORTANT): abortDepth: 120.000000 m
2023-09-10T00:08:15.306Z,1694304495.306 [IBIT](IMPORTANT): IBIT PASSED
2023-09-10T00:08:45.186Z,1694304525.186 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-10T00:09:02.057Z,1694304542.057 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-10T00:09:04.066Z,1694304544.066 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247360
2023-09-10T00:09:15.795Z,1694304555.795 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T000914
2023-09-10T00:09:15.798Z,1694304555.798 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003694
2023-09-10T00:09:23.229Z,1694304563.229 [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" ij25 1 2
2023-09-10T00:09:23.322Z,1694304563.322 [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" ij25 1 2.000000
2023-09-10T00:09:23.322Z,1694304563.322 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=ij25
2023-09-10T00:09:23.323Z,1694304563.323 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='ij25'): "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-10T00:09:24.231Z,1694304564.231 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T000923
2023-09-10T00:09:31.569Z,1694304571.569 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" ij25 2 2
2023-09-10T00:09:31.668Z,1694304571.668 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" ij25 2 2.000000
2023-09-10T00:09:31.669Z,1694304571.669 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=ij25
2023-09-10T00:09:31.670Z,1694304571.670 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='ij25'): "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run", ASAP
2023-09-10T00:09:31.783Z,1694304571.783 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-09-10T00:09:31.784Z,1694304571.784 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-09-10T00:09:31.784Z,1694304571.784 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-09-10T00:09:31.860Z,1694304571.860 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-09-10T00:09:31.863Z,1694304571.863 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-09-10T00:09:31.866Z,1694304571.866 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-09-10T00:09:31.868Z,1694304571.868 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-09-10T00:09:31.875Z,1694304571.875 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-09-10T00:09:31.878Z,1694304571.878 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-09-10T00:09:31.881Z,1694304571.881 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-09-10T00:09:31.884Z,1694304571.884 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-09-10T00:09:31.887Z,1694304571.887 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-09-10T00:09:31.890Z,1694304571.890 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-09-10T00:09:31.892Z,1694304571.892 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-09-10T00:09:31.895Z,1694304571.895 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-09-10T00:09:31.899Z,1694304571.899 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-09-10T00:09:31.899Z,1694304571.899 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-09-10T00:09:31.899Z,1694304571.899 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-09-10T00:09:32.017Z,1694304572.017 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-09-10T00:09:32.020Z,1694304572.020 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-09-10T00:09:32.023Z,1694304572.023 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-09-10T00:09:32.046Z,1694304572.046 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-09-10T00:09:32.049Z,1694304572.049 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-09-10T00:09:32.052Z,1694304572.052 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-09-10T00:09:32.055Z,1694304572.055 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-09-10T00:09:32.102Z,1694304572.102 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-09-10T00:09:32.116Z,1694304572.116 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-10T00:09:32.170Z,1694304572.170 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-09-10T00:09:32.171Z,1694304572.171 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-09-10T00:09:32.219Z,1694304572.219 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-09-10T00:09:32.222Z,1694304572.222 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-09-10T00:09:32.249Z,1694304572.249 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-09-10T00:09:32.252Z,1694304572.252 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-09-10T00:09:32.253Z,1694304572.253 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-09-10T00:09:32.277Z,1694304572.277 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-09-10T00:09:32.294Z,1694304572.294 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-09-10T00:09:32.317Z,1694304572.317 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-10T00:09:32.317Z,1694304572.317 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-10T00:09:32.335Z,1694304572.335 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2023-09-10T00:09:32.338Z,1694304572.338 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-10T00:09:32.341Z,1694304572.341 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-10T00:09:32.345Z,1694304572.345 [transit:MassHold.Pitch](DEBUG): Construct.
2023-09-10T00:09:32.364Z,1694304572.364 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-09-10T00:09:32.389Z,1694304572.389 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-09-10T00:09:32.392Z,1694304572.392 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-09-10T00:09:32.433Z,1694304572.433 [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-10T00:09:32.433Z,1694304572.433 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-09-10T00:09:41.227Z,1694304581.227 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute
2023-09-10T00:09:41.228Z,1694304581.228 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2023-09-10T00:09:41.242Z,1694304581.242 [CommandExec](IMPORTANT): got command set transit.Latitude 41.76107 degree
2023-09-10T00:09:41.242Z,1694304581.242 [CommandExec](IMPORTANT): got command set transit.Longitude -81.27857 degree
2023-09-10T00:09:41.243Z,1694304581.243 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-09-10T00:09:41.244Z,1694304581.244 [CommandExec](IMPORTANT): got command set transit.MaxDepth 3 meter
2023-09-10T00:09:47.199Z,1694304587.199 [CommandExec](IMPORTANT): got command set transit.MinOffshore .01 kilometer
2023-09-10T00:09:47.200Z,1694304587.200 [CommandExec](IMPORTANT): got command set transit.MinAltitude 2 meter
2023-09-10T00:09:47.200Z,1694304587.200 [CommandExec](IMPORTANT): got command set transit:BackseatDriver.EnableBackseat 1 bool
2023-09-10T00:09:47.201Z,1694304587.201 [CommandExec](IMPORTANT): got command run
2023-09-10T00:09:47.203Z,1694304587.203 [CommandExec](IMPORTANT): Running 
2023-09-10T00:09:47.448Z,1694304587.448 [Default] Stopped
2023-09-10T00:09:47.448Z,1694304587.448 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-10T00:09:47.448Z,1694304587.448 [Default:B.GoToSurface] Stopped
2023-09-10T00:09:47.448Z,1694304587.448 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-10T00:09:47.448Z,1694304587.448 [Default:CheckIn] Stopped
2023-09-10T00:09:47.448Z,1694304587.448 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-10T00:09:47.448Z,1694304587.448 [Default:CheckIn:C.Wait] Stopped
2023-09-10T00:09:47.449Z,1694304587.449 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-10T00:09:47.449Z,1694304587.449 [MissionManager](IMPORTANT): Started mission transit
2023-09-10T00:09:47.449Z,1694304587.449 [transit] Running Loop=1
2023-09-10T00:09:47.449Z,1694304587.449 [transit](DEBUG): Aggregate::initialize transit
2023-09-10T00:09:47.449Z,1694304587.449 [transit:StandardEnvelopes] Running Loop=1
2023-09-10T00:09:47.449Z,1694304587.449 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-09-10T00:09:47.450Z,1694304587.450 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-10T00:09:47.450Z,1694304587.450 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-09-10T00:09:47.450Z,1694304587.450 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-10T00:09:47.450Z,1694304587.450 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-09-10T00:09:47.450Z,1694304587.450 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-10T00:09:47.450Z,1694304587.450 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-09-10T00:09:47.450Z,1694304587.450 [transit:BackseatDriver] Running Loop=1
2023-09-10T00:09:47.451Z,1694304587.451 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2023-09-10T00:09:47.451Z,1694304587.451 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-10T00:09:47.451Z,1694304587.451 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-10T00:09:47.451Z,1694304587.451 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-09-10T00:09:47.451Z,1694304587.451 [transit:MassHold.Pitch] Running Loop=1
2023-09-10T00:09:47.451Z,1694304587.451 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-09-10T00:09:47.451Z,1694304587.451 [transit:B] Running Loop=1
2023-09-10T00:09:47.452Z,1694304587.452 [transit:MassHold.Pitch] Running Loop=1
2023-09-10T00:09:47.452Z,1694304587.452 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-10T00:09:47.452Z,1694304587.452 [transit:BackseatDriver] Running Loop=1
2023-09-10T00:09:47.453Z,1694304587.453 [transit:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2023-09-10T00:09:47.470Z,1694304587.470 [transit:StandardEnvelopes] Running Loop=1
2023-09-10T00:09:47.470Z,1694304587.470 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-10T00:09:47.494Z,1694304587.494 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-10T00:09:47.495Z,1694304587.495 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-10T00:09:47.496Z,1694304587.496 [transit:B] Stopped
2023-09-10T00:09:47.496Z,1694304587.496 [transit:C] Running Loop=1
2023-09-10T00:09:47.613Z,1694304587.613 [BackseatComponent](INFO): Powering up
2023-09-10T00:09:47.614Z,1694304587.614 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-09-10T00:09:47.835Z,1694304587.835 [Power24vConverter](INFO): Powering up.
2023-09-10T00:09:47.873Z,1694304587.873 [transit:C] Stopped
2023-09-10T00:09:47.873Z,1694304587.873 [transit:E] Running Loop=1
2023-09-10T00:09:48.257Z,1694304588.257 [transit:E] Stopped
2023-09-10T00:09:48.257Z,1694304588.257 [transit:F] Running Loop=1
2023-09-10T00:09:48.661Z,1694304588.661 [transit:F] Stopped
2023-09-10T00:09:48.661Z,1694304588.661 [transit:G] Running Loop=1
2023-09-10T00:09:49.069Z,1694304589.069 [transit:G] Stopped
2023-09-10T00:09:49.069Z,1694304589.069 [transit:Transit] Running Loop=1
2023-09-10T00:09:49.070Z,1694304589.070 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-09-10T00:09:49.070Z,1694304589.070 [transit:Transit:A.Pitch] Running Loop=1
2023-09-10T00:09:49.070Z,1694304589.070 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-09-10T00:09:49.070Z,1694304589.070 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-10T00:09:49.070Z,1694304589.070 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-09-10T00:09:49.070Z,1694304589.070 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-09-10T00:09:49.070Z,1694304589.070 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-09-10T00:09:49.072Z,1694304589.072 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 41.761070,-81.278572
2023-09-10T00:09:49.073Z,1694304589.073 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2023-09-10T00:09:49.481Z,1694304589.481 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-10T00:09:49.481Z,1694304589.481 [transit:Transit:A.Pitch] Running Loop=1
2023-09-10T00:10:48.313Z,1694304648.313 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20230910T001045
2023-09-10T00:11:20.302Z,1694304680.302 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T001119
2023-09-10T00:11:28.169Z,1694304688.169 [DataOverHttps](INFO): Received command: stop
2023-09-10T00:11:28.234Z,1694304688.234 [CommandExec](IMPORTANT): got command stop
2023-09-10T00:11:28.234Z,1694304688.234 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-09-10T00:11:28.450Z,1694304688.450 [MissionManager](INFO): MissionManager is completed.
2023-09-10T00:11:28.450Z,1694304688.450 [MissionManager](INFO): Uninitializing Mission transit
2023-09-10T00:11:28.450Z,1694304688.450 [transit] Stopped
2023-09-10T00:11:28.450Z,1694304688.450 [transit](DEBUG): Aggregate::uninitialize transit
2023-09-10T00:11:28.450Z,1694304688.450 [transit:StandardEnvelopes] Stopped
2023-09-10T00:11:28.450Z,1694304688.450 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2023-09-10T00:11:28.450Z,1694304688.450 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2023-09-10T00:11:28.451Z,1694304688.451 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2023-09-10T00:11:28.451Z,1694304688.451 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2023-09-10T00:11:28.451Z,1694304688.451 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2023-09-10T00:11:28.451Z,1694304688.451 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2023-09-10T00:11:28.451Z,1694304688.451 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2023-09-10T00:11:28.451Z,1694304688.451 [transit:BackseatDriver] Stopped
2023-09-10T00:11:28.451Z,1694304688.451 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2023-09-10T00:11:28.451Z,1694304688.451 [transit:BackseatDriver:A.BackseatDriver] Stopped
2023-09-10T00:11:28.451Z,1694304688.451 [transit:BuoyancyHold.Buoyancy] Stopped
2023-09-10T00:11:28.451Z,1694304688.451 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-09-10T00:11:28.451Z,1694304688.451 [transit:MassHold.Pitch] Stopped
2023-09-10T00:11:28.451Z,1694304688.451 [transit:Transit] Stopped
2023-09-10T00:11:28.452Z,1694304688.452 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2023-09-10T00:11:28.452Z,1694304688.452 [transit:Transit:A.Pitch] Stopped
2023-09-10T00:11:28.452Z,1694304688.452 [transit:Transit:B.SetSpeed] Stopped
2023-09-10T00:11:28.452Z,1694304688.452 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2023-09-10T00:11:28.452Z,1694304688.452 [transit:Transit:Wpt1.Waypoint] Stopped
2023-09-10T00:11:28.452Z,1694304688.452 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2023-09-10T00:11:28.858Z,1694304688.858 [MissionManager](IMPORTANT): Started mission Default
2023-09-10T00:11:28.859Z,1694304688.859 [Default] Running Loop=1
2023-09-10T00:11:28.859Z,1694304688.859 [Default](DEBUG): Aggregate::initialize Default
2023-09-10T00:11:28.859Z,1694304688.859 [Default:B.GoToSurface] Running Loop=1
2023-09-10T00:11:28.859Z,1694304688.859 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-10T00:11:28.859Z,1694304688.859 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-10T00:11:28.859Z,1694304688.859 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-10T00:11:28.860Z,1694304688.860 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-10T00:11:28.860Z,1694304688.860 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-10T00:11:28.860Z,1694304688.860 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-10T00:11:28.861Z,1694304688.861 [Default:A.Wait] Running Loop=1
2023-09-10T00:11:28.861Z,1694304688.861 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-10T00:11:30.346Z,1694304690.346 [BackseatComponent](INFO): Sent LCM shutdown request.
2023-09-10T00:11:30.347Z,1694304690.347 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2023-09-10T00:11:42.190Z,1694304702.190 [Default:A.Wait](INFO): Done Waiting.
2023-09-10T00:11:42.191Z,1694304702.191 [Default:A.Wait] Stopped
2023-09-10T00:11:42.191Z,1694304702.191 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-10T00:11:42.585Z,1694304702.585 [Default:CheckIn] Running Loop=1
2023-09-10T00:11:42.585Z,1694304702.585 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-10T00:11:42.585Z,1694304702.585 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-10T00:11:44.600Z,1694304704.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001143.00,A,4145.53036,N,08116.65327,W,0.019,2.21,100923,,,D*72
2023-09-10T00:11:44.602Z,1694304704.602 [NAL9602](INFO): GPS fix at 20230910T001143: (41.758839, -81.277554)
2023-09-10T00:11:44.613Z,1694304704.613 [Default:CheckIn:Read_GPS] Stopped
2023-09-10T00:11:44.613Z,1694304704.613 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-10T00:11:48.525Z,1694304708.525 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2023-09-10T00:11:48.526Z,1694304708.526 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2023-09-10T00:11:48.526Z,1694304708.526 [LcmUniversalReporter](INFO): Deactivating messaging.
2023-09-10T00:11:53.664Z,1694304713.664 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230909T231941/Courier0031.lzma
2023-09-10T00:11:54.667Z,1694304714.667 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0031.lzma.bak
2023-09-10T00:11:54.667Z,1694304714.667 [DataOverHttps](IMPORTANT): SBD MOMSN=18756418, MTMSN=20230910T001153
2023-09-10T00:12:05.077Z,1694304725.077 [DataOverHttps](INFO): Received command: schedule resume
2023-09-10T00:12:05.123Z,1694304725.123 [CommandExec](IMPORTANT): got command schedule resume
2023-09-10T00:12:05.123Z,1694304725.123 [CommandExec](IMPORTANT): Scheduling is resumed
2023-09-10T00:12:05.211Z,1694304725.211 [NAL9602](INFO): SBD MO Status=1, MOMSN=3892, MT Status=0, MTMSN=0
2023-09-10T00:12:05.212Z,1694304725.212 [NAL9602](INFO): No messages in MT queue
2023-09-10T00:12:13.419Z,1694304733.419 [DataOverHttps](INFO): Sending 1508 bytes from file Logs/20230909T231941/Express0032.lzma
2023-09-10T00:12:14.419Z,1694304734.419 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0032.lzma.bak
2023-09-10T00:12:14.419Z,1694304734.419 [DataOverHttps](INFO): SBD MOMSN=18756422
2023-09-10T00:12:35.673Z,1694304755.673 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230909T231941/Courier0036.lzma
2023-09-10T00:12:35.958Z,1694304755.958 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-10T00:12:36.675Z,1694304756.675 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0036.lzma.bak
2023-09-10T00:12:36.676Z,1694304756.676 [DataOverHttps](INFO): SBD MOMSN=18756478
2023-09-10T00:12:57.525Z,1694304777.525 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230909T231941/Express0038.lzma
2023-09-10T00:12:58.527Z,1694304778.527 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0038.lzma.bak
2023-09-10T00:12:58.527Z,1694304778.527 [DataOverHttps](INFO): SBD MOMSN=18756480
2023-09-10T00:13:03.106Z,1694304783.106 [Default:CheckIn:Read_Iridium] Stopped
2023-09-10T00:13:03.107Z,1694304783.107 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-10T00:13:03.107Z,1694304783.107 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-10T00:18:03.811Z,1694305083.811 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-10T00:18:03.811Z,1694305083.811 [Default:CheckIn:C.Wait] Stopped
2023-09-10T00:18:03.811Z,1694305083.811 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-10T00:18:03.812Z,1694305083.812 [Default:CheckIn:D] Running Loop=1
2023-09-10T00:18:04.208Z,1694305084.208 [Default:CheckIn:D] Stopped
2023-09-10T00:18:04.208Z,1694305084.208 [Default:CheckIn:E] Running Loop=1
2023-09-10T00:18:04.612Z,1694305084.612 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.589157 min 
2023-09-10T00:18:04.613Z,1694305084.613 [Default:CheckIn:E] Stopped
2023-09-10T00:18:04.613Z,1694305084.613 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-10T00:18:04.613Z,1694305084.613 [Default:CheckIn] Stopped
2023-09-10T00:18:04.613Z,1694305084.613 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-10T00:18:04.613Z,1694305084.613 [Default:CheckIn](INFO): Running loop #2
2023-09-10T00:18:04.613Z,1694305084.613 [Default:CheckIn] Running Loop=2
2023-09-10T00:18:04.613Z,1694305084.613 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-10T00:18:04.614Z,1694305084.614 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-10T00:18:06.624Z,1694305086.624 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001806.00,A,4145.53067,N,08116.65422,W,0.019,2.21,100923,,,D*7C
2023-09-10T00:18:06.626Z,1694305086.626 [NAL9602](INFO): GPS fix at 20230910T001806: (41.758845, -81.277570)
2023-09-10T00:18:06.652Z,1694305086.652 [Default:CheckIn:Read_GPS] Stopped
2023-09-10T00:18:06.652Z,1694305086.652 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-10T00:18:10.846Z,1694305090.846 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230909T231941/Courier0040.lzma
2023-09-10T00:18:11.851Z,1694305091.851 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0040.lzma.bak
2023-09-10T00:18:11.851Z,1694305091.851 [DataOverHttps](INFO): SBD MOMSN=18756496
2023-09-10T00:18:22.023Z,1694305102.023 [NAL9602](INFO): SBD MO Status=1, MOMSN=3893, MT Status=0, MTMSN=0
2023-09-10T00:18:22.023Z,1694305102.023 [NAL9602](INFO): No messages in MT queue
2023-09-10T00:18:32.601Z,1694305112.601 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20230909T231941/Express0041.lzma
2023-09-10T00:18:33.603Z,1694305113.603 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0041.lzma.bak
2023-09-10T00:18:33.603Z,1694305113.603 [DataOverHttps](INFO): SBD MOMSN=18756503
2023-09-10T00:18:39.918Z,1694305119.918 [Default:CheckIn:Read_Iridium] Stopped
2023-09-10T00:18:39.918Z,1694305119.918 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-10T00:18:39.918Z,1694305119.918 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-10T00:18:52.806Z,1694305132.806 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-10T00:21:49.354Z,1694305309.354 [Power24vConverter](INFO): Powering down.
2023-09-10T00:23:40.547Z,1694305420.547 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-10T00:23:40.547Z,1694305420.547 [Default:CheckIn:C.Wait] Stopped
2023-09-10T00:23:40.547Z,1694305420.547 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-10T00:23:40.548Z,1694305420.548 [Default:CheckIn:D] Running Loop=1
2023-09-10T00:23:40.972Z,1694305420.972 [Default:CheckIn:D] Stopped
2023-09-10T00:23:40.972Z,1694305420.972 [Default:CheckIn:E] Running Loop=1
2023-09-10T00:23:41.415Z,1694305421.415 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.201893 min 
2023-09-10T00:23:41.415Z,1694305421.415 [Default:CheckIn:E] Stopped
2023-09-10T00:23:41.415Z,1694305421.415 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-10T00:23:41.416Z,1694305421.416 [Default:CheckIn] Stopped
2023-09-10T00:23:41.416Z,1694305421.416 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-10T00:23:41.416Z,1694305421.416 [Default:CheckIn](INFO): Running loop #3
2023-09-10T00:23:41.416Z,1694305421.416 [Default:CheckIn] Running Loop=3
2023-09-10T00:23:41.416Z,1694305421.416 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-10T00:23:41.416Z,1694305421.416 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-10T00:23:43.369Z,1694305423.369 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002342.00,A,4145.53146,N,08116.65392,W,0.875,25.16,100923,,,D*49
2023-09-10T00:23:43.371Z,1694305423.371 [NAL9602](INFO): GPS fix at 20230910T002342: (41.758858, -81.277565)
2023-09-10T00:23:43.382Z,1694305423.382 [Default:CheckIn:Read_GPS] Stopped
2023-09-10T00:23:43.382Z,1694305423.382 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-10T00:23:51.960Z,1694305431.960 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230909T231941/Courier0043.lzma
2023-09-10T00:23:52.963Z,1694305432.963 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0043.lzma.bak
2023-09-10T00:23:52.963Z,1694305432.963 [DataOverHttps](INFO): SBD MOMSN=18756516
2023-09-10T00:24:05.192Z,1694305445.192 [NAL9602](INFO): SBD MO Status=1, MOMSN=3894, MT Status=0, MTMSN=0
2023-09-10T00:24:05.192Z,1694305445.192 [NAL9602](INFO): No messages in MT queue
2023-09-10T00:24:14.285Z,1694305454.285 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230909T231941/Express0044.lzma
2023-09-10T00:24:15.287Z,1694305455.287 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Express0044.lzma.bak
2023-09-10T00:24:15.287Z,1694305455.287 [DataOverHttps](INFO): SBD MOMSN=18756519
2023-09-10T00:24:19.340Z,1694305459.340 [Default:CheckIn:Read_Iridium] Stopped
2023-09-10T00:24:19.340Z,1694305459.340 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-10T00:24:19.341Z,1694305459.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-10T00:24:35.954Z,1694305475.954 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-10T00:26:22.199Z,1694305582.199 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T002621
2023-09-10T00:26:29.517Z,1694305589.517 [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" ijv1 1 2
2023-09-10T00:26:29.589Z,1694305589.589 [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" ijv1 1 2.000000
2023-09-10T00:26:29.590Z,1694305589.590 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=ijv1
2023-09-10T00:26:29.591Z,1694305589.591 [CommandExec](IMPORTANT): Scheduled #3 (#1 of 2 with id='ijv1'): "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-10T00:26:30.519Z,1694305590.519 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T002629
2023-09-10T00:26:40.465Z,1694305600.465 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" ijv1 2 2
2023-09-10T00:26:40.496Z,1694305600.496 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" ijv1 2 2.000000
2023-09-10T00:26:40.497Z,1694305600.497 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=ijv1
2023-09-10T00:26:40.498Z,1694305600.498 [CommandExec](IMPORTANT): Scheduled #4 (#2 of 2 with id='ijv1'): "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run", ASAP
2023-09-10T00:26:40.610Z,1694305600.610 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-09-10T00:26:40.610Z,1694305600.610 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-09-10T00:26:40.611Z,1694305600.611 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-09-10T00:26:40.743Z,1694305600.743 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-09-10T00:26:40.752Z,1694305600.752 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-09-10T00:26:40.754Z,1694305600.754 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-09-10T00:26:40.756Z,1694305600.756 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-09-10T00:26:40.758Z,1694305600.758 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-09-10T00:26:40.760Z,1694305600.760 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-09-10T00:26:40.778Z,1694305600.778 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-09-10T00:26:40.781Z,1694305600.781 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-09-10T00:26:40.783Z,1694305600.783 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-09-10T00:26:40.785Z,1694305600.785 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-09-10T00:26:40.787Z,1694305600.787 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-09-10T00:26:40.789Z,1694305600.789 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-09-10T00:26:40.845Z,1694305600.845 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-09-10T00:26:40.845Z,1694305600.845 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-09-10T00:26:40.846Z,1694305600.846 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-09-10T00:26:41.023Z,1694305601.023 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-09-10T00:26:41.030Z,1694305601.030 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-09-10T00:26:41.032Z,1694305601.032 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-09-10T00:26:41.046Z,1694305601.046 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-09-10T00:26:41.048Z,1694305601.048 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-09-10T00:26:41.054Z,1694305601.054 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-09-10T00:26:41.056Z,1694305601.056 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-09-10T00:26:41.063Z,1694305601.063 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-09-10T00:26:41.073Z,1694305601.073 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-10T00:26:41.152Z,1694305601.152 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-09-10T00:26:41.152Z,1694305601.152 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-09-10T00:26:41.202Z,1694305601.202 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-09-10T00:26:41.204Z,1694305601.204 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-09-10T00:26:41.206Z,1694305601.206 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-09-10T00:26:41.208Z,1694305601.208 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-09-10T00:26:41.221Z,1694305601.221 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-09-10T00:26:41.227Z,1694305601.227 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-09-10T00:26:41.232Z,1694305601.232 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-09-10T00:26:41.317Z,1694305601.317 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-10T00:26:41.329Z,1694305601.329 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-10T00:26:41.364Z,1694305601.364 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2023-09-10T00:26:41.376Z,1694305601.376 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-10T00:26:41.395Z,1694305601.395 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-10T00:26:41.402Z,1694305601.402 [transit:MassHold.Pitch](DEBUG): Construct.
2023-09-10T00:26:41.424Z,1694305601.424 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-09-10T00:26:41.436Z,1694305601.436 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-09-10T00:26:41.454Z,1694305601.454 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-09-10T00:26:41.467Z,1694305601.467 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T002640
2023-09-10T00:26:41.495Z,1694305601.495 [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-10T00:26:41.495Z,1694305601.495 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-09-10T00:26:56.577Z,1694305616.577 [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" ijw6 1 2
2023-09-10T00:26:57.580Z,1694305617.580 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T002656
2023-09-10T00:26:58.503Z,1694305618.503 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute
2023-09-10T00:26:58.504Z,1694305618.504 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2023-09-10T00:26:58.505Z,1694305618.505 [CommandExec](IMPORTANT): got command set transit.Latitude 41.76107 degree
2023-09-10T00:26:58.506Z,1694305618.506 [CommandExec](IMPORTANT): got command set transit.Longitude -81.27857 degree
2023-09-10T00:26:58.506Z,1694305618.506 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-09-10T00:26:58.507Z,1694305618.507 [CommandExec](IMPORTANT): got command set transit.MaxDepth 3 meter
2023-09-10T00:26:58.548Z,1694305618.548 [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" ijw6 1 2.000000
2023-09-10T00:26:58.549Z,1694305618.549 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=ijw6
2023-09-10T00:26:58.550Z,1694305618.550 [CommandExec](IMPORTANT): Scheduled #5 (#1 of 2 with id='ijw6'): "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-10T00:27:11.219Z,1694305631.219 [CommandExec](IMPORTANT): got command set transit.MinOffshore .01 kilometer
2023-09-10T00:27:11.220Z,1694305631.220 [CommandExec](IMPORTANT): got command set transit.MinAltitude 2 meter
2023-09-10T00:27:11.220Z,1694305631.220 [CommandExec](IMPORTANT): got command set transit:BackseatDriver.EnableBackseat 1 bool
2023-09-10T00:27:11.285Z,1694305631.285 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" ijw6 2 2
2023-09-10T00:27:11.313Z,1694305631.313 [CommandExec](IMPORTANT): got command run
2023-09-10T00:27:11.315Z,1694305631.315 [CommandExec](IMPORTANT): Running 
2023-09-10T00:27:11.335Z,1694305631.335 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" ijw6 2 2.000000
2023-09-10T00:27:11.336Z,1694305631.336 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=ijw6
2023-09-10T00:27:11.352Z,1694305631.352 [Default] Stopped
2023-09-10T00:27:11.353Z,1694305631.353 [CommandExec](IMPORTANT): Scheduled #6 (#2 of 2 with id='ijw6'): "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run", ASAP
2023-09-10T00:27:11.353Z,1694305631.353 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-10T00:27:11.353Z,1694305631.353 [Default:B.GoToSurface] Stopped
2023-09-10T00:27:11.353Z,1694305631.353 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-10T00:27:11.353Z,1694305631.353 [Default:CheckIn] Stopped
2023-09-10T00:27:11.353Z,1694305631.353 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-10T00:27:11.353Z,1694305631.353 [Default:CheckIn:C.Wait] Stopped
2023-09-10T00:27:11.353Z,1694305631.353 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-10T00:27:11.354Z,1694305631.354 [MissionManager](IMPORTANT): Started mission transit
2023-09-10T00:27:11.354Z,1694305631.354 [transit] Running Loop=1
2023-09-10T00:27:11.354Z,1694305631.354 [transit](DEBUG): Aggregate::initialize transit
2023-09-10T00:27:11.354Z,1694305631.354 [transit:StandardEnvelopes] Running Loop=1
2023-09-10T00:27:11.354Z,1694305631.354 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-09-10T00:27:11.354Z,1694305631.354 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-10T00:27:11.354Z,1694305631.354 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-09-10T00:27:11.355Z,1694305631.355 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-10T00:27:11.355Z,1694305631.355 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-09-10T00:27:11.355Z,1694305631.355 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-10T00:27:11.355Z,1694305631.355 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-09-10T00:27:11.355Z,1694305631.355 [transit:BackseatDriver] Running Loop=1
2023-09-10T00:27:11.355Z,1694305631.355 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2023-09-10T00:27:11.355Z,1694305631.355 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-10T00:27:11.355Z,1694305631.355 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-10T00:27:11.355Z,1694305631.355 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-09-10T00:27:11.355Z,1694305631.355 [transit:MassHold.Pitch] Running Loop=1
2023-09-10T00:27:11.356Z,1694305631.356 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-09-10T00:27:11.356Z,1694305631.356 [transit:B] Running Loop=1
2023-09-10T00:27:11.356Z,1694305631.356 [transit:MassHold.Pitch] Running Loop=1
2023-09-10T00:27:11.357Z,1694305631.357 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-10T00:27:11.374Z,1694305631.374 [transit:BackseatDriver] Running Loop=1
2023-09-10T00:27:11.374Z,1694305631.374 [transit:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2023-09-10T00:27:11.451Z,1694305631.451 [transit:StandardEnvelopes] Running Loop=1
2023-09-10T00:27:11.451Z,1694305631.451 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-10T00:27:11.455Z,1694305631.455 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-09-10T00:27:11.455Z,1694305631.455 [MissionManager](INFO): Uninitializing Mission transit
2023-09-10T00:27:11.457Z,1694305631.457 [BackseatComponent](INFO): Powering up
2023-09-10T00:27:11.458Z,1694305631.458 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-09-10T00:27:11.482Z,1694305631.482 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-10T00:27:11.483Z,1694305631.483 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-10T00:27:11.483Z,1694305631.483 [transit:B] Stopped
2023-09-10T00:27:11.483Z,1694305631.483 [transit:C] Running Loop=1
2023-09-10T00:27:11.483Z,1694305631.483 [transit] Stopped
2023-09-10T00:27:11.484Z,1694305631.484 [transit](DEBUG): Aggregate::uninitialize transit
2023-09-10T00:27:11.484Z,1694305631.484 [transit:C] Stopped
2023-09-10T00:27:11.484Z,1694305631.484 [transit:StandardEnvelopes] Stopped
2023-09-10T00:27:11.484Z,1694305631.484 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2023-09-10T00:27:11.484Z,1694305631.484 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2023-09-10T00:27:11.484Z,1694305631.484 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2023-09-10T00:27:11.484Z,1694305631.484 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2023-09-10T00:27:11.484Z,1694305631.484 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2023-09-10T00:27:11.484Z,1694305631.484 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2023-09-10T00:27:11.484Z,1694305631.484 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2023-09-10T00:27:11.484Z,1694305631.484 [transit:BackseatDriver] Stopped
2023-09-10T00:27:11.484Z,1694305631.484 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2023-09-10T00:27:11.484Z,1694305631.484 [transit:BackseatDriver:A.BackseatDriver] Stopped
2023-09-10T00:27:11.485Z,1694305631.485 [transit:BuoyancyHold.Buoyancy] Stopped
2023-09-10T00:27:11.485Z,1694305631.485 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-09-10T00:27:11.493Z,1694305631.493 [transit:MassHold.Pitch] Stopped
2023-09-10T00:27:11.546Z,1694305631.546 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-09-10T00:27:11.546Z,1694305631.546 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-09-10T00:27:11.674Z,1694305631.674 [Power24vConverter](INFO): Powering up.
2023-09-10T00:27:11.842Z,1694305631.842 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-09-10T00:27:11.844Z,1694305631.844 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-09-10T00:27:11.863Z,1694305631.863 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-09-10T00:27:11.877Z,1694305631.877 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-09-10T00:27:11.879Z,1694305631.879 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-09-10T00:27:11.881Z,1694305631.881 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-09-10T00:27:11.883Z,1694305631.883 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-09-10T00:27:11.906Z,1694305631.906 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-09-10T00:27:11.908Z,1694305631.908 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-09-10T00:27:11.910Z,1694305631.910 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-09-10T00:27:11.912Z,1694305631.912 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-09-10T00:27:11.926Z,1694305631.926 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-09-10T00:27:11.928Z,1694305631.928 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-09-10T00:27:11.929Z,1694305631.929 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-09-10T00:27:11.929Z,1694305631.929 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-09-10T00:27:12.123Z,1694305632.123 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-09-10T00:27:12.145Z,1694305632.145 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-09-10T00:27:12.147Z,1694305632.147 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-09-10T00:27:12.153Z,1694305632.153 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-09-10T00:27:12.155Z,1694305632.155 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-09-10T00:27:12.170Z,1694305632.170 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-09-10T00:27:12.172Z,1694305632.172 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-09-10T00:27:12.178Z,1694305632.178 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-09-10T00:27:12.196Z,1694305632.196 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-10T00:27:12.265Z,1694305632.265 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-09-10T00:27:12.265Z,1694305632.265 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-09-10T00:27:12.343Z,1694305632.343 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-09-10T00:27:12.357Z,1694305632.357 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-09-10T00:27:12.359Z,1694305632.359 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-09-10T00:27:12.366Z,1694305632.366 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-09-10T00:27:12.367Z,1694305632.367 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-09-10T00:27:12.390Z,1694305632.390 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-09-10T00:27:12.431Z,1694305632.431 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-09-10T00:27:12.509Z,1694305632.509 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-10T00:27:12.509Z,1694305632.509 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-10T00:27:12.555Z,1694305632.555 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2023-09-10T00:27:12.577Z,1694305632.577 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-10T00:27:12.580Z,1694305632.580 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-10T00:27:12.588Z,1694305632.588 [transit:MassHold.Pitch](DEBUG): Construct.
2023-09-10T00:27:12.633Z,1694305632.633 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-09-10T00:27:12.673Z,1694305632.673 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-09-10T00:27:12.676Z,1694305632.676 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-09-10T00:27:12.722Z,1694305632.722 [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-10T00:27:12.723Z,1694305632.723 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-09-10T00:27:12.957Z,1694305632.957 [MissionManager](IMPORTANT): Started mission Default
2023-09-10T00:27:12.957Z,1694305632.957 [Default] Running Loop=1
2023-09-10T00:27:12.957Z,1694305632.957 [Default](DEBUG): Aggregate::initialize Default
2023-09-10T00:27:12.958Z,1694305632.958 [Default:B.GoToSurface] Running Loop=1
2023-09-10T00:27:12.958Z,1694305632.958 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-10T00:27:12.958Z,1694305632.958 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-10T00:27:12.958Z,1694305632.958 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-10T00:27:12.958Z,1694305632.958 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-10T00:27:12.959Z,1694305632.959 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-10T00:27:12.959Z,1694305632.959 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-10T00:27:12.959Z,1694305632.959 [Default:A.Wait] Running Loop=1
2023-09-10T00:27:12.959Z,1694305632.959 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-10T00:27:26.335Z,1694305646.335 [Default:A.Wait](INFO): Done Waiting.
2023-09-10T00:27:26.336Z,1694305646.336 [Default:A.Wait] Stopped
2023-09-10T00:27:26.336Z,1694305646.336 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-10T00:27:26.717Z,1694305646.717 [Default:CheckIn] Running Loop=1
2023-09-10T00:27:26.721Z,1694305646.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-10T00:27:26.721Z,1694305646.721 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-10T00:27:26.923Z,1694305646.923 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute
2023-09-10T00:27:26.923Z,1694305646.923 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2023-09-10T00:27:26.924Z,1694305646.924 [CommandExec](IMPORTANT): got command set transit.Latitude 41.76107 degree
2023-09-10T00:27:26.925Z,1694305646.925 [CommandExec](IMPORTANT): got command set transit.Longitude -81.27857 degree
2023-09-10T00:27:26.926Z,1694305646.926 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-09-10T00:27:26.926Z,1694305646.926 [CommandExec](IMPORTANT): got command set transit.MaxDepth 3 meter
2023-09-10T00:27:28.708Z,1694305648.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002728.00,A,4145.54242,N,08116.65286,W,0.272,358.55,100923,,,D*76
2023-09-10T00:27:28.710Z,1694305648.710 [NAL9602](INFO): GPS fix at 20230910T002728: (41.759040, -81.277548)
2023-09-10T00:27:28.761Z,1694305648.761 [Default:CheckIn:Read_GPS] Stopped
2023-09-10T00:27:28.761Z,1694305648.761 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-10T00:27:32.330Z,1694305652.330 [BackseatComponent](INFO): Sent LCM shutdown request.
2023-09-10T00:27:32.331Z,1694305652.331 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2023-09-10T00:27:35.333Z,1694305655.333 [CommandExec](IMPORTANT): got command set transit.MinOffshore .01 kilometer
2023-09-10T00:27:35.338Z,1694305655.338 [CommandExec](IMPORTANT): got command set transit.MinAltitude 2 meter
2023-09-10T00:27:35.338Z,1694305655.338 [CommandExec](IMPORTANT): got command set transit:BackseatDriver.EnableBackseat 1 bool
2023-09-10T00:27:35.339Z,1694305655.339 [CommandExec](IMPORTANT): got command run
2023-09-10T00:27:35.341Z,1694305655.341 [CommandExec](IMPORTANT): Running 
2023-09-10T00:27:35.479Z,1694305655.479 [Default] Stopped
2023-09-10T00:27:35.479Z,1694305655.479 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-10T00:27:35.480Z,1694305655.480 [Default:B.GoToSurface] Stopped
2023-09-10T00:27:35.480Z,1694305655.480 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-10T00:27:35.480Z,1694305655.480 [Default:CheckIn] Stopped
2023-09-10T00:27:35.480Z,1694305655.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-10T00:27:35.480Z,1694305655.480 [Default:CheckIn:Read_Iridium] Stopped
2023-09-10T00:27:35.480Z,1694305655.480 [MissionManager](IMPORTANT): Started mission transit
2023-09-10T00:27:35.480Z,1694305655.480 [transit] Running Loop=1
2023-09-10T00:27:35.480Z,1694305655.480 [transit](DEBUG): Aggregate::initialize transit
2023-09-10T00:27:35.481Z,1694305655.481 [transit:StandardEnvelopes] Running Loop=1
2023-09-10T00:27:35.481Z,1694305655.481 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-09-10T00:27:35.481Z,1694305655.481 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-10T00:27:35.481Z,1694305655.481 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-09-10T00:27:35.481Z,1694305655.481 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-10T00:27:35.481Z,1694305655.481 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-09-10T00:27:35.481Z,1694305655.481 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-10T00:27:35.481Z,1694305655.481 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-09-10T00:27:35.481Z,1694305655.481 [transit:BackseatDriver] Running Loop=1
2023-09-10T00:27:35.482Z,1694305655.482 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2023-09-10T00:27:35.482Z,1694305655.482 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-10T00:27:35.482Z,1694305655.482 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-10T00:27:35.482Z,1694305655.482 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-09-10T00:27:35.482Z,1694305655.482 [transit:MassHold.Pitch] Running Loop=1
2023-09-10T00:27:35.482Z,1694305655.482 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-09-10T00:27:35.482Z,1694305655.482 [transit:B] Running Loop=1
2023-09-10T00:27:35.483Z,1694305655.483 [transit:MassHold.Pitch] Running Loop=1
2023-09-10T00:27:35.483Z,1694305655.483 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-10T00:27:35.483Z,1694305655.483 [transit:BackseatDriver] Running Loop=1
2023-09-10T00:27:35.484Z,1694305655.484 [transit:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2023-09-10T00:27:35.575Z,1694305655.575 [transit:StandardEnvelopes] Running Loop=1
2023-09-10T00:27:35.575Z,1694305655.575 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-10T00:27:35.595Z,1694305655.595 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-10T00:27:35.595Z,1694305655.595 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-10T00:27:35.596Z,1694305655.596 [transit:B] Stopped
2023-09-10T00:27:35.596Z,1694305655.596 [transit:C] Running Loop=1
2023-09-10T00:27:35.913Z,1694305655.913 [transit:C] Stopped
2023-09-10T00:27:35.913Z,1694305655.913 [transit:E] Running Loop=1
2023-09-10T00:27:36.289Z,1694305656.289 [transit:E] Stopped
2023-09-10T00:27:36.289Z,1694305656.289 [transit:F] Running Loop=1
2023-09-10T00:27:36.717Z,1694305656.717 [transit:F] Stopped
2023-09-10T00:27:36.717Z,1694305656.717 [transit:G] Running Loop=1
2023-09-10T00:27:37.093Z,1694305657.093 [transit:G] Stopped
2023-09-10T00:27:37.093Z,1694305657.093 [transit:Transit] Running Loop=1
2023-09-10T00:27:37.094Z,1694305657.094 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-09-10T00:27:37.094Z,1694305657.094 [transit:Transit:A.Pitch] Running Loop=1
2023-09-10T00:27:37.094Z,1694305657.094 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-09-10T00:27:37.094Z,1694305657.094 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-10T00:27:37.094Z,1694305657.094 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-09-10T00:27:37.094Z,1694305657.094 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-09-10T00:27:37.094Z,1694305657.094 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-09-10T00:27:37.095Z,1694305657.095 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 41.761070,-81.278572
2023-09-10T00:27:37.096Z,1694305657.096 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2023-09-10T00:27:37.498Z,1694305657.498 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-10T00:27:37.498Z,1694305657.498 [transit:Transit:A.Pitch] Running Loop=1
2023-09-10T00:27:39.812Z,1694305659.812 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230909T231941/Courier0046.lzma
2023-09-10T00:27:40.820Z,1694305660.820 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0046.lzma.bak
2023-09-10T00:27:40.821Z,1694305660.821 [DataOverHttps](INFO): SBD MOMSN=18756546
2023-09-10T00:27:43.729Z,1694305663.729 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:27:44.125Z,1694305664.125 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:27:44.541Z,1694305664.541 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:27:44.969Z,1694305664.969 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:27:45.301Z,1694305665.301 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:27:45.685Z,1694305665.685 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:27:46.086Z,1694305666.086 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-10T00:27:46.086Z,1694305666.086 [ThrusterHE](INFO): Powering down
2023-09-10T00:27:47.026Z,1694305667.026 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-10T00:27:47.146Z,1694305667.146 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-10T00:27:50.493Z,1694305670.493 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2023-09-10T00:27:50.494Z,1694305670.494 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2023-09-10T00:27:50.494Z,1694305670.494 [LcmUniversalReporter](INFO): Deactivating messaging.
2023-09-10T00:27:52.015Z,1694305672.015 [NAL9602](INFO): SBD MO Status=2, MOMSN=3895, MT Status=2, MTMSN=0
2023-09-10T00:27:52.015Z,1694305672.015 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-10T00:28:08.896Z,1694305688.896 [BackseatComponent](INFO): Powering up
2023-09-10T00:28:08.896Z,1694305688.896 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-09-10T00:28:11.765Z,1694305691.765 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:28:12.253Z,1694305692.253 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:28:12.505Z,1694305692.505 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:28:12.953Z,1694305692.953 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:28:14.945Z,1694305694.945 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:28:15.267Z,1694305695.267 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-10T00:28:15.267Z,1694305695.267 [ThrusterHE](INFO): Powering down
2023-09-10T00:28:16.122Z,1694305696.122 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-10T00:28:16.242Z,1694305696.242 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-10T00:28:21.789Z,1694305701.789 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-10T00:28:26.531Z,1694305706.531 [NAL9602](INFO): SBD MO Status=2, MOMSN=3895, MT Status=2, MTMSN=0
2023-09-10T00:28:26.531Z,1694305706.531 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-10T00:28:33.497Z,1694305713.497 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:28:33.921Z,1694305713.921 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:28:35.641Z,1694305715.641 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:28:35.901Z,1694305715.901 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:28:36.318Z,1694305716.318 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-10T00:28:36.318Z,1694305716.318 [ThrusterHE](INFO): Powering down
2023-09-10T00:28:37.215Z,1694305717.215 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-10T00:28:37.334Z,1694305717.334 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-10T00:28:46.979Z,1694305726.979 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T002846
2023-09-10T00:28:54.949Z,1694305734.949 [DataOverHttps](INFO): Received command: sched asap "stop" ijyn 1 2
2023-09-10T00:28:55.006Z,1694305735.006 [CommandExec](IMPORTANT): got command schedule asap "stop" ijyn 1 2.000000
2023-09-10T00:28:55.006Z,1694305735.006 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=ijyn
2023-09-10T00:28:55.007Z,1694305735.007 [CommandExec](IMPORTANT): Scheduled #7 (#1 of 2 with id='ijyn'): "stop", ASAP
2023-09-10T00:28:55.950Z,1694305735.950 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T002855
2023-09-10T00:29:04.561Z,1694305744.561 [DataOverHttps](INFO): Received command: sched asap "" ijyn 2 2
2023-09-10T00:29:04.648Z,1694305744.648 [CommandExec](IMPORTANT): got command schedule asap "" ijyn 2 2.000000
2023-09-10T00:29:04.648Z,1694305744.648 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=ijyn
2023-09-10T00:29:04.649Z,1694305744.649 [CommandExec](IMPORTANT): Scheduled #8 (#2 of 2 with id='ijyn'): "", ASAP
2023-09-10T00:29:04.750Z,1694305744.750 [CommandExec](IMPORTANT): got command stop
2023-09-10T00:29:04.750Z,1694305744.750 [CommandExec](IMPORTANT): Scheduling is paused for 1 commands
2023-09-10T00:29:04.750Z,1694305744.750 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-09-10T00:29:05.010Z,1694305745.010 [MissionManager](INFO): MissionManager is completed.
2023-09-10T00:29:05.010Z,1694305745.010 [MissionManager](INFO): Uninitializing Mission transit
2023-09-10T00:29:05.010Z,1694305745.010 [transit] Stopped
2023-09-10T00:29:05.010Z,1694305745.010 [transit](DEBUG): Aggregate::uninitialize transit
2023-09-10T00:29:05.010Z,1694305745.010 [transit:StandardEnvelopes] Stopped
2023-09-10T00:29:05.011Z,1694305745.011 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2023-09-10T00:29:05.011Z,1694305745.011 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2023-09-10T00:29:05.011Z,1694305745.011 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2023-09-10T00:29:05.011Z,1694305745.011 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2023-09-10T00:29:05.011Z,1694305745.011 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2023-09-10T00:29:05.011Z,1694305745.011 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2023-09-10T00:29:05.011Z,1694305745.011 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2023-09-10T00:29:05.011Z,1694305745.011 [transit:BackseatDriver] Stopped
2023-09-10T00:29:05.011Z,1694305745.011 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2023-09-10T00:29:05.011Z,1694305745.011 [transit:BackseatDriver:A.BackseatDriver] Stopped
2023-09-10T00:29:05.011Z,1694305745.011 [transit:BuoyancyHold.Buoyancy] Stopped
2023-09-10T00:29:05.011Z,1694305745.011 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-09-10T00:29:05.011Z,1694305745.011 [transit:MassHold.Pitch] Stopped
2023-09-10T00:29:05.012Z,1694305745.012 [transit:Transit] Stopped
2023-09-10T00:29:05.012Z,1694305745.012 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2023-09-10T00:29:05.012Z,1694305745.012 [transit:Transit:A.Pitch] Stopped
2023-09-10T00:29:05.012Z,1694305745.012 [transit:Transit:B.SetSpeed] Stopped
2023-09-10T00:29:05.012Z,1694305745.012 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2023-09-10T00:29:05.012Z,1694305745.012 [transit:Transit:Wpt1.Waypoint] Stopped
2023-09-10T00:29:05.012Z,1694305745.012 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2023-09-10T00:29:05.393Z,1694305745.393 [MissionManager](IMPORTANT): Started mission Default
2023-09-10T00:29:05.394Z,1694305745.394 [Default] Running Loop=1
2023-09-10T00:29:05.394Z,1694305745.394 [Default](DEBUG): Aggregate::initialize Default
2023-09-10T00:29:05.394Z,1694305745.394 [Default:B.GoToSurface] Running Loop=1
2023-09-10T00:29:05.394Z,1694305745.394 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-10T00:29:05.394Z,1694305745.394 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-10T00:29:05.395Z,1694305745.395 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-10T00:29:05.395Z,1694305745.395 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-10T00:29:05.395Z,1694305745.395 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-10T00:29:05.395Z,1694305745.395 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-10T00:29:05.396Z,1694305745.396 [Default:A.Wait] Running Loop=1
2023-09-10T00:29:05.396Z,1694305745.396 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-10T00:29:05.560Z,1694305745.560 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T002904
2023-09-10T00:29:07.303Z,1694305747.303 [BackseatComponent](INFO): Sent LCM shutdown request.
2023-09-10T00:29:07.303Z,1694305747.303 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2023-09-10T00:29:07.387Z,1694305747.387 [NAL9602](INFO): SBD MO Status=1, MOMSN=3895, MT Status=0, MTMSN=0
2023-09-10T00:29:07.387Z,1694305747.387 [NAL9602](INFO): No messages in MT queue
2023-09-10T00:29:15.805Z,1694305755.805 [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" ijz8 1 2
2023-09-10T00:29:15.861Z,1694305755.861 [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" ijz8 1 2.000000
2023-09-10T00:29:15.862Z,1694305755.862 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=ijz8
2023-09-10T00:29:15.863Z,1694305755.863 [CommandExec](IMPORTANT): Scheduled #9 (#1 of 2 with id='ijz8'): "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-10T00:29:16.807Z,1694305756.807 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T002916
2023-09-10T00:29:18.748Z,1694305758.748 [Default:A.Wait](INFO): Done Waiting.
2023-09-10T00:29:18.748Z,1694305758.748 [Default:A.Wait] Stopped
2023-09-10T00:29:18.749Z,1694305758.749 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-10T00:29:19.163Z,1694305759.163 [Default:CheckIn] Running Loop=1
2023-09-10T00:29:19.163Z,1694305759.163 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-10T00:29:19.163Z,1694305759.163 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-10T00:29:21.164Z,1694305761.164 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002920.00,A,4145.54073,N,08116.65201,W,0.117,81.47,100923,,,D*4B
2023-09-10T00:29:21.166Z,1694305761.166 [NAL9602](INFO): GPS fix at 20230910T002920: (41.759012, -81.277534)
2023-09-10T00:29:21.205Z,1694305761.205 [Default:CheckIn:Read_GPS] Stopped
2023-09-10T00:29:21.205Z,1694305761.205 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-10T00:29:25.236Z,1694305765.236 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230909T231941/Courier0051.lzma
2023-09-10T00:29:25.239Z,1694305765.239 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" ijz8 2 2
2023-09-10T00:29:25.245Z,1694305765.245 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" ijz8 2 2.000000
2023-09-10T00:29:25.245Z,1694305765.245 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=ijz8
2023-09-10T00:29:25.246Z,1694305765.246 [CommandExec](IMPORTANT): Scheduled #10 (#2 of 2 with id='ijz8'): "set transit.MinOffshore .01 km;set transit.MinAltitude 2 m;set transit:BackseatDriver.EnableBackseat 1 bool;run", ASAP
2023-09-10T00:29:25.306Z,1694305765.306 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2023-09-10T00:29:25.306Z,1694305765.306 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2023-09-10T00:29:25.306Z,1694305765.306 [LcmUniversalReporter](INFO): Deactivating messaging.
2023-09-10T00:29:25.370Z,1694305765.370 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-09-10T00:29:25.371Z,1694305765.371 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-09-10T00:29:25.371Z,1694305765.371 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-09-10T00:29:25.553Z,1694305765.553 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-09-10T00:29:25.555Z,1694305765.555 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-09-10T00:29:25.557Z,1694305765.557 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-09-10T00:29:25.559Z,1694305765.559 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-09-10T00:29:25.573Z,1694305765.573 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-09-10T00:29:25.576Z,1694305765.576 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-09-10T00:29:25.578Z,1694305765.578 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-09-10T00:29:25.580Z,1694305765.580 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-09-10T00:29:25.582Z,1694305765.582 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-09-10T00:29:25.584Z,1694305765.584 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-09-10T00:29:25.594Z,1694305765.594 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-09-10T00:29:25.596Z,1694305765.596 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-09-10T00:29:25.634Z,1694305765.634 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-09-10T00:29:25.634Z,1694305765.634 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-09-10T00:29:25.634Z,1694305765.634 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-09-10T00:29:25.858Z,1694305765.858 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-09-10T00:29:25.860Z,1694305765.860 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-09-10T00:29:25.863Z,1694305765.863 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-09-10T00:29:25.870Z,1694305765.870 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-09-10T00:29:25.872Z,1694305765.872 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-09-10T00:29:25.882Z,1694305765.882 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-09-10T00:29:25.884Z,1694305765.884 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-09-10T00:29:25.891Z,1694305765.891 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0051.lzma.bak
2023-09-10T00:29:25.891Z,1694305765.891 [DataOverHttps](INFO): SBD MOMSN=18756590
2023-09-10T00:29:25.895Z,1694305765.895 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-09-10T00:29:25.928Z,1694305765.928 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-10T00:29:25.982Z,1694305765.982 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-09-10T00:29:25.982Z,1694305765.982 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-09-10T00:29:26.042Z,1694305766.042 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-09-10T00:29:26.044Z,1694305766.044 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-09-10T00:29:26.087Z,1694305766.087 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-09-10T00:29:26.093Z,1694305766.093 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-09-10T00:29:26.094Z,1694305766.094 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-09-10T00:29:26.108Z,1694305766.108 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-09-10T00:29:26.127Z,1694305766.127 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-09-10T00:29:26.208Z,1694305766.208 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-10T00:29:26.208Z,1694305766.208 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-10T00:29:26.247Z,1694305766.247 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2023-09-10T00:29:26.253Z,1694305766.253 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-10T00:29:26.256Z,1694305766.256 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-10T00:29:26.267Z,1694305766.267 [transit:MassHold.Pitch](DEBUG): Construct.
2023-09-10T00:29:26.273Z,1694305766.273 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-09-10T00:29:26.285Z,1694305766.285 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-09-10T00:29:26.287Z,1694305766.287 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-09-10T00:29:26.320Z,1694305766.320 [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-10T00:29:26.322Z,1694305766.322 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-09-10T00:29:40.520Z,1694305780.520 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute
2023-09-10T00:29:40.520Z,1694305780.520 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2023-09-10T00:29:40.521Z,1694305780.521 [CommandExec](IMPORTANT): got command set transit.Latitude 41.76107 degree
2023-09-10T00:29:40.522Z,1694305780.522 [CommandExec](IMPORTANT): got command set transit.Longitude -81.27857 degree
2023-09-10T00:29:40.523Z,1694305780.523 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-09-10T00:29:40.523Z,1694305780.523 [CommandExec](IMPORTANT): got command set transit.MaxDepth 3 meter
2023-09-10T00:29:50.199Z,1694305790.199 [CommandExec](IMPORTANT): got command set transit.MinOffshore .01 kilometer
2023-09-10T00:29:50.200Z,1694305790.200 [CommandExec](IMPORTANT): got command set transit.MinAltitude 2 meter
2023-09-10T00:29:50.201Z,1694305790.201 [CommandExec](IMPORTANT): got command set transit:BackseatDriver.EnableBackseat 1 bool
2023-09-10T00:29:50.202Z,1694305790.202 [CommandExec](IMPORTANT): got command run
2023-09-10T00:29:50.203Z,1694305790.203 [CommandExec](IMPORTANT): Running 
2023-09-10T00:29:50.442Z,1694305790.442 [Default] Stopped
2023-09-10T00:29:50.442Z,1694305790.442 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-10T00:29:50.442Z,1694305790.442 [Default:B.GoToSurface] Stopped
2023-09-10T00:29:50.442Z,1694305790.442 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-10T00:29:50.442Z,1694305790.442 [Default:CheckIn] Stopped
2023-09-10T00:29:50.442Z,1694305790.442 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-10T00:29:50.442Z,1694305790.442 [Default:CheckIn:Read_Iridium] Stopped
2023-09-10T00:29:50.442Z,1694305790.442 [MissionManager](IMPORTANT): Started mission transit
2023-09-10T00:29:50.443Z,1694305790.443 [transit] Running Loop=1
2023-09-10T00:29:50.443Z,1694305790.443 [transit](DEBUG): Aggregate::initialize transit
2023-09-10T00:29:50.443Z,1694305790.443 [transit:StandardEnvelopes] Running Loop=1
2023-09-10T00:29:50.443Z,1694305790.443 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-09-10T00:29:50.443Z,1694305790.443 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-10T00:29:50.443Z,1694305790.443 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-09-10T00:29:50.443Z,1694305790.443 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-10T00:29:50.444Z,1694305790.444 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-09-10T00:29:50.444Z,1694305790.444 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-10T00:29:50.444Z,1694305790.444 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-09-10T00:29:50.444Z,1694305790.444 [transit:BackseatDriver] Running Loop=1
2023-09-10T00:29:50.444Z,1694305790.444 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2023-09-10T00:29:50.444Z,1694305790.444 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-10T00:29:50.444Z,1694305790.444 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-10T00:29:50.444Z,1694305790.444 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-09-10T00:29:50.444Z,1694305790.444 [transit:MassHold.Pitch] Running Loop=1
2023-09-10T00:29:50.444Z,1694305790.444 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-09-10T00:29:50.445Z,1694305790.445 [transit:B] Running Loop=1
2023-09-10T00:29:50.450Z,1694305790.450 [transit:MassHold.Pitch] Running Loop=1
2023-09-10T00:29:50.450Z,1694305790.450 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-10T00:29:50.450Z,1694305790.450 [transit:BackseatDriver] Running Loop=1
2023-09-10T00:29:50.450Z,1694305790.450 [transit:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2023-09-10T00:29:50.495Z,1694305790.495 [transit:StandardEnvelopes] Running Loop=1
2023-09-10T00:29:50.495Z,1694305790.495 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-10T00:29:50.508Z,1694305790.508 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-10T00:29:50.508Z,1694305790.508 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-10T00:29:50.509Z,1694305790.509 [transit:B] Stopped
2023-09-10T00:29:50.509Z,1694305790.509 [transit:C] Running Loop=1
2023-09-10T00:29:50.633Z,1694305790.633 [BackseatComponent](INFO): Powering up
2023-09-10T00:29:50.634Z,1694305790.634 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-09-10T00:29:50.829Z,1694305790.829 [transit:C] Stopped
2023-09-10T00:29:50.829Z,1694305790.829 [transit:E] Running Loop=1
2023-09-10T00:29:51.225Z,1694305791.225 [transit:E] Stopped
2023-09-10T00:29:51.225Z,1694305791.225 [transit:F] Running Loop=1
2023-09-10T00:29:51.288Z,1694305791.288 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230909T231941/Courier0055.lzma
2023-09-10T00:29:51.633Z,1694305791.633 [transit:F] Stopped
2023-09-10T00:29:51.633Z,1694305791.633 [transit:G] Running Loop=1
2023-09-10T00:29:52.049Z,1694305792.049 [transit:G] Stopped
2023-09-10T00:29:52.049Z,1694305792.049 [transit:Transit] Running Loop=1
2023-09-10T00:29:52.050Z,1694305792.050 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-09-10T00:29:52.050Z,1694305792.050 [transit:Transit:A.Pitch] Running Loop=1
2023-09-10T00:29:52.050Z,1694305792.050 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-09-10T00:29:52.050Z,1694305792.050 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-10T00:29:52.050Z,1694305792.050 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-09-10T00:29:52.050Z,1694305792.050 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-09-10T00:29:52.050Z,1694305792.050 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-09-10T00:29:52.051Z,1694305792.051 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 41.761070,-81.278572
2023-09-10T00:29:52.052Z,1694305792.052 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2023-09-10T00:29:52.291Z,1694305792.291 [DataOverHttps](INFO): Moved sent file to Logs/20230909T231941/Courier0055.lzma.bak
2023-09-10T00:29:52.291Z,1694305792.291 [DataOverHttps](INFO): SBD MOMSN=18756594
2023-09-10T00:29:52.439Z,1694305792.439 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-10T00:29:52.439Z,1694305792.439 [transit:Transit:A.Pitch] Running Loop=1
2023-09-10T00:29:53.300Z,1694305793.300 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-10T00:30:39.919Z,1694305839.919 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T003039
2023-09-10T00:30:49.626Z,1694305849.626 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20230910T003046
2023-09-10T00:30:51.629Z,1694305851.629 [DataOverHttps](INFO): Received command: sched asap "schedule resume" ik2d 1 2
2023-09-10T00:30:51.712Z,1694305851.712 [CommandExec](IMPORTANT): got command schedule asap "schedule resume" ik2d 1 2.000000
2023-09-10T00:30:51.712Z,1694305851.712 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=ik2d
2023-09-10T00:30:51.723Z,1694305851.723 [CommandExec](IMPORTANT): Scheduled #11 (#1 of 2 with id='ik2d'): "schedule resume", ASAP
2023-09-10T00:30:52.630Z,1694305852.630 [DataOverHttps](IMPORTANT): SBD MTMSN=20230910T003051
2023-09-10T00:31:00.466Z,1694305860.466 [DataOverHttps](INFO): Received command: sched asap "" ik2d 2 2
2023-09-10T00:31:00.559Z,1694305860.559 [CommandExec](IMPORTANT): got command schedule asap "" ik2d 2 2.000000
2023-09-10T00:31:00.560Z,1694305860.560 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=ik2d
2023-09-10T00:31:00.561Z,1694305860.561 [CommandExec](IMPORTANT): Scheduled #12 (#2 of 2 with id='ik2d'): "", ASAP
2023-09-10T00:31:00.663Z,1694305860.663 [CommandExec](IMPORTANT): got command schedule resume
2023-09-10T00:31:00.663Z,1694305860.663 [CommandExec](IMPORTANT): Scheduling is resumed
2023-09-10T00:32:34.749Z,1694305954.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-10T00:32:37.762Z,1694305957.762 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243431
2023-09-10T00:32:59.597Z,1694305979.597 [Radio_Surface](INFO): Powering down
2023-09-10T00:33:09.889Z,1694305989.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-10T00:33:10.894Z,1694305990.894 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-09-10T00:33:35.197Z,1694306015.197 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:33:35.581Z,1694306015.581 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:33:37.561Z,1694306017.561 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:33:37.973Z,1694306017.973 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-09-10T00:33:39.186Z,1694306019.186 [ThrusterHE](FAULT): Lock Detect. Motor stopped spinning or could not start spinning.
2023-09-10T00:33:39.186Z,1694306019.186 [ThrusterHE] Hardware Fault, FailCount= 1
2023-09-10T00:33:39.187Z,1694306019.187 [ThrusterHE](ERROR): Hardware Fault
2023-09-10T00:33:39.204Z,1694306019.204 [CBIT](ERROR): Hardware Fault in component: ThrusterHE
2023-09-10T00:33:39.666Z,1694306019.666 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-10T00:33:39.666Z,1694306019.666 [ThrusterHE](INFO): Powering down
2023-09-10T00:33:40.362Z,1694306020.362 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-10T00:33:40.482Z,1694306020.482 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-10T00:33:40.486Z,1694306020.486 [CBIT](INFO): Clearing failed state for component ThrusterHE
2023-09-10T00:33:40.486Z,1694306020.486 [ThrusterHE] No Fault, FailCount= 1
2023-09-10T00:33:46.134Z,1694306026.134 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-10T00:34:10.646Z,1694306050.646 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 41.761070,-81.278572
2023-09-10T00:34:10.647Z,1694306050.647 [transit:Transit:Wpt1.Waypoint] Stopped
2023-09-10T00:34:10.647Z,1694306050.647 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2023-09-10T00:34:10.647Z,1694306050.647 [transit:Transit:PhoneHome] Running Loop=1
2023-09-10T00:34:10.647Z,1694306050.647 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome
2023-09-10T00:34:11.070Z,1694306051.070 [transit:NeedComms] Running Loop=1
2023-09-10T00:34:11.070Z,1694306051.070 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2023-09-10T00:34:11.070Z,1694306051.070 [transit:NeedComms:B.GoToSurface] Running Loop=1
2023-09-10T00:34:11.070Z,1694306051.070 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-10T00:34:11.070Z,1694306051.070 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2023-09-10T00:34:11.071Z,1694306051.071 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2023-09-10T00:34:11.071Z,1694306051.071 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2023-09-10T00:34:11.072Z,1694306051.072 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2023-09-10T00:34:11.072Z,1694306051.072 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-10T00:34:11.072Z,1694306051.072 [transit:NeedComms:A] Running Loop=1
2023-09-10T00:34:11.074Z,1694306051.074 [transit:NeedComms:A](INFO): last time_fix was: 1694305760.000000 second since 1970/01/01T00:00:00Z 
2023-09-10T00:34:11.074Z,1694306051.074 [transit:NeedComms:A] Stopped
2023-09-10T00:34:18.319Z,1694306058.319 [transit:NeedComms:C] Running Loop=1
2023-09-10T00:34:18.723Z,1694306058.723 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-10T00:34:18.801Z,1694306058.801 [Radio_Surface](INFO): Powering up
2023-09-10T00:34:24.193Z,1694306064.193 [DataOverHttps](INFO): Radio surface powered ON.
2023-09-10T00:34:34.474Z,1694306074.474 [NAL9602](INFO): SBD MO Status=1, MOMSN=3896, MT Status=0, MTMSN=0
2023-09-10T00:34:34.474Z,1694306074.474 [NAL9602](INFO): No messages in MT queue
2023-09-10T00:34:35.678Z,1694306075.678 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003435.00,A,4145.63460,N,08116.69639,W,0.816,2.85,100923,,,A*7A
2023-09-10T00:34:35.680Z,1694306075.680 [NAL9602](INFO): GPS fix at 20230910T003435: (41.760577, -81.278273)
2023-09-10T00:34:35.698Z,1694306075.698 [transit:NeedComms:C] Stopped
2023-09-10T00:34:35.698Z,1694306075.698 [transit:NeedComms:D] Running Loop=1
2023-09-10T00:34:36.123Z,1694306076.123 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-10T00:34:51.851Z,1694306091.851 [NAL9602](INFO): SBD MO Status=1, MOMSN=3897, MT Status=0, MTMSN=0
2023-09-10T00:34:51.905Z,1694306091.905 [NAL9602](INFO): Sent 73 bytes from file Logs/20230909T231941/Courier0061.lzma
2023-09-10T00:34:51.905Z,1694306091.905 [NAL9602](INFO): Packets left to send: 0
2023-09-10T00:34:54.313Z,1694306094.313 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-10T00:35:04.108Z,1694306104.108 [NAL9602](INFO): SBD MO Status=1, MOMSN=3898, MT Status=0, MTMSN=0
2023-09-10T00:35:04.161Z,1694306104.161 [NAL9602](INFO): Sent 332 bytes from file Logs/20230909T231941/Express0047.lzma
2023-09-10T00:35:04.161Z,1694306104.161 [NAL9602](INFO): Packets left to send: 2
2023-09-10T00:35:16.096Z,1694306116.096 [NAL9602](INFO): SBD MO Status=1, MOMSN=3899, MT Status=0, MTMSN=0
2023-09-10T00:35:16.149Z,1694306116.149 [NAL9602](INFO): Sent 332 bytes from file Logs/20230909T231941/Express0047.lzma
2023-09-10T00:35:16.149Z,1694306116.149 [NAL9602](INFO): Packets left to send: 1
2023-09-10T00:35:29.461Z,1694306129.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-10T00:35:33.700Z,1694306133.700 [NAL9602](INFO): SBD MO Status=1, MOMSN=3900, MT Status=0, MTMSN=0
2023-09-10T00:35:33.749Z,1694306133.749 [NAL9602](INFO): Sent 149 bytes from file Logs/20230909T231941/Express0047.lzma
2023-09-10T00:35:33.749Z,1694306133.749 [NAL9602](INFO): Packets left to send: 0
2023-09-10T00:35:45.340Z,1694306145.340 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-10T00:35:45.343Z,1694306145.343 [BPC1](INFO): Received data from all battery sticks.
2023-09-10T00:35:50.170Z,1694306150.170 [transit:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.simrad_data_logging_rate with code: 1946
2023-09-10T00:35:52.160Z,1694306152.160 [NAL9602](INFO): SBD MO Status=1, MOMSN=3901, MT Status=0, MTMSN=0
2023-09-10T00:35:52.223Z,1694306152.223 [NAL9602](INFO): Sent 332 bytes from file Logs/20230909T231941/Express0050.lzma
2023-09-10T00:35:52.223Z,1694306152.223 [NAL9602](INFO): Packets left to send: 2
2023-09-10T00:36:04.601Z,1694306164.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-10T00:36:17.410Z,1694306177.410 [NAL9602](INFO): SBD MO Status=1, MOMSN=3902, MT Status=0, MTMSN=0
2023-09-10T00:36:17.457Z,1694306177.457 [NAL9602](INFO): Sent 332 bytes from file Logs/20230909T231941/Express0050.lzma
2023-09-10T00:36:17.458Z,1694306177.458 [NAL9602](INFO): Packets left to send: 1
2023-09-10T00:36:34.188Z,1694306194.188 [NAL9602](INFO): SBD MO Status=1, MOMSN=3903, MT Status=0, MTMSN=0
2023-09-10T00:36:34.249Z,1694306194.249 [NAL9602](INFO): Sent 74 bytes from file Logs/20230909T231941/Express0050.lzma
2023-09-10T00:36:34.249Z,1694306194.249 [NAL9602](INFO): Packets left to send: 0
2023-09-10T00:36:39.741Z,1694306199.741 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-10T00:37:01.134Z,1694306221.134 [NAL9602](INFO): SBD MO Status=1, MOMSN=3904, MT Status=0, MTMSN=0
2023-09-10T00:37:01.181Z,1694306221.181 [NAL9602](INFO): Sent 61 bytes from file Logs/20230909T231941/Express0056.lzma
2023-09-10T00:37:01.181Z,1694306221.181 [NAL9602](INFO): Packets left to send: 0
2023-09-10T00:37:14.885Z,1694306234.885 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-10T00:37:24.503Z,1694306244.503 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=3905, MT Status=1, MTMSN=700
2023-09-10T00:37:24.559Z,1694306244.559 [NAL9602](INFO): Sent 317 bytes from file Logs/20230909T231941/Express0059.lzma
2023-09-10T00:37:24.560Z,1694306244.560 [NAL9602](INFO): Packets left to send: 0