2023-09-08T13:52:19.968Z,1694181139.968 [Supervisor](DEBUG): Initializing supervisor.
2023-09-08T13:52:19.973Z,1694181139.973 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-08T13:52:19.973Z,1694181139.973 [SyncHandler](INFO): Protected caller Thread ID is 829
2023-09-08T13:52:19.974Z,1694181139.974 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-08T13:52:19.975Z,1694181139.975 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-08T13:52:19.975Z,1694181139.975 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 830
2023-09-08T13:52:19.979Z,1694181139.979 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-08T13:52:19.996Z,1694181139.996 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-08T13:52:19.997Z,1694181139.997 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-08T13:52:19.998Z,1694181139.998 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 831
2023-09-08T13:52:20.002Z,1694181140.002 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-08T13:52:20.003Z,1694181140.003 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-08T13:52:20.003Z,1694181140.003 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 832
2023-09-08T13:52:20.005Z,1694181140.005 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-08T13:52:20.006Z,1694181140.006 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-08T13:52:20.006Z,1694181140.006 [logger ThreadHandler](INFO): Protected caller Thread ID is 833
2023-09-08T13:52:20.010Z,1694181140.010 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-08T13:52:20.010Z,1694181140.010 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-08T13:52:20.016Z,1694181140.016 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-08T13:52:20.111Z,1694181140.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-08T13:52:20.113Z,1694181140.113 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-08T13:52:20.324Z,1694181140.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-08T13:52:20.326Z,1694181140.326 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-08T13:52:20.575Z,1694181140.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-08T13:52:20.576Z,1694181140.576 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-08T13:52:20.680Z,1694181140.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-08T13:52:20.682Z,1694181140.682 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-08T13:52:21.439Z,1694181141.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-08T13:52:21.441Z,1694181141.441 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-08T13:52:21.914Z,1694181141.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-08T13:52:21.916Z,1694181141.916 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-08T13:52:22.391Z,1694181142.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-08T13:52:22.393Z,1694181142.393 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-08T13:52:22.786Z,1694181142.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-08T13:52:22.787Z,1694181142.787 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-08T13:52:23.154Z,1694181143.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-08T13:52:23.154Z,1694181143.154 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-08T13:52:24.036Z,1694181144.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-08T13:52:24.037Z,1694181144.037 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-08T13:52:24.253Z,1694181144.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-08T13:52:24.254Z,1694181144.254 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-08T13:52:24.392Z,1694181144.392 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-08T13:52:24.393Z,1694181144.393 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-08T13:52:24.473Z,1694181144.473 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-08T13:52:24.552Z,1694181144.552 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-08T13:52:24.731Z,1694181144.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-08T13:52:24.732Z,1694181144.732 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-08T13:52:24.959Z,1694181144.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-08T13:52:24.961Z,1694181144.961 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-09-08T13:52:24.963Z,1694181144.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-09-08T13:52:25.047Z,1694181145.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-09-08T13:52:25.309Z,1694181145.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-08T13:52:25.311Z,1694181145.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-09-08T13:52:25.416Z,1694181145.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-09-08T13:52:25.571Z,1694181145.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-09-08T13:52:25.673Z,1694181145.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-09-08T13:52:25.784Z,1694181145.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-09-08T13:52:25.892Z,1694181145.892 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-09-08T13:52:25.994Z,1694181145.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-09-08T13:52:26.151Z,1694181146.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2023-09-08T13:52:26.247Z,1694181146.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-09-08T13:52:26.342Z,1694181146.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-09-08T13:52:26.444Z,1694181146.444 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-08T13:52:26.474Z,1694181146.474 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-08T13:52:26.657Z,1694181146.657 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-08T13:52:26.658Z,1694181146.658 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-08T13:52:26.841Z,1694181146.841 [BuoyancyServo] Loaded
2023-09-08T13:52:26.841Z,1694181146.841 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-08T13:52:26.862Z,1694181146.862 [ElevatorServo] Loaded
2023-09-08T13:52:26.862Z,1694181146.862 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-08T13:52:26.883Z,1694181146.883 [MassServo] Loaded
2023-09-08T13:52:26.883Z,1694181146.883 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-08T13:52:26.903Z,1694181146.903 [RudderServo] Loaded
2023-09-08T13:52:26.903Z,1694181146.903 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-08T13:52:26.919Z,1694181146.919 [ThrusterHE] Loaded
2023-09-08T13:52:26.919Z,1694181146.919 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-08T13:52:26.920Z,1694181146.920 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-08T13:52:26.921Z,1694181146.921 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-08T13:52:27.090Z,1694181147.090 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-08T13:52:27.105Z,1694181147.105 [SBIT] Loaded
2023-09-08T13:52:27.105Z,1694181147.105 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-08T13:52:27.108Z,1694181147.108 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-08T13:52:27.129Z,1694181147.129 [IBIT] Loaded
2023-09-08T13:52:27.129Z,1694181147.129 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-08T13:52:27.135Z,1694181147.135 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-08T13:52:27.355Z,1694181147.355 [CBIT] Loaded
2023-09-08T13:52:27.355Z,1694181147.355 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-08T13:52:27.355Z,1694181147.355 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-08T13:52:27.357Z,1694181147.357 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-08T13:52:27.574Z,1694181147.574 [CTD_Seabird] Loaded
2023-09-08T13:52:27.575Z,1694181147.575 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-08T13:52:27.576Z,1694181147.576 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406674E0
2023-09-08T13:52:27.576Z,1694181147.576 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 919
2023-09-08T13:52:27.609Z,1694181147.609 [ESPComponent] Loaded
2023-09-08T13:52:27.609Z,1694181147.609 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-09-08T13:52:27.664Z,1694181147.664 [PAR_Licor] Loaded
2023-09-08T13:52:27.664Z,1694181147.664 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-08T13:52:27.737Z,1694181147.737 [WetLabsBB2FL] Loaded
2023-09-08T13:52:27.737Z,1694181147.737 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-08T13:52:27.738Z,1694181147.738 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406974E0
2023-09-08T13:52:27.738Z,1694181147.738 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 920
2023-09-08T13:52:27.739Z,1694181147.739 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-08T13:52:27.753Z,1694181147.753 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-08T13:52:28.363Z,1694181148.363 [DepthRateCalculator] Loaded
2023-09-08T13:52:28.363Z,1694181148.363 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-08T13:52:28.369Z,1694181148.369 [PitchRateCalculator] Loaded
2023-09-08T13:52:28.369Z,1694181148.369 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-08T13:52:28.379Z,1694181148.379 [SpeedCalculator] Loaded
2023-09-08T13:52:28.379Z,1694181148.379 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-08T13:52:28.384Z,1694181148.384 [YawRateCalculator] Loaded
2023-09-08T13:52:28.384Z,1694181148.384 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-08T13:52:28.403Z,1694181148.403 [ElevatorOffsetCalculator] Loaded
2023-09-08T13:52:28.404Z,1694181148.404 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-08T13:52:28.421Z,1694181148.421 [ShortestDistanceToPolygonSidesCalculator] Loaded
2023-09-08T13:52:28.421Z,1694181148.421 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2023-09-08T13:52:28.421Z,1694181148.421 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-08T13:52:28.422Z,1694181148.422 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-08T13:52:28.764Z,1694181148.764 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-08T13:52:28.765Z,1694181148.765 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-08T13:52:28.880Z,1694181148.880 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-08T13:52:28.881Z,1694181148.881 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-08T13:52:29.267Z,1694181149.267 [DeadReckonUsingSpeedCalculator] Loaded
2023-09-08T13:52:29.267Z,1694181149.267 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-09-08T13:52:29.281Z,1694181149.281 [NavChart] Loaded
2023-09-08T13:52:29.281Z,1694181149.281 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-08T13:52:29.287Z,1694181149.287 [UniversalFixResidualReporter] Loaded
2023-09-08T13:52:29.287Z,1694181149.287 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-08T13:52:29.288Z,1694181149.288 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-08T13:52:29.288Z,1694181149.288 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-08T13:52:30.095Z,1694181150.095 [AHRS_M2] Loaded
2023-09-08T13:52:30.096Z,1694181150.096 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-08T13:52:30.345Z,1694181150.345 [BackseatComponent] Loaded
2023-09-08T13:52:30.345Z,1694181150.345 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-08T13:52:30.346Z,1694181150.346 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A2F4E0
2023-09-08T13:52:30.346Z,1694181150.346 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2023-09-08T13:52:30.349Z,1694181150.349 [LcmUniversalReporter] Loaded
2023-09-08T13:52:30.350Z,1694181150.350 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-08T13:52:31.085Z,1694181151.085 [BPC1] Loaded
2023-09-08T13:52:31.085Z,1694181151.085 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-08T13:52:31.228Z,1694181151.228 [DataOverHttps] Loaded
2023-09-08T13:52:31.228Z,1694181151.228 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-08T13:52:31.229Z,1694181151.229 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A5F4E0
2023-09-08T13:52:31.230Z,1694181151.230 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2023-09-08T13:52:31.250Z,1694181151.250 [Depth_Keller] Loaded
2023-09-08T13:52:31.251Z,1694181151.251 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-08T13:52:31.256Z,1694181151.256 [DropWeight] Loaded
2023-09-08T13:52:31.256Z,1694181151.256 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-09-08T13:52:31.295Z,1694181151.295 [DVL_micro] Loaded
2023-09-08T13:52:31.296Z,1694181151.296 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-09-08T13:52:31.357Z,1694181151.357 [NAL9602] Loaded
2023-09-08T13:52:31.357Z,1694181151.357 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-08T13:52:31.383Z,1694181151.383 [Onboard] Loaded
2023-09-08T13:52:31.383Z,1694181151.383 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-08T13:52:31.384Z,1694181151.384 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A8F4E0
2023-09-08T13:52:31.385Z,1694181151.385 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2023-09-08T13:52:31.397Z,1694181151.397 [Power24vConverter] Loaded
2023-09-08T13:52:31.397Z,1694181151.397 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-08T13:52:31.411Z,1694181151.411 [Radio_Surface] Loaded
2023-09-08T13:52:31.411Z,1694181151.411 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-08T13:52:31.412Z,1694181151.412 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40ABF4E0
2023-09-08T13:52:31.412Z,1694181151.412 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2023-09-08T13:52:31.426Z,1694181151.426 [Sonardyne_Nano] Loaded
2023-09-08T13:52:31.426Z,1694181151.426 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-09-08T13:52:31.427Z,1694181151.427 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-08T13:52:31.428Z,1694181151.428 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-08T13:52:31.509Z,1694181151.509 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-08T13:52:31.510Z,1694181151.510 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-08T13:52:31.577Z,1694181151.577 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-08T13:52:31.628Z,1694181151.628 [VerticalControl] Loaded
2023-09-08T13:52:31.628Z,1694181151.628 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-08T13:52:31.631Z,1694181151.631 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-08T13:52:31.669Z,1694181151.669 [HorizontalControl] Loaded
2023-09-08T13:52:31.669Z,1694181151.669 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-08T13:52:31.671Z,1694181151.671 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-08T13:52:31.673Z,1694181151.673 [SpeedControl] Loaded
2023-09-08T13:52:31.673Z,1694181151.673 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-08T13:52:31.676Z,1694181151.676 [LoopControl](DEBUG): Construct LoopControl.
2023-09-08T13:52:31.677Z,1694181151.677 [LoopControl] Loaded
2023-09-08T13:52:31.677Z,1694181151.677 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-08T13:52:31.678Z,1694181151.678 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-08T13:52:31.678Z,1694181151.678 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-08T13:52:31.693Z,1694181151.693 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-08T13:52:31.694Z,1694181151.694 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-08T13:52:32.051Z,1694181152.051 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-08T13:52:32.058Z,1694181152.058 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-08T13:52:32.060Z,1694181152.060 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-08T13:52:32.071Z,1694181152.071 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-08T13:52:32.072Z,1694181152.072 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-09-08T13:52:32.073Z,1694181152.073 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 925
2023-09-08T13:52:32.077Z,1694181152.077 [Supervisor](INFO): Main Thread ID is 822
2023-09-08T13:52:32.077Z,1694181152.077 [Supervisor](DEBUG): Running supervisor.
2023-09-08T13:52:32.078Z,1694181152.078 [CommandExec ThreadHandler](INFO): Handler Thread ID is 926
2023-09-08T13:52:32.078Z,1694181152.078 [CommandExec](INFO): Initializing the command executive.
2023-09-08T13:52:32.080Z,1694181152.080 [CommandLine ThreadHandler](INFO): Handler Thread ID is 927
2023-09-08T13:52:32.082Z,1694181152.082 [controlThread ThreadHandler](INFO): Handler Thread ID is 928
2023-09-08T13:52:32.082Z,1694181152.082 [controlThread](DEBUG): Initializing ControlThread
2023-09-08T13:52:32.084Z,1694181152.084 [SBIT](INFO): Initialize SBIT Component.
2023-09-08T13:52:32.085Z,1694181152.085 [SBIT](IMPORTANT): git: 2023-09-07
2023-09-08T13:52:32.085Z,1694181152.085 [SBIT](INFO): git hash: de73b65bba9e058a7fe4bf2bc7b950ee8b764c06
2023-09-08T13:52:32.085Z,1694181152.085 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-08T13:52:32.087Z,1694181152.087 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-09-08T13:52:32.088Z,1694181152.088 [SBIT](INFO): Beginning SBIT in 55.000000 seconds.
2023-09-08T13:52:32.088Z,1694181152.088 [IBIT](INFO): Initialize IBIT Component.
2023-09-08T13:52:32.089Z,1694181152.089 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-08T13:52:32.090Z,1694181152.090 [logger ThreadHandler](INFO): Handler Thread ID is 929
2023-09-08T13:52:32.100Z,1694181152.100 [CBIT](DEBUG): Initialized mux pins.
2023-09-08T13:52:32.100Z,1694181152.100 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-08T13:52:32.108Z,1694181152.108 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 930
2023-09-08T13:52:32.109Z,1694181152.109 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-08T13:52:32.113Z,1694181152.113 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 932
2023-09-08T13:52:32.116Z,1694181152.116 [WetLabsBB2FL](INFO): Powering up
2023-09-08T13:52:32.117Z,1694181152.117 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2023-09-08T13:52:32.124Z,1694181152.124 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-08T13:52:32.124Z,1694181152.124 [CBIT](DEBUG): Initializing heartbeat.
2023-09-08T13:52:32.125Z,1694181152.125 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2023-09-08T13:52:32.126Z,1694181152.126 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-08T13:52:32.132Z,1694181152.132 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2023-09-08T13:52:32.149Z,1694181152.149 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2023-09-08T13:52:32.172Z,1694181152.172 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 938
2023-09-08T13:52:32.182Z,1694181152.182 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T13:52:32.182Z,1694181152.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-09-08T13:52:32.182Z,1694181152.182 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T13:52:32.183Z,1694181152.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-09-08T13:52:32.183Z,1694181152.183 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T13:52:32.183Z,1694181152.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-09-08T13:52:32.183Z,1694181152.183 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T13:52:32.183Z,1694181152.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-09-08T13:52:32.183Z,1694181152.183 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T13:52:32.184Z,1694181152.184 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-09-08T13:52:32.184Z,1694181152.184 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T13:52:32.184Z,1694181152.184 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-09-08T13:52:32.184Z,1694181152.184 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T13:52:32.184Z,1694181152.184 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000
2023-09-08T13:52:32.185Z,1694181152.185 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T13:52:32.185Z,1694181152.185 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000
2023-09-08T13:52:32.185Z,1694181152.185 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T13:52:32.185Z,1694181152.185 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-09-08T13:52:32.185Z,1694181152.185 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T13:52:32.185Z,1694181152.185 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-09-08T13:52:32.186Z,1694181152.186 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T13:52:32.186Z,1694181152.186 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-09-08T13:52:32.186Z,1694181152.186 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T13:52:32.186Z,1694181152.186 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-09-08T13:52:32.186Z,1694181152.186 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T13:52:32.186Z,1694181152.186 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-09-08T13:52:32.196Z,1694181152.196 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-08T13:52:32.196Z,1694181152.196 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-08T13:52:32.236Z,1694181152.236 [CBIT](DEBUG): Backplane powered.
2023-09-08T13:52:32.240Z,1694181152.240 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-08T13:52:32.241Z,1694181152.241 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-08T13:52:32.241Z,1694181152.241 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-08T13:52:32.241Z,1694181152.241 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-08T13:52:32.242Z,1694181152.242 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-08T13:52:32.242Z,1694181152.242 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2023-09-08T13:52:32.243Z,1694181152.243 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-08T13:52:32.244Z,1694181152.244 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-08T13:52:32.244Z,1694181152.244 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-08T13:52:32.254Z,1694181152.254 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-08T13:52:32.256Z,1694181152.256 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-08T13:52:32.257Z,1694181152.257 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-08T13:52:32.257Z,1694181152.257 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-08T13:52:32.258Z,1694181152.258 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-08T13:52:32.302Z,1694181152.302 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T13:52:32.338Z,1694181152.338 [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-08T13:52:32.339Z,1694181152.339 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-08T13:52:32.409Z,1694181152.409 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-08T13:52:32.410Z,1694181152.410 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-08T13:52:32.429Z,1694181152.429 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T13:52:32.454Z,1694181152.454 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-08T13:52:32.473Z,1694181152.473 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-08T13:52:32.478Z,1694181152.478 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-08T13:52:32.502Z,1694181152.502 [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-08T13:52:32.507Z,1694181152.507 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-09-08T13:52:32.527Z,1694181152.527 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-08T13:52:32.556Z,1694181152.556 [Radio_Surface](INFO): Powering up
2023-09-08T13:52:32.685Z,1694181152.685 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.894409 decibar, 0.000000 m
2023-09-08T13:52:32.693Z,1694181152.693 [Power24vConverter](INFO): Powering up.
2023-09-08T13:52:32.694Z,1694181152.694 [Sonardyne_Nano](INFO): Initializing.
2023-09-08T13:52:32.720Z,1694181152.720 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-09-08T13:52:32.782Z,1694181152.782 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-08T13:52:32.788Z,1694181152.788 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-08T13:52:32.789Z,1694181152.789 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-08T13:52:32.796Z,1694181152.796 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-08T13:52:32.797Z,1694181152.797 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-08T13:52:32.805Z,1694181152.805 [MassServo](DEBUG): Initializing MassServo.
2023-09-08T13:52:32.805Z,1694181152.805 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-08T13:52:32.812Z,1694181152.812 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-08T13:52:32.813Z,1694181152.813 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-08T13:52:32.820Z,1694181152.820 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-08T13:52:33.040Z,1694181153.040 [DVL_micro](INFO): Powering down
2023-09-08T13:52:34.224Z,1694181154.224 [WetLabsBB2FL](INFO): Powering down
2023-09-08T13:52:34.942Z,1694181154.942 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-09-08T13:52:36.585Z,1694181156.585 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2023-09-08T13:52:38.405Z,1694181158.405 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-08T13:52:39.140Z,1694181159.140 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-09-08T13:53:01.611Z,1694181181.611 [NAL9602](INFO): Powering up NAL9602
2023-09-08T13:53:04.148Z,1694181184.148 [MassServo](ERROR): getPosition uart error serial timeout
2023-09-08T13:53:04.148Z,1694181184.148 [MassServo](FAULT): Invalid EZ Servo response:"\r"
2023-09-08T13:53:04.149Z,1694181184.149 [MassServo] Communications Fault, FailCount= 1
2023-09-08T13:53:04.149Z,1694181184.149 [MassServo](ERROR): Communications Fault
2023-09-08T13:53:04.163Z,1694181184.163 [CBIT](ERROR): Communications Fault in component: MassServo
2023-09-08T13:53:04.453Z,1694181184.453 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-09-08T13:53:04.453Z,1694181184.453 [MassServo](INFO): Powering down
2023-09-08T13:53:05.266Z,1694181185.266 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-08T13:53:05.381Z,1694181185.381 [MassServo](DEBUG): Initializing MassServo.
2023-09-08T13:53:05.385Z,1694181185.385 [CBIT](INFO): Clearing failed state for component MassServo
2023-09-08T13:53:05.385Z,1694181185.385 [MassServo] No Fault, FailCount= 1
2023-09-08T13:53:12.522Z,1694181192.522 [NAL9602](INFO): NAL9602 initialized
2023-09-08T13:53:27.882Z,1694181207.882 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-08T13:53:27.887Z,1694181207.887 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-08T13:53:38.849Z,1694181218.849 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005669
CHAN A1 (24V): 0.002585
CHAN A2 (12V): -0.006468
CHAN A3 (5V): -0.004075
CHAN B0 (3.3V): -0.001725
CHAN B1 (3.15aV): -0.001537
CHAN B2 (3.15bV): -0.001785
CHAN B3 (GND): -0.000344
OPEN: 0.000283
Full Scale: +/- 1 mA
2023-09-08T13:53:44.032Z,1694181224.032 [NAL9602](INFO): SBD MO Status=2, MOMSN=9008, MT Status=2, MTMSN=0
2023-09-08T13:53:44.032Z,1694181224.032 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T13:54:01.404Z,1694181241.404 [NAL9602](INFO): SBD MO Status=2, MOMSN=9008, MT Status=2, MTMSN=0
2023-09-08T13:54:01.404Z,1694181241.404 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T13:54:16.755Z,1694181256.755 [NAL9602](INFO): SBD MO Status=0, MOMSN=9008, MT Status=0, MTMSN=0
2023-09-08T13:54:16.755Z,1694181256.755 [NAL9602](INFO): No messages in MT queue
2023-09-08T13:54:17.969Z,1694181257.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135417.00,A,4145.52979,N,08116.65304,W,0.058,0.00,080923,,,A*7A
2023-09-08T13:54:17.972Z,1694181257.972 [NAL9602](INFO): GPS fix at 20230908T135417: (41.758830, -81.277551)
2023-09-08T13:54:22.037Z,1694181262.037 [SBIT](IMPORTANT): SBIT PASSED
2023-09-08T13:54:22.037Z,1694181262.037 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-08T13:54:22.038Z,1694181262.038 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-09-08T13:54:22.038Z,1694181262.038 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool;

2023-09-08T13:54:22.039Z,1694181262.039 [SBIT](IMPORTANT): DeadReckonUsingMultipleVelocitySources.loadAtStartup=0 bool;

2023-09-08T13:54:22.039Z,1694181262.039 [SBIT](IMPORTANT): DeadReckonUsingSpeedCalculator.loadAtStartup=1 bool;

2023-09-08T13:54:22.039Z,1694181262.039 [SBIT](IMPORTANT): Depth_Keller.offset=-0.35 decibar;

2023-09-08T13:54:22.039Z,1694181262.039 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2023-09-08T13:54:22.039Z,1694181262.039 [SBIT](IMPORTANT): ESPComponent.filterCompleteTimeout=135 minute;

2023-09-08T13:54:22.039Z,1694181262.039 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;

2023-09-08T13:54:22.039Z,1694181262.039 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2023-09-08T13:54:22.039Z,1694181262.039 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;

2023-09-08T13:54:22.039Z,1694181262.039 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius;

2023-09-08T13:54:22.040Z,1694181262.040 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-09-08T13:54:22.041Z,1694181262.041 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-09-08T13:54:22.041Z,1694181262.041 [SBIT](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 1.000000 kilogram_per_cubic_meter;

2023-09-08T13:54:22.041Z,1694181262.041 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter;

2023-09-08T13:54:22.041Z,1694181262.041 [SBIT](IMPORTANT): InternalEnvSim.loadAtStartup=0 bool;

2023-09-08T13:54:22.041Z,1694181262.041 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;

2023-09-08T13:54:22.041Z,1694181262.041 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-09-08T13:54:22.041Z,1694181262.041 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2023-09-08T13:54:22.041Z,1694181262.041 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;

2023-09-08T13:54:22.042Z,1694181262.042 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;

2023-09-08T13:54:22.042Z,1694181262.042 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=193.25 cubic_centimeter;

2023-09-08T13:54:22.042Z,1694181262.042 [SBIT](IMPORTANT): VerticalControl.massDefault=8.91 millimeter;

2023-09-08T13:54:22.042Z,1694181262.042 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-09-08T13:54:22.042Z,1694181262.042 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-09-08T13:54:22.042Z,1694181262.042 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=0 bool;

2023-09-08T13:54:22.042Z,1694181262.042 [SBIT](IMPORTANT): WetLabsSeaOWL_UV_A.loadAtStartup=0 bool;

2023-09-08T13:54:22.432Z,1694181262.432 [MissionManager](IMPORTANT): Started mission Startup
2023-09-08T13:54:22.432Z,1694181262.432 [Startup] Running Loop=1
2023-09-08T13:54:22.432Z,1694181262.432 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-08T13:54:22.432Z,1694181262.432 [Startup:A.GoToSurface] Running Loop=1
2023-09-08T13:54:22.433Z,1694181262.433 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T13:54:22.433Z,1694181262.433 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T13:54:22.433Z,1694181262.433 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T13:54:22.434Z,1694181262.434 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T13:54:22.434Z,1694181262.434 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T13:54:22.434Z,1694181262.434 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T13:54:22.436Z,1694181262.436 [Startup:StartupSatComms] Running Loop=1
2023-09-08T13:54:22.436Z,1694181262.436 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-08T13:54:22.436Z,1694181262.436 [Startup:StartupSatComms:A] Running Loop=1
2023-09-08T13:54:22.840Z,1694181262.840 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-08T13:54:24.433Z,1694181264.433 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135424.00,A,4145.53027,N,08116.65274,W,0.078,0.00,080923,,,A*7D
2023-09-08T13:54:24.436Z,1694181264.436 [NAL9602](INFO): GPS fix at 20230908T135424: (41.758838, -81.277546)
2023-09-08T13:54:24.467Z,1694181264.467 [Startup:StartupSatComms:A] Stopped
2023-09-08T13:54:24.467Z,1694181264.467 [Startup:StartupSatComms:B] Running Loop=1
2023-09-08T13:54:24.874Z,1694181264.874 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T13:54:47.774Z,1694181287.774 [NAL9602](INFO): SBD MO Status=1, MOMSN=9009, MT Status=0, MTMSN=0
2023-09-08T13:54:47.824Z,1694181287.824 [NAL9602](INFO): Sent 170 bytes from file Logs/20230908T133357/Courier0007.lzma
2023-09-08T13:54:47.824Z,1694181287.824 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:54:58.460Z,1694181298.460 [NAL9602](INFO): SBD MO Status=1, MOMSN=9010, MT Status=0, MTMSN=0
2023-09-08T13:54:58.517Z,1694181298.517 [NAL9602](INFO): Sent 18 bytes from file Logs/20230908T133357/Courier0010.lzma
2023-09-08T13:54:58.517Z,1694181298.517 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:55:09.706Z,1694181309.706 [NAL9602](INFO): SBD MO Status=1, MOMSN=9011, MT Status=0, MTMSN=0
2023-09-08T13:55:09.760Z,1694181309.760 [NAL9602](INFO): Sent 270 bytes from file Logs/20230908T135219/Courier0000.lzma
2023-09-08T13:55:09.761Z,1694181309.761 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:55:20.095Z,1694181320.095 [NAL9602](INFO): SBD MO Status=1, MOMSN=9012, MT Status=0, MTMSN=0
2023-09-08T13:55:20.152Z,1694181320.152 [NAL9602](INFO): Sent 159 bytes from file Logs/20230908T133357/Express0005.lzma
2023-09-08T13:55:20.152Z,1694181320.152 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:55:24.541Z,1694181324.541 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-08T13:54:24.5Z
2023-09-08T13:55:24.541Z,1694181324.541 [Startup:StartupSatComms:B] Stopped
2023-09-08T13:55:24.542Z,1694181324.542 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-08T13:55:24.542Z,1694181324.542 [Startup:StartupSatComms] Stopped
2023-09-08T13:55:24.542Z,1694181324.542 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-08T13:55:24.542Z,1694181324.542 [Startup](INFO): Completed Startup
2023-09-08T13:55:24.543Z,1694181324.543 [MissionManager](INFO): Startup is completed.
2023-09-08T13:55:24.543Z,1694181324.543 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-08T13:55:24.543Z,1694181324.543 [Startup] Stopped
2023-09-08T13:55:24.543Z,1694181324.543 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-08T13:55:24.543Z,1694181324.543 [Startup:A.GoToSurface] Stopped
2023-09-08T13:55:24.543Z,1694181324.543 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T13:55:24.642Z,1694181324.642 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T13:55:24.642Z,1694181324.642 [Default] Running Loop=1
2023-09-08T13:55:24.643Z,1694181324.643 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T13:55:24.643Z,1694181324.643 [Default:B.GoToSurface] Running Loop=1
2023-09-08T13:55:24.643Z,1694181324.643 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T13:55:24.643Z,1694181324.643 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T13:55:24.643Z,1694181324.643 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T13:55:24.643Z,1694181324.643 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T13:55:24.644Z,1694181324.644 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T13:55:24.644Z,1694181324.644 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T13:55:24.645Z,1694181324.645 [Default:A.Wait] Running Loop=1
2023-09-08T13:55:24.645Z,1694181324.645 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:55:25.076Z,1694181325.076 [DVL_micro](INFO): Powering down
2023-09-08T13:55:30.236Z,1694181330.236 [NAL9602](INFO): SBD MO Status=1, MOMSN=9013, MT Status=0, MTMSN=0
2023-09-08T13:55:30.285Z,1694181330.285 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T133357/Express0008.lzma
2023-09-08T13:55:30.286Z,1694181330.286 [NAL9602](INFO): Packets left to send: 1
2023-09-08T13:55:37.923Z,1694181337.923 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T13:55:37.924Z,1694181337.924 [Default:A.Wait] Stopped
2023-09-08T13:55:37.924Z,1694181337.924 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:55:38.552Z,1694181338.552 [Default:CheckIn] Running Loop=1
2023-09-08T13:55:38.552Z,1694181338.552 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:55:38.552Z,1694181338.552 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:55:38.882Z,1694181338.882 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-08T13:55:40.332Z,1694181340.332 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135540.00,A,4145.52993,N,08116.65227,W,0.019,0.00,080923,,,A*78
2023-09-08T13:55:40.337Z,1694181340.337 [NAL9602](INFO): GPS fix at 20230908T135540: (41.758832, -81.277538)
2023-09-08T13:55:40.348Z,1694181340.348 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:55:40.348Z,1694181340.348 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:55:40.765Z,1694181340.765 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T13:56:02.279Z,1694181362.279 [NAL9602](INFO): SBD MO Status=1, MOMSN=9014, MT Status=0, MTMSN=0
2023-09-08T13:56:02.336Z,1694181362.336 [NAL9602](INFO): Sent 77 bytes from file Logs/20230908T135219/Courier0004.lzma
2023-09-08T13:56:02.336Z,1694181362.336 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:56:10.682Z,1694181370.682 [NAL9602](INFO): SBD MO Status=1, MOMSN=9015, MT Status=0, MTMSN=0
2023-09-08T13:56:10.728Z,1694181370.728 [NAL9602](INFO): Sent 57 bytes from file Logs/20230908T133357/Express0008.lzma
2023-09-08T13:56:10.728Z,1694181370.728 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:56:18.906Z,1694181378.906 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004084
2023-09-08T13:56:27.978Z,1694181387.978 [NAL9602](INFO): SBD MO Status=1, MOMSN=9016, MT Status=0, MTMSN=0
2023-09-08T13:56:28.036Z,1694181388.036 [NAL9602](INFO): Sent 18 bytes from file Logs/20230908T133357/Express0011.lzma
2023-09-08T13:56:28.037Z,1694181388.037 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:56:28.120Z,1694181388.120 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:56:28.120Z,1694181388.120 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:56:28.120Z,1694181388.120 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:56:33.266Z,1694181393.266 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230908T133357/Express0011.lzma
2023-09-08T13:56:33.266Z,1694181393.266 [DataOverHttps](FAULT): Could not open file Logs/20230908T133357/Express0011.lzma
2023-09-08T13:56:45.158Z,1694181405.158 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230908T133357/Express0011.lzma
2023-09-08T13:56:45.158Z,1694181405.158 [DataOverHttps](FAULT): Could not open file Logs/20230908T133357/Express0011.lzma
2023-09-08T13:56:59.021Z,1694181419.021 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:57:36.360Z,1694181456.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:58:07.484Z,1694181487.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:58:38.608Z,1694181518.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:59:09.740Z,1694181549.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:59:40.864Z,1694181580.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T13:59:46.889Z,1694181586.889 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.727524
2023-09-08T14:00:42.108Z,1694181642.108 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:01:13.232Z,1694181673.232 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:01:19.467Z,1694181679.467 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-08T14:01:19.470Z,1694181679.470 [BPC1](INFO): Received data from all battery sticks.
2023-09-08T14:01:28.693Z,1694181688.693 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:01:28.693Z,1694181688.693 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:01:28.693Z,1694181688.693 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:01:28.693Z,1694181688.693 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:01:29.090Z,1694181689.090 [Default:CheckIn:D] Stopped
2023-09-08T14:01:29.090Z,1694181689.090 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:01:29.500Z,1694181689.500 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.074130 min 
2023-09-08T14:01:29.500Z,1694181689.500 [Default:CheckIn:E] Stopped
2023-09-08T14:01:29.500Z,1694181689.500 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:01:29.500Z,1694181689.500 [Default:CheckIn] Stopped
2023-09-08T14:01:29.500Z,1694181689.500 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:01:29.500Z,1694181689.500 [Default:CheckIn](INFO): Running loop #2
2023-09-08T14:01:29.500Z,1694181689.500 [Default:CheckIn] Running Loop=2
2023-09-08T14:01:29.501Z,1694181689.501 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:01:29.501Z,1694181689.501 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:01:31.505Z,1694181691.505 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,140131.00,A,4145.52923,N,08116.65355,W,0.058,0.00,080923,,,D*77
2023-09-08T14:01:31.507Z,1694181691.507 [NAL9602](INFO): GPS fix at 20230908T140131: (41.758820, -81.277559)
2023-09-08T14:01:31.518Z,1694181691.518 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:01:31.518Z,1694181691.518 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:01:44.368Z,1694181704.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:02:15.492Z,1694181735.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:02:23.879Z,1694181743.879 [NAL9602](INFO): SBD MO Status=2, MOMSN=9017, MT Status=2, MTMSN=0
2023-09-08T14:02:23.879Z,1694181743.879 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T14:02:46.616Z,1694181766.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:02:46.807Z,1694181766.807 [NAL9602](INFO): SBD MO Status=1, MOMSN=9017, MT Status=0, MTMSN=0
2023-09-08T14:02:46.868Z,1694181766.868 [NAL9602](INFO): Sent 206 bytes from file Logs/20230908T135219/Courier0007.lzma
2023-09-08T14:02:46.869Z,1694181766.869 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:02:56.335Z,1694181776.335 [NAL9602](INFO): SBD MO Status=1, MOMSN=9018, MT Status=0, MTMSN=0
2023-09-08T14:02:56.396Z,1694181776.396 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T135219/Express0001.lzma
2023-09-08T14:02:56.396Z,1694181776.396 [NAL9602](INFO): Packets left to send: 3
2023-09-08T14:03:07.861Z,1694181787.861 [NAL9602](INFO): SBD MO Status=1, MOMSN=9019, MT Status=0, MTMSN=0
2023-09-08T14:03:07.913Z,1694181787.913 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T135219/Express0001.lzma
2023-09-08T14:03:07.913Z,1694181787.913 [NAL9602](INFO): Packets left to send: 2
2023-09-08T14:03:17.742Z,1694181797.742 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:03:28.910Z,1694181808.910 [NAL9602](INFO): SBD MO Status=2, MOMSN=9020, MT Status=2, MTMSN=0
2023-09-08T14:03:28.910Z,1694181808.910 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T14:03:48.864Z,1694181828.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:04:00.149Z,1694181840.149 [NAL9602](INFO): SBD MO Status=2, MOMSN=9020, MT Status=2, MTMSN=0
2023-09-08T14:04:00.149Z,1694181840.149 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T14:04:19.341Z,1694181859.341 [NAL9602](INFO): SBD MO Status=1, MOMSN=9020, MT Status=0, MTMSN=0
2023-09-08T14:04:19.400Z,1694181859.400 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T135219/Express0001.lzma
2023-09-08T14:04:19.400Z,1694181859.400 [NAL9602](INFO): Packets left to send: 1
2023-09-08T14:04:19.992Z,1694181859.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:04:31.627Z,1694181871.627 [NAL9602](INFO): SBD MO Status=1, MOMSN=9021, MT Status=0, MTMSN=0
2023-09-08T14:04:31.692Z,1694181871.692 [NAL9602](INFO): Sent 323 bytes from file Logs/20230908T135219/Express0001.lzma
2023-09-08T14:04:31.692Z,1694181871.692 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:04:42.361Z,1694181882.361 [NAL9602](INFO): SBD MO Status=1, MOMSN=9022, MT Status=0, MTMSN=0
2023-09-08T14:04:42.413Z,1694181882.413 [NAL9602](INFO): Sent 228 bytes from file Logs/20230908T135219/Express0005.lzma
2023-09-08T14:04:42.413Z,1694181882.413 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:04:50.318Z,1694181890.318 [NAL9602](INFO): SBD MO Status=1, MOMSN=9023, MT Status=0, MTMSN=0
2023-09-08T14:04:50.368Z,1694181890.368 [NAL9602](INFO): Sent 257 bytes from file Logs/20230908T135219/Express0008.lzma
2023-09-08T14:04:50.368Z,1694181890.368 [NAL9602](INFO): Packets left to send: 0
2023-09-08T14:04:51.116Z,1694181891.116 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:04:59.053Z,1694181899.053 [NAL9602](INFO): SBD MO Status=0, MOMSN=9024, MT Status=0, MTMSN=0
2023-09-08T14:04:59.140Z,1694181899.140 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:04:59.141Z,1694181899.141 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:04:59.141Z,1694181899.141 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:05:22.248Z,1694181922.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:05:29.761Z,1694181929.761 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:05:53.372Z,1694181953.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:06:24.496Z,1694181984.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:06:55.621Z,1694182015.621 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:07:26.748Z,1694182046.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:07:57.872Z,1694182077.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:08:28.996Z,1694182108.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T14:08:41.116Z,1694182121.116 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002886
2023-09-08T14:09:50.164Z,1694182190.164 [CommandExec](IMPORTANT): got command ibit
2023-09-08T14:09:50.493Z,1694182190.493 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-09-08T14:09:50.494Z,1694182190.494 [IBIT](IMPORTANT): Beginning control surface checks.
2023-09-08T14:09:50.497Z,1694182190.497 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-08T14:09:52.078Z,1694182192.078 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,140951.00,A,4145.52892,N,08116.65390,W,0.019,0.00,080923,,,D*7E
2023-09-08T14:09:52.080Z,1694182192.080 [NAL9602](INFO): GPS fix at 20230908T140951: (41.758815, -81.277565)
2023-09-08T14:10:01.400Z,1694182201.400 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005722
CHAN A1 (24V): 0.002099
CHAN A2 (12V): -0.006646
CHAN A3 (5V): -0.003481
CHAN B0 (3.3V): -0.001691
CHAN B1 (3.15aV): -0.000922
CHAN B2 (3.15bV): -0.000869
CHAN B3 (GND): -0.000750
OPEN: 0.000311
Full Scale: +/- 1 mA
2023-09-08T14:10:09.454Z,1694182209.454 [NAL9602](INFO): SBD MO Status=0, MOMSN=9025, MT Status=0, MTMSN=0
2023-09-08T14:10:09.454Z,1694182209.454 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:10:10.665Z,1694182210.665 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141010.00,A,4145.52927,N,08116.65410,W,0.019,0.00,080923,,,D*73
2023-09-08T14:10:10.667Z,1694182210.667 [NAL9602](INFO): GPS fix at 20230908T141010: (41.758821, -81.277568)
2023-09-08T14:10:13.502Z,1694182213.502 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141013.00,A,4145.52928,N,08116.65405,W,0.039,0.00,080923,,,D*79
2023-09-08T14:10:13.505Z,1694182213.505 [NAL9602](INFO): GPS fix at 20230908T141013: (41.758821, -81.277568)
2023-09-08T14:10:16.726Z,1694182216.726 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141016.00,A,4145.52931,N,08116.65398,W,0.019,0.00,080923,,,D*75
2023-09-08T14:10:16.729Z,1694182216.729 [NAL9602](INFO): GPS fix at 20230908T141016: (41.758822, -81.277566)
2023-09-08T14:10:19.554Z,1694182219.554 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141019.00,A,4145.52931,N,08116.65396,W,0.039,0.00,080923,,,D*76
2023-09-08T14:10:19.556Z,1694182219.556 [NAL9602](INFO): GPS fix at 20230908T141019: (41.758822, -81.277566)
2023-09-08T14:10:22.782Z,1694182222.782 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141022.00,A,4145.52935,N,08116.65395,W,0.078,0.00,080923,,,D*7C
2023-09-08T14:10:22.785Z,1694182222.785 [NAL9602](INFO): GPS fix at 20230908T141022: (41.758823, -81.277566)
2023-09-08T14:10:25.621Z,1694182225.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141025.00,A,4145.52942,N,08116.65398,W,0.058,0.00,080923,,,D*74
2023-09-08T14:10:25.623Z,1694182225.623 [NAL9602](INFO): GPS fix at 20230908T141025: (41.758824, -81.277566)
2023-09-08T14:10:28.850Z,1694182228.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141028.00,A,4145.52941,N,08116.65389,W,0.117,0.00,080923,,,D*70
2023-09-08T14:10:28.852Z,1694182228.852 [NAL9602](INFO): GPS fix at 20230908T141028: (41.758823, -81.277565)
2023-09-08T14:10:31.684Z,1694182231.684 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141031.00,A,4145.52932,N,08116.65368,W,0.039,0.00,080923,,,D*7E
2023-09-08T14:10:31.686Z,1694182231.686 [NAL9602](INFO): GPS fix at 20230908T141031: (41.758822, -81.277561)
2023-09-08T14:10:34.606Z,1694182234.606 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141034.00,A,4145.52923,N,08116.65350,W,0.019,0.00,080923,,,D*72
2023-09-08T14:10:34.613Z,1694182234.613 [NAL9602](INFO): GPS fix at 20230908T141034: (41.758820, -81.277558)
2023-09-08T14:10:36.717Z,1694182236.717 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 41.758820 Longitude: -81.277557
2023-09-08T14:10:37.100Z,1694182237.100 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.492001
2023-09-08T14:10:37.100Z,1694182237.100 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-09-08T14:10:37.101Z,1694182237.101 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-09-08T14:10:37.509Z,1694182237.509 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-09-08T14:10:37.510Z,1694182237.510 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-09-08T14:10:37.510Z,1694182237.510 [IBIT](IMPORTANT): Pressure:9.751299 PSI
2023-09-08T14:10:37.510Z,1694182237.510 [IBIT](IMPORTANT): Humidity:13.685753 %
2023-09-08T14:10:37.896Z,1694182237.896 [IBIT](IMPORTANT): Vehicle Pitch:-3.218198 degrees
2023-09-08T14:10:37.896Z,1694182237.896 [IBIT](IMPORTANT): Vehicle  Roll:2.072029 degrees
2023-09-08T14:10:37.897Z,1694182237.897 [IBIT](IMPORTANT): Vehicle  Heading:111.685837 degrees
2023-09-08T14:10:38.277Z,1694182238.277 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-09-08T14:10:38.277Z,1694182238.277 [IBIT](IMPORTANT): buoyancyNeutral: 193.250000 cc
2023-09-08T14:10:38.277Z,1694182238.277 [IBIT](IMPORTANT): massDefault: 0.891000 cm
2023-09-08T14:10:38.278Z,1694182238.278 [IBIT](IMPORTANT): stopDepth: 100.000000 m
2023-09-08T14:10:38.278Z,1694182238.278 [IBIT](IMPORTANT): abortDepth: 120.000000 m
2023-09-08T14:10:38.278Z,1694182238.278 [IBIT](IMPORTANT): IBIT PASSED
2023-09-08T14:10:38.665Z,1694182238.665 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:10:38.665Z,1694182238.665 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:10:38.666Z,1694182238.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:10:38.666Z,1694182238.666 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:10:39.055Z,1694182239.055 [Default:CheckIn:D] Stopped
2023-09-08T14:10:39.055Z,1694182239.055 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:10:39.482Z,1694182239.482 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.240201 min 
2023-09-08T14:10:39.483Z,1694182239.483 [Default:CheckIn:E] Stopped
2023-09-08T14:10:39.483Z,1694182239.483 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:10:39.496Z,1694182239.496 [Default:CheckIn] Stopped
2023-09-08T14:10:39.496Z,1694182239.496 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:10:39.496Z,1694182239.496 [Default:CheckIn](INFO): Running loop #3
2023-09-08T14:10:39.496Z,1694182239.496 [Default:CheckIn] Running Loop=3
2023-09-08T14:10:39.497Z,1694182239.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:10:39.497Z,1694182239.497 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:10:41.430Z,1694182241.430 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141041.00,A,4145.52924,N,08116.65335,W,0.039,0.00,080923,,,D*76
2023-09-08T14:10:41.441Z,1694182241.441 [NAL9602](INFO): GPS fix at 20230908T141041: (41.758821, -81.277556)
2023-09-08T14:10:41.451Z,1694182241.451 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:10:41.451Z,1694182241.451 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:10:50.867Z,1694182250.867 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230908T135219/Courier0010.lzma
2023-09-08T14:10:51.870Z,1694182251.870 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0010.lzma.bak
2023-09-08T14:10:51.870Z,1694182251.870 [DataOverHttps](INFO): SBD MOMSN=18750149
2023-09-08T14:11:09.769Z,1694182269.769 [DataOverHttps](INFO): Sending 773 bytes from file Logs/20230908T135219/Express0011.lzma
2023-09-08T14:11:10.770Z,1694182270.770 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0011.lzma.bak
2023-09-08T14:11:10.770Z,1694182270.770 [DataOverHttps](INFO): SBD MOMSN=18750152
2023-09-08T14:11:13.815Z,1694182273.815 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:11:13.815Z,1694182273.815 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:11:13.815Z,1694182273.815 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:11:14.194Z,1694182274.194 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:11:45.360Z,1694182305.360 [CommandExec](IMPORTANT): got command failComponent
2023-09-08T14:11:45.361Z,1694182305.361 [CommandExec](IMPORTANT): Failed components:
2023-09-08T14:11:45.361Z,1694182305.361 [CommandExec](IMPORTANT): No failed Components.
2023-09-08T14:14:47.182Z,1694182487.182 [CommandExec](IMPORTANT): got command get distance_from_shore
2023-09-08T14:14:47.182Z,1694182487.182 [CommandExec](IMPORTANT): distance_from_shore 62.067566 m
2023-09-08T14:16:14.419Z,1694182574.419 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:16:14.419Z,1694182574.419 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:16:14.420Z,1694182574.420 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:16:14.420Z,1694182574.420 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:16:14.831Z,1694182574.831 [Default:CheckIn:D] Stopped
2023-09-08T14:16:14.831Z,1694182574.831 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:16:15.241Z,1694182575.241 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.836479 min 
2023-09-08T14:16:15.241Z,1694182575.241 [Default:CheckIn:E] Stopped
2023-09-08T14:16:15.241Z,1694182575.241 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:16:15.241Z,1694182575.241 [Default:CheckIn] Stopped
2023-09-08T14:16:15.241Z,1694182575.241 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:16:15.241Z,1694182575.241 [Default:CheckIn](INFO): Running loop #4
2023-09-08T14:16:15.241Z,1694182575.241 [Default:CheckIn] Running Loop=4
2023-09-08T14:16:15.241Z,1694182575.241 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:16:15.241Z,1694182575.241 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:16:17.246Z,1694182577.246 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141617.00,A,4145.52995,N,08116.65404,W,0.039,0.00,080923,,,D*7C
2023-09-08T14:16:17.248Z,1694182577.248 [NAL9602](INFO): GPS fix at 20230908T141617: (41.758832, -81.277567)
2023-09-08T14:16:17.287Z,1694182577.287 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:16:17.287Z,1694182577.287 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:16:25.639Z,1694182585.639 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230908T135219/Courier0013.lzma
2023-09-08T14:16:26.642Z,1694182586.642 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0013.lzma.bak
2023-09-08T14:16:26.642Z,1694182586.642 [DataOverHttps](INFO): SBD MOMSN=18750198
2023-09-08T14:16:29.367Z,1694182589.367 [NAL9602](INFO): SBD MO Status=0, MOMSN=9026, MT Status=0, MTMSN=0
2023-09-08T14:16:29.367Z,1694182589.367 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:16:49.892Z,1694182609.892 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20230908T135219/Express0014.lzma
2023-09-08T14:16:50.894Z,1694182610.894 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0014.lzma.bak
2023-09-08T14:16:50.894Z,1694182610.894 [DataOverHttps](INFO): SBD MOMSN=18750201
2023-09-08T14:16:54.096Z,1694182614.096 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:16:54.096Z,1694182614.096 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:16:54.096Z,1694182614.096 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:17:00.140Z,1694182620.140 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:21:54.866Z,1694182914.866 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:21:54.866Z,1694182914.866 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:21:54.866Z,1694182914.866 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:21:54.866Z,1694182914.866 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:21:55.270Z,1694182915.270 [Default:CheckIn:D] Stopped
2023-09-08T14:21:55.270Z,1694182915.270 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:21:55.676Z,1694182915.676 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.510459 min 
2023-09-08T14:21:55.676Z,1694182915.676 [Default:CheckIn:E] Stopped
2023-09-08T14:21:55.676Z,1694182915.676 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:21:55.676Z,1694182915.676 [Default:CheckIn] Stopped
2023-09-08T14:21:55.676Z,1694182915.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:21:55.676Z,1694182915.676 [Default:CheckIn](INFO): Running loop #5
2023-09-08T14:21:55.676Z,1694182915.676 [Default:CheckIn] Running Loop=5
2023-09-08T14:21:55.677Z,1694182915.677 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:21:55.677Z,1694182915.677 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:21:57.692Z,1694182917.692 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142157.00,A,4145.53064,N,08116.65347,W,0.078,0.00,080923,,,D*7F
2023-09-08T14:21:57.694Z,1694182917.694 [NAL9602](INFO): GPS fix at 20230908T142157: (41.758844, -81.277558)
2023-09-08T14:21:57.705Z,1694182917.705 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:21:57.705Z,1694182917.705 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:22:06.075Z,1694182926.075 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230908T135219/Courier0016.lzma
2023-09-08T14:22:07.078Z,1694182927.078 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0016.lzma.bak
2023-09-08T14:22:07.078Z,1694182927.078 [DataOverHttps](INFO): SBD MOMSN=18750262
2023-09-08T14:22:11.019Z,1694182931.019 [NAL9602](INFO): SBD MO Status=0, MOMSN=9027, MT Status=0, MTMSN=0
2023-09-08T14:22:11.019Z,1694182931.019 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:22:26.591Z,1694182946.591 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T135219/Express0017.lzma
2023-09-08T14:22:27.594Z,1694182947.594 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0017.lzma.bak
2023-09-08T14:22:27.595Z,1694182947.595 [DataOverHttps](INFO): SBD MOMSN=18750265
2023-09-08T14:22:30.450Z,1694182950.450 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:22:30.451Z,1694182950.451 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:22:30.451Z,1694182950.451 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:22:41.764Z,1694182961.764 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:27:31.050Z,1694183251.050 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:27:31.050Z,1694183251.050 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:27:31.050Z,1694183251.050 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:27:31.051Z,1694183251.051 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:27:31.455Z,1694183251.455 [Default:CheckIn:D] Stopped
2023-09-08T14:27:31.455Z,1694183251.455 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:27:31.954Z,1694183251.954 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.113538 min 
2023-09-08T14:27:31.954Z,1694183251.954 [Default:CheckIn:E] Stopped
2023-09-08T14:27:31.954Z,1694183251.954 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:27:31.954Z,1694183251.954 [Default:CheckIn] Stopped
2023-09-08T14:27:31.954Z,1694183251.954 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:27:31.954Z,1694183251.954 [Default:CheckIn](INFO): Running loop #6
2023-09-08T14:27:31.954Z,1694183251.954 [Default:CheckIn] Running Loop=6
2023-09-08T14:27:31.954Z,1694183251.954 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:27:31.954Z,1694183251.954 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:27:33.881Z,1694183253.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142733.00,A,4145.53087,N,08116.65361,W,0.039,0.00,080923,,,D*77
2023-09-08T14:27:33.883Z,1694183253.883 [NAL9602](INFO): GPS fix at 20230908T142733: (41.758848, -81.277560)
2023-09-08T14:27:33.954Z,1694183253.954 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:27:33.954Z,1694183253.954 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:27:46.987Z,1694183266.987 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230908T135219/Courier0019.lzma
2023-09-08T14:27:47.990Z,1694183267.990 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0019.lzma.bak
2023-09-08T14:27:47.990Z,1694183267.990 [DataOverHttps](INFO): SBD MOMSN=18750282
2023-09-08T14:27:53.551Z,1694183273.551 [NAL9602](INFO): SBD MO Status=0, MOMSN=9028, MT Status=0, MTMSN=0
2023-09-08T14:27:53.551Z,1694183273.551 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:28:05.696Z,1694183285.696 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T135219/Express0020.lzma
2023-09-08T14:28:06.698Z,1694183286.698 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0020.lzma.bak
2023-09-08T14:28:06.698Z,1694183286.698 [DataOverHttps](INFO): SBD MOMSN=18750285
2023-09-08T14:28:09.748Z,1694183289.748 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:28:09.748Z,1694183289.748 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:28:09.749Z,1694183289.749 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:28:24.279Z,1694183304.279 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:33:10.307Z,1694183590.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:33:10.307Z,1694183590.307 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:33:10.307Z,1694183590.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:33:10.307Z,1694183590.307 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:33:10.717Z,1694183590.717 [Default:CheckIn:D] Stopped
2023-09-08T14:33:10.717Z,1694183590.717 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:33:11.114Z,1694183591.114 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.767900 min 
2023-09-08T14:33:11.114Z,1694183591.114 [Default:CheckIn:E] Stopped
2023-09-08T14:33:11.114Z,1694183591.114 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:33:11.114Z,1694183591.114 [Default:CheckIn] Stopped
2023-09-08T14:33:11.114Z,1694183591.114 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:33:11.115Z,1694183591.115 [Default:CheckIn](INFO): Running loop #7
2023-09-08T14:33:11.115Z,1694183591.115 [Default:CheckIn] Running Loop=7
2023-09-08T14:33:11.115Z,1694183591.115 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:33:11.115Z,1694183591.115 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:33:13.130Z,1694183593.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143312.00,A,4145.52995,N,08116.65400,W,0.019,0.00,080923,,,D*78
2023-09-08T14:33:13.132Z,1694183593.132 [NAL9602](INFO): GPS fix at 20230908T143312: (41.758832, -81.277567)
2023-09-08T14:33:13.143Z,1694183593.143 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:33:13.143Z,1694183593.143 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:33:21.747Z,1694183601.747 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230908T135219/Courier0022.lzma
2023-09-08T14:33:22.750Z,1694183602.750 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0022.lzma.bak
2023-09-08T14:33:22.750Z,1694183602.750 [DataOverHttps](INFO): SBD MOMSN=18750302
2023-09-08T14:33:29.703Z,1694183609.703 [NAL9602](INFO): SBD MO Status=0, MOMSN=9029, MT Status=0, MTMSN=0
2023-09-08T14:33:29.703Z,1694183609.703 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:33:43.759Z,1694183623.759 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T135219/Express0023.lzma
2023-09-08T14:33:44.762Z,1694183624.762 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0023.lzma.bak
2023-09-08T14:33:44.762Z,1694183624.762 [DataOverHttps](INFO): SBD MOMSN=18750310
2023-09-08T14:33:47.956Z,1694183627.956 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:33:47.956Z,1694183627.956 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:33:47.956Z,1694183627.956 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:34:00.453Z,1694183640.453 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:38:48.361Z,1694183928.361 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:38:48.361Z,1694183928.361 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:38:48.361Z,1694183928.361 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:38:48.361Z,1694183928.361 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:38:48.748Z,1694183928.748 [Default:CheckIn:D] Stopped
2023-09-08T14:38:48.748Z,1694183928.748 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:38:49.149Z,1694183929.149 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.401750 min 
2023-09-08T14:38:49.149Z,1694183929.149 [Default:CheckIn:E] Stopped
2023-09-08T14:38:49.149Z,1694183929.149 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:38:49.149Z,1694183929.149 [Default:CheckIn] Stopped
2023-09-08T14:38:49.149Z,1694183929.149 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:38:49.149Z,1694183929.149 [Default:CheckIn](INFO): Running loop #8
2023-09-08T14:38:49.149Z,1694183929.149 [Default:CheckIn] Running Loop=8
2023-09-08T14:38:49.149Z,1694183929.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:38:49.150Z,1694183929.150 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:38:51.165Z,1694183931.165 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143850.00,A,4145.53032,N,08116.65430,W,0.000,0.00,080923,,,D*7B
2023-09-08T14:38:51.167Z,1694183931.167 [NAL9602](INFO): GPS fix at 20230908T143850: (41.758839, -81.277572)
2023-09-08T14:38:51.178Z,1694183931.178 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:38:51.178Z,1694183931.178 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:38:59.603Z,1694183939.603 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230908T135219/Courier0025.lzma
2023-09-08T14:39:00.606Z,1694183940.606 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0025.lzma.bak
2023-09-08T14:39:00.606Z,1694183940.606 [DataOverHttps](INFO): SBD MOMSN=18750352
2023-09-08T14:39:10.560Z,1694183950.560 [NAL9602](INFO): SBD MO Status=0, MOMSN=9030, MT Status=0, MTMSN=0
2023-09-08T14:39:10.561Z,1694183950.561 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:39:18.648Z,1694183958.648 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T135219/Express0026.lzma
2023-09-08T14:39:19.650Z,1694183959.650 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0026.lzma.bak
2023-09-08T14:39:19.650Z,1694183959.650 [DataOverHttps](INFO): SBD MOMSN=18750356
2023-09-08T14:39:22.700Z,1694183962.700 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:39:22.700Z,1694183962.700 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:39:22.700Z,1694183962.700 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:39:41.344Z,1694183981.344 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:44:23.375Z,1694184263.375 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:44:23.375Z,1694184263.375 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:44:23.376Z,1694184263.376 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:44:23.376Z,1694184263.376 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:44:23.785Z,1694184263.785 [Default:CheckIn:D] Stopped
2023-09-08T14:44:23.785Z,1694184263.785 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:44:24.182Z,1694184264.182 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.985714 min 
2023-09-08T14:44:24.182Z,1694184264.182 [Default:CheckIn:E] Stopped
2023-09-08T14:44:24.182Z,1694184264.182 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:44:24.182Z,1694184264.182 [Default:CheckIn] Stopped
2023-09-08T14:44:24.183Z,1694184264.183 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:44:24.183Z,1694184264.183 [Default:CheckIn](INFO): Running loop #9
2023-09-08T14:44:24.183Z,1694184264.183 [Default:CheckIn] Running Loop=9
2023-09-08T14:44:24.183Z,1694184264.183 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:44:24.183Z,1694184264.183 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:44:26.198Z,1694184266.198 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144425.00,A,4145.53029,N,08116.65376,W,0.039,0.00,080923,,,D*77
2023-09-08T14:44:26.201Z,1694184266.201 [NAL9602](INFO): GPS fix at 20230908T144425: (41.758838, -81.277563)
2023-09-08T14:44:26.211Z,1694184266.211 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:44:26.211Z,1694184266.211 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:44:36.963Z,1694184276.963 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230908T135219/Courier0028.lzma
2023-09-08T14:44:37.966Z,1694184277.966 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0028.lzma.bak
2023-09-08T14:44:37.966Z,1694184277.966 [DataOverHttps](INFO): SBD MOMSN=18750367
2023-09-08T14:44:44.827Z,1694184284.827 [NAL9602](INFO): SBD MO Status=0, MOMSN=9031, MT Status=0, MTMSN=0
2023-09-08T14:44:44.827Z,1694184284.827 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:44:59.863Z,1694184299.863 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T135219/Express0029.lzma
2023-09-08T14:45:00.867Z,1694184300.866 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0029.lzma.bak
2023-09-08T14:45:00.867Z,1694184300.867 [DataOverHttps](INFO): SBD MOMSN=18750379
2023-09-08T14:45:03.879Z,1694184303.879 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:45:03.879Z,1694184303.879 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:45:03.884Z,1694184303.884 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:45:15.530Z,1694184315.530 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:50:04.431Z,1694184604.431 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:50:04.431Z,1694184604.431 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:50:04.431Z,1694184604.431 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:50:04.431Z,1694184604.431 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:50:04.802Z,1694184604.802 [Default:CheckIn:D] Stopped
2023-09-08T14:50:04.802Z,1694184604.802 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:50:05.212Z,1694184605.212 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.669320 min 
2023-09-08T14:50:05.212Z,1694184605.212 [Default:CheckIn:E] Stopped
2023-09-08T14:50:05.212Z,1694184605.212 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:50:05.212Z,1694184605.212 [Default:CheckIn] Stopped
2023-09-08T14:50:05.212Z,1694184605.212 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:50:05.212Z,1694184605.212 [Default:CheckIn](INFO): Running loop #10
2023-09-08T14:50:05.212Z,1694184605.212 [Default:CheckIn] Running Loop=10
2023-09-08T14:50:05.213Z,1694184605.213 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:50:05.213Z,1694184605.213 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:50:07.218Z,1694184607.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145006.00,A,4145.52862,N,08116.65320,W,0.058,0.00,080923,,,D*71
2023-09-08T14:50:07.232Z,1694184607.232 [NAL9602](INFO): GPS fix at 20230908T145006: (41.758810, -81.277553)
2023-09-08T14:50:07.242Z,1694184607.242 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:50:07.242Z,1694184607.242 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:50:15.791Z,1694184615.791 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230908T135219/Courier0031.lzma
2023-09-08T14:50:16.794Z,1694184616.794 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0031.lzma.bak
2023-09-08T14:50:16.794Z,1694184616.794 [DataOverHttps](INFO): SBD MOMSN=18750391
2023-09-08T14:50:18.531Z,1694184618.531 [NAL9602](INFO): SBD MO Status=0, MOMSN=9032, MT Status=0, MTMSN=0
2023-09-08T14:50:18.531Z,1694184618.531 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:50:36.439Z,1694184636.439 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230908T135219/Express0032.lzma
2023-09-08T14:50:37.438Z,1694184637.438 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0032.lzma.bak
2023-09-08T14:50:37.438Z,1694184637.438 [DataOverHttps](INFO): SBD MOMSN=18750394
2023-09-08T14:50:42.121Z,1694184642.121 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:50:42.121Z,1694184642.121 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:50:42.121Z,1694184642.121 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:50:49.325Z,1694184649.325 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:55:42.660Z,1694184942.660 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:55:42.660Z,1694184942.660 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:55:42.660Z,1694184942.660 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:55:42.660Z,1694184942.660 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:55:43.115Z,1694184943.115 [Default:CheckIn:D] Stopped
2023-09-08T14:55:43.115Z,1694184943.115 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:55:43.477Z,1694184943.477 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.307869 min 
2023-09-08T14:55:43.477Z,1694184943.477 [Default:CheckIn:E] Stopped
2023-09-08T14:55:43.477Z,1694184943.477 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:55:43.477Z,1694184943.477 [Default:CheckIn] Stopped
2023-09-08T14:55:43.478Z,1694184943.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:55:43.478Z,1694184943.478 [Default:CheckIn](INFO): Running loop #11
2023-09-08T14:55:43.478Z,1694184943.478 [Default:CheckIn] Running Loop=11
2023-09-08T14:55:43.478Z,1694184943.478 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:55:43.478Z,1694184943.478 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:55:45.474Z,1694184945.474 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145545.00,A,4145.53088,N,08116.65379,W,0.039,0.00,080923,,,D*75
2023-09-08T14:55:45.477Z,1694184945.477 [NAL9602](INFO): GPS fix at 20230908T145545: (41.758848, -81.277563)
2023-09-08T14:55:45.487Z,1694184945.487 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:55:45.487Z,1694184945.487 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:55:55.559Z,1694184955.559 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230908T135219/Courier0034.lzma
2023-09-08T14:55:56.562Z,1694184956.562 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0034.lzma.bak
2023-09-08T14:55:56.562Z,1694184956.562 [DataOverHttps](INFO): SBD MOMSN=18750410
2023-09-08T14:56:02.847Z,1694184962.847 [NAL9602](INFO): SBD MO Status=0, MOMSN=9033, MT Status=0, MTMSN=0
2023-09-08T14:56:02.847Z,1694184962.847 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:56:17.763Z,1694184977.763 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T135219/Express0035.lzma
2023-09-08T14:56:18.766Z,1694184978.766 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0035.lzma.bak
2023-09-08T14:56:18.766Z,1694184978.766 [DataOverHttps](INFO): SBD MOMSN=18750413
2023-09-08T14:56:21.869Z,1694184981.869 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:56:21.869Z,1694184981.869 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:56:21.869Z,1694184981.869 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:56:33.620Z,1694184993.620 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:01:22.672Z,1694185282.672 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T15:01:22.672Z,1694185282.672 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:01:22.672Z,1694185282.672 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:01:22.672Z,1694185282.672 [Default:CheckIn:D] Running Loop=1
2023-09-08T15:01:23.089Z,1694185283.089 [Default:CheckIn:D] Stopped
2023-09-08T15:01:23.089Z,1694185283.089 [Default:CheckIn:E] Running Loop=1
2023-09-08T15:01:23.482Z,1694185283.482 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.974105 min 
2023-09-08T15:01:23.482Z,1694185283.482 [Default:CheckIn:E] Stopped
2023-09-08T15:01:23.482Z,1694185283.482 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T15:01:23.482Z,1694185283.482 [Default:CheckIn] Stopped
2023-09-08T15:01:23.482Z,1694185283.482 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:01:23.482Z,1694185283.482 [Default:CheckIn](INFO): Running loop #12
2023-09-08T15:01:23.482Z,1694185283.482 [Default:CheckIn] Running Loop=12
2023-09-08T15:01:23.482Z,1694185283.482 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:01:23.482Z,1694185283.482 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:01:25.495Z,1694185285.495 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150125.00,A,4145.52973,N,08116.65356,W,0.058,2.61,080923,,,D*70
2023-09-08T15:01:25.497Z,1694185285.497 [NAL9602](INFO): GPS fix at 20230908T150125: (41.758829, -81.277559)
2023-09-08T15:01:25.508Z,1694185285.508 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:01:25.508Z,1694185285.508 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:01:33.471Z,1694185293.471 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230908T135219/Courier0037.lzma
2023-09-08T15:01:34.474Z,1694185294.474 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0037.lzma.bak
2023-09-08T15:01:34.474Z,1694185294.474 [DataOverHttps](INFO): SBD MOMSN=18750424
2023-09-08T15:01:41.667Z,1694185301.667 [NAL9602](INFO): SBD MO Status=0, MOMSN=9034, MT Status=0, MTMSN=0
2023-09-08T15:01:41.667Z,1694185301.667 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:02:07.944Z,1694185327.944 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T135219/Express0038.lzma
2023-09-08T15:02:10.190Z,1694185330.190 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0038.lzma.bak
2023-09-08T15:02:10.191Z,1694185330.191 [DataOverHttps](INFO): SBD MOMSN=18750427
2023-09-08T15:02:12.369Z,1694185332.369 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:02:12.411Z,1694185332.411 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:02:12.411Z,1694185332.411 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:02:12.411Z,1694185332.411 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:03:35.203Z,1694185415.203 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-09-08T15:06:03.378Z,1694185563.378 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T150602
2023-09-08T15:06:10.804Z,1694185570.804 [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" fz9f 1 2
2023-09-08T15:06:10.832Z,1694185570.832 [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" fz9f 1 2.000000
2023-09-08T15:06:10.833Z,1694185570.833 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=fz9f
2023-09-08T15:06:10.834Z,1694185570.834 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='fz9f'): "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 41.76107 degree;set transit.Longitude -81.27857 degree;set transit.Depth 1 m;set transit.MaxDepth 3 m", ASAP
2023-09-08T15:06:11.806Z,1694185571.806 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T150611
2023-09-08T15:06:19.489Z,1694185579.489 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .01 km;run" fz9f 2 2
2023-09-08T15:06:19.494Z,1694185579.494 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .01 km;run" fz9f 2 2.000000
2023-09-08T15:06:19.494Z,1694185579.494 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=fz9f
2023-09-08T15:06:19.496Z,1694185579.496 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='fz9f'): "set transit.MinOffshore .01 km;run", ASAP
2023-09-08T15:06:19.622Z,1694185579.622 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-09-08T15:06:19.622Z,1694185579.622 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-09-08T15:06:19.622Z,1694185579.622 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-09-08T15:06:19.769Z,1694185579.769 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-09-08T15:06:19.771Z,1694185579.771 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-09-08T15:06:19.775Z,1694185579.775 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-09-08T15:06:19.786Z,1694185579.786 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-09-08T15:06:19.793Z,1694185579.793 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-09-08T15:06:19.795Z,1694185579.795 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-09-08T15:06:19.803Z,1694185579.803 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-09-08T15:06:19.810Z,1694185579.810 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-09-08T15:06:19.815Z,1694185579.815 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-09-08T15:06:19.830Z,1694185579.830 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-09-08T15:06:19.835Z,1694185579.835 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-09-08T15:06:19.846Z,1694185579.846 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-09-08T15:06:19.852Z,1694185579.852 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-09-08T15:06:19.853Z,1694185579.853 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-09-08T15:06:19.853Z,1694185579.853 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-09-08T15:06:19.966Z,1694185579.966 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-09-08T15:06:19.977Z,1694185579.977 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-09-08T15:06:19.979Z,1694185579.979 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-09-08T15:06:19.987Z,1694185579.987 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-09-08T15:06:19.990Z,1694185579.990 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-09-08T15:06:20.030Z,1694185580.030 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-09-08T15:06:20.035Z,1694185580.035 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-09-08T15:06:20.054Z,1694185580.054 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-09-08T15:06:20.077Z,1694185580.077 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T15:06:20.149Z,1694185580.149 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-09-08T15:06:20.150Z,1694185580.150 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-09-08T15:06:20.197Z,1694185580.197 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-09-08T15:06:20.208Z,1694185580.208 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-09-08T15:06:20.211Z,1694185580.211 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-09-08T15:06:20.214Z,1694185580.214 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-09-08T15:06:20.215Z,1694185580.215 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-09-08T15:06:20.240Z,1694185580.240 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-09-08T15:06:20.257Z,1694185580.257 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-09-08T15:06:20.282Z,1694185580.282 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-08T15:06:20.282Z,1694185580.282 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-08T15:06:20.302Z,1694185580.302 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2023-09-08T15:06:20.309Z,1694185580.309 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-08T15:06:20.317Z,1694185580.317 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-08T15:06:20.325Z,1694185580.325 [transit:MassHold.Pitch](DEBUG): Construct.
2023-09-08T15:06:20.340Z,1694185580.340 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-09-08T15:06:20.357Z,1694185580.357 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-09-08T15:06:20.365Z,1694185580.365 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-09-08T15:06:20.394Z,1694185580.394 [MissionManager](DEBUG): 
mission transit {
  """
  Vehicle transits to desired waypoint and can be commanded to use/hold
  mass or buoyancy.
  """

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

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

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

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

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

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

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

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

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

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

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

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

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

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  assign in sequence NeedComms:WaitForPitchUp = 1 second

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MinAltitude = MinAltitude

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

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

    set position = Control:VerticalControl.buoyancyNeutral
  }

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

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate Transit {
    run in sequence

    behavior Guidance:Pitch {
      run in parallel

      set depth = Depth
    }

    behavior Guidance:SetSpeed {
      run in parallel

      set speed = Speed
    }

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

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

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


2023-09-08T15:06:20.394Z,1694185580.394 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-09-08T15:06:29.032Z,1694185589.032 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute
2023-09-08T15:06:29.033Z,1694185589.033 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2023-09-08T15:06:29.033Z,1694185589.033 [CommandExec](IMPORTANT): got command set transit.Latitude 41.76107 degree
2023-09-08T15:06:29.034Z,1694185589.034 [CommandExec](IMPORTANT): got command set transit.Longitude -81.27857 degree
2023-09-08T15:06:29.035Z,1694185589.035 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-09-08T15:06:29.035Z,1694185589.035 [CommandExec](IMPORTANT): got command set transit.MaxDepth 3 meter
2023-09-08T15:06:30.713Z,1694185590.713 [CommandExec](IMPORTANT): got command set transit.MinOffshore .01 kilometer
2023-09-08T15:06:30.713Z,1694185590.713 [CommandExec](IMPORTANT): got command run
2023-09-08T15:06:30.715Z,1694185590.715 [CommandExec](IMPORTANT): Running 
2023-09-08T15:06:30.993Z,1694185590.993 [Default] Stopped
2023-09-08T15:06:30.994Z,1694185590.994 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T15:06:30.994Z,1694185590.994 [Default:B.GoToSurface] Stopped
2023-09-08T15:06:30.994Z,1694185590.994 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T15:06:30.994Z,1694185590.994 [Default:CheckIn] Stopped
2023-09-08T15:06:30.994Z,1694185590.994 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:06:30.994Z,1694185590.994 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:06:30.994Z,1694185590.994 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:06:30.994Z,1694185590.994 [MissionManager](IMPORTANT): Started mission transit
2023-09-08T15:06:30.995Z,1694185590.995 [transit] Running Loop=1
2023-09-08T15:06:30.995Z,1694185590.995 [transit](DEBUG): Aggregate::initialize transit
2023-09-08T15:06:30.995Z,1694185590.995 [transit:StandardEnvelopes] Running Loop=1
2023-09-08T15:06:30.995Z,1694185590.995 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-09-08T15:06:30.995Z,1694185590.995 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-08T15:06:30.995Z,1694185590.995 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-09-08T15:06:30.995Z,1694185590.995 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-08T15:06:30.995Z,1694185590.995 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-09-08T15:06:31.000Z,1694185591.000 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-08T15:06:31.001Z,1694185591.001 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-09-08T15:06:31.001Z,1694185591.001 [transit:BackseatDriver] Running Loop=1
2023-09-08T15:06:31.002Z,1694185591.002 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2023-09-08T15:06:31.002Z,1694185591.002 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-08T15:06:31.003Z,1694185591.003 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-08T15:06:31.003Z,1694185591.003 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-09-08T15:06:31.003Z,1694185591.003 [transit:MassHold.Pitch] Running Loop=1
2023-09-08T15:06:31.008Z,1694185591.008 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-09-08T15:06:31.008Z,1694185591.008 [transit:B] Running Loop=1
2023-09-08T15:06:31.009Z,1694185591.009 [transit:MassHold.Pitch] Running Loop=1
2023-09-08T15:06:31.009Z,1694185591.009 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-08T15:06:31.009Z,1694185591.009 [transit:BackseatDriver] Running Loop=1
2023-09-08T15:06:31.010Z,1694185591.010 [transit:StandardEnvelopes] Running Loop=1
2023-09-08T15:06:31.010Z,1694185591.010 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-08T15:06:31.026Z,1694185591.026 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-08T15:06:31.027Z,1694185591.027 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-08T15:06:31.032Z,1694185591.032 [transit:B] Stopped
2023-09-08T15:06:31.032Z,1694185591.032 [transit:C] Running Loop=1
2023-09-08T15:06:31.382Z,1694185591.382 [transit:C] Stopped
2023-09-08T15:06:31.382Z,1694185591.382 [transit:E] Running Loop=1
2023-09-08T15:06:31.774Z,1694185591.774 [transit:E] Stopped
2023-09-08T15:06:31.774Z,1694185591.774 [transit:F] Running Loop=1
2023-09-08T15:06:32.207Z,1694185592.207 [transit:F] Stopped
2023-09-08T15:06:32.208Z,1694185592.208 [transit:G] Running Loop=1
2023-09-08T15:06:32.635Z,1694185592.635 [transit:G] Stopped
2023-09-08T15:06:32.640Z,1694185592.640 [transit:Transit] Running Loop=1
2023-09-08T15:06:32.640Z,1694185592.640 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-09-08T15:06:32.640Z,1694185592.640 [transit:Transit:A.Pitch] Running Loop=1
2023-09-08T15:06:32.640Z,1694185592.640 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-09-08T15:06:32.640Z,1694185592.640 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-08T15:06:32.640Z,1694185592.640 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-09-08T15:06:32.641Z,1694185592.641 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-09-08T15:06:32.641Z,1694185592.641 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-09-08T15:06:32.642Z,1694185592.642 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 41.761070,-81.278572
2023-09-08T15:06:32.643Z,1694185592.643 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2023-09-08T15:06:32.967Z,1694185592.967 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-08T15:06:32.967Z,1694185592.967 [transit:Transit:A.Pitch] Running Loop=1
2023-09-08T15:06:51.635Z,1694185611.635 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T150651
2023-09-08T15:07:00.881Z,1694185620.881 [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" fzb1 1 2
2023-09-08T15:07:00.977Z,1694185620.977 [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" fzb1 1 2.000000
2023-09-08T15:07:00.977Z,1694185620.977 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=fzb1
2023-09-08T15:07:00.978Z,1694185620.978 [CommandExec](IMPORTANT): Scheduled #3 (#1 of 2 with id='fzb1'): "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 41.76107 degree;set transit.Longitude -81.27857 degree;set transit.Depth 1 m;set transit.MaxDepth 3 m", ASAP
2023-09-08T15:07:01.882Z,1694185621.882 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T150701
2023-09-08T15:07:09.809Z,1694185629.809 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .01 km;run" fzb1 2 2
2023-09-08T15:07:09.855Z,1694185629.855 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .01 km;run" fzb1 2 2.000000
2023-09-08T15:07:09.855Z,1694185629.855 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=fzb1
2023-09-08T15:07:09.856Z,1694185629.856 [CommandExec](IMPORTANT): Scheduled #4 (#2 of 2 with id='fzb1'): "set transit.MinOffshore .01 km;run", ASAP
2023-09-08T15:07:09.968Z,1694185629.968 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-09-08T15:07:09.969Z,1694185629.969 [MissionManager](INFO): Uninitializing Mission transit
2023-09-08T15:07:09.969Z,1694185629.969 [transit] Stopped
2023-09-08T15:07:09.969Z,1694185629.969 [transit](DEBUG): Aggregate::uninitialize transit
2023-09-08T15:07:09.969Z,1694185629.969 [transit:StandardEnvelopes] Stopped
2023-09-08T15:07:09.969Z,1694185629.969 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2023-09-08T15:07:09.969Z,1694185629.969 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2023-09-08T15:07:09.969Z,1694185629.969 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2023-09-08T15:07:09.969Z,1694185629.969 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2023-09-08T15:07:09.969Z,1694185629.969 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2023-09-08T15:07:09.969Z,1694185629.969 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2023-09-08T15:07:09.969Z,1694185629.969 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2023-09-08T15:07:09.969Z,1694185629.969 [transit:BackseatDriver] Stopped
2023-09-08T15:07:09.970Z,1694185629.970 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2023-09-08T15:07:09.970Z,1694185629.970 [transit:BackseatDriver:A.BackseatDriver] Stopped
2023-09-08T15:07:09.970Z,1694185629.970 [transit:BuoyancyHold.Buoyancy] Stopped
2023-09-08T15:07:09.970Z,1694185629.970 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-09-08T15:07:09.970Z,1694185629.970 [transit:MassHold.Pitch] Stopped
2023-09-08T15:07:09.970Z,1694185629.970 [transit:Transit] Stopped
2023-09-08T15:07:09.970Z,1694185629.970 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2023-09-08T15:07:09.970Z,1694185629.970 [transit:Transit:A.Pitch] Stopped
2023-09-08T15:07:09.970Z,1694185629.970 [transit:Transit:B.SetSpeed] Stopped
2023-09-08T15:07:09.970Z,1694185629.970 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2023-09-08T15:07:09.970Z,1694185629.970 [transit:Transit:Wpt1.Waypoint] Stopped
2023-09-08T15:07:09.970Z,1694185629.970 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2023-09-08T15:07:09.983Z,1694185629.983 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-09-08T15:07:10.001Z,1694185630.001 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-09-08T15:07:10.149Z,1694185630.149 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-09-08T15:07:10.156Z,1694185630.156 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-09-08T15:07:10.158Z,1694185630.158 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-09-08T15:07:10.168Z,1694185630.168 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-09-08T15:07:10.171Z,1694185630.171 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-09-08T15:07:10.189Z,1694185630.189 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-09-08T15:07:10.191Z,1694185630.191 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-09-08T15:07:10.201Z,1694185630.201 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-09-08T15:07:10.203Z,1694185630.203 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-09-08T15:07:10.214Z,1694185630.214 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-09-08T15:07:10.228Z,1694185630.228 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-09-08T15:07:10.230Z,1694185630.230 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-09-08T15:07:10.240Z,1694185630.240 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-09-08T15:07:10.241Z,1694185630.241 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-09-08T15:07:10.241Z,1694185630.241 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-09-08T15:07:10.388Z,1694185630.388 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-09-08T15:07:10.390Z,1694185630.390 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-09-08T15:07:10.397Z,1694185630.397 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-09-08T15:07:10.404Z,1694185630.404 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-09-08T15:07:10.406Z,1694185630.406 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-09-08T15:07:10.414Z,1694185630.414 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-09-08T15:07:10.419Z,1694185630.419 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-09-08T15:07:10.430Z,1694185630.430 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-09-08T15:07:10.454Z,1694185630.454 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T15:07:10.523Z,1694185630.523 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-09-08T15:07:10.523Z,1694185630.523 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-09-08T15:07:10.612Z,1694185630.612 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-09-08T15:07:10.615Z,1694185630.615 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-09-08T15:07:10.625Z,1694185630.625 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-09-08T15:07:10.627Z,1694185630.627 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-09-08T15:07:10.634Z,1694185630.634 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-09-08T15:07:10.648Z,1694185630.648 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-09-08T15:07:10.675Z,1694185630.675 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-09-08T15:07:10.702Z,1694185630.702 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-08T15:07:10.703Z,1694185630.703 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-08T15:07:10.750Z,1694185630.750 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2023-09-08T15:07:10.757Z,1694185630.757 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-08T15:07:10.759Z,1694185630.759 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-08T15:07:10.778Z,1694185630.778 [transit:MassHold.Pitch](DEBUG): Construct.
2023-09-08T15:07:10.787Z,1694185630.787 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-09-08T15:07:10.795Z,1694185630.795 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-09-08T15:07:10.800Z,1694185630.800 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-09-08T15:07:10.820Z,1694185630.820 [MissionManager](DEBUG): 
mission transit {
  """
  Vehicle transits to desired waypoint and can be commanded to use/hold
  mass or buoyancy.
  """

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

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

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

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

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

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

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

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

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

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

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

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

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

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  assign in sequence NeedComms:WaitForPitchUp = 1 second

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MinAltitude = MinAltitude

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

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

    set position = Control:VerticalControl.buoyancyNeutral
  }

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

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate Transit {
    run in sequence

    behavior Guidance:Pitch {
      run in parallel

      set depth = Depth
    }

    behavior Guidance:SetSpeed {
      run in parallel

      set speed = Speed
    }

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

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

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


2023-09-08T15:07:10.820Z,1694185630.820 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-09-08T15:07:11.028Z,1694185631.028 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T15:07:11.028Z,1694185631.028 [Default] Running Loop=1
2023-09-08T15:07:11.028Z,1694185631.028 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T15:07:11.028Z,1694185631.028 [Default:B.GoToSurface] Running Loop=1
2023-09-08T15:07:11.028Z,1694185631.028 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T15:07:11.029Z,1694185631.029 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T15:07:11.029Z,1694185631.029 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T15:07:11.029Z,1694185631.029 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T15:07:11.029Z,1694185631.029 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T15:07:11.030Z,1694185631.030 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T15:07:11.030Z,1694185631.030 [Default:A.Wait] Running Loop=1
2023-09-08T15:07:11.030Z,1694185631.030 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:07:21.667Z,1694185641.667 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute
2023-09-08T15:07:21.668Z,1694185641.668 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2023-09-08T15:07:21.669Z,1694185641.669 [CommandExec](IMPORTANT): got command set transit.Latitude 41.76107 degree
2023-09-08T15:07:21.670Z,1694185641.670 [CommandExec](IMPORTANT): got command set transit.Longitude -81.27857 degree
2023-09-08T15:07:21.671Z,1694185641.671 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-09-08T15:07:21.671Z,1694185641.671 [CommandExec](IMPORTANT): got command set transit.MaxDepth 3 meter
2023-09-08T15:07:23.291Z,1694185643.291 [CommandExec](IMPORTANT): got command set transit.MinOffshore .01 kilometer
2023-09-08T15:07:23.292Z,1694185643.292 [CommandExec](IMPORTANT): got command run
2023-09-08T15:07:23.293Z,1694185643.293 [CommandExec](IMPORTANT): Running 
2023-09-08T15:07:23.556Z,1694185643.556 [Default] Stopped
2023-09-08T15:07:23.556Z,1694185643.556 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T15:07:23.556Z,1694185643.556 [Default:A.Wait] Stopped
2023-09-08T15:07:23.556Z,1694185643.556 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:07:23.557Z,1694185643.557 [Default:B.GoToSurface] Stopped
2023-09-08T15:07:23.557Z,1694185643.557 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T15:07:23.557Z,1694185643.557 [MissionManager](IMPORTANT): Started mission transit
2023-09-08T15:07:23.557Z,1694185643.557 [transit] Running Loop=1
2023-09-08T15:07:23.557Z,1694185643.557 [transit](DEBUG): Aggregate::initialize transit
2023-09-08T15:07:23.557Z,1694185643.557 [transit:StandardEnvelopes] Running Loop=1
2023-09-08T15:07:23.557Z,1694185643.557 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-09-08T15:07:23.557Z,1694185643.557 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-08T15:07:23.558Z,1694185643.558 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-09-08T15:07:23.558Z,1694185643.558 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-08T15:07:23.558Z,1694185643.558 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-09-08T15:07:23.558Z,1694185643.558 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-08T15:07:23.558Z,1694185643.558 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-09-08T15:07:23.558Z,1694185643.558 [transit:BackseatDriver] Running Loop=1
2023-09-08T15:07:23.558Z,1694185643.558 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2023-09-08T15:07:23.558Z,1694185643.558 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-08T15:07:23.558Z,1694185643.558 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-08T15:07:23.558Z,1694185643.558 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-09-08T15:07:23.559Z,1694185643.559 [transit:MassHold.Pitch] Running Loop=1
2023-09-08T15:07:23.559Z,1694185643.559 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-09-08T15:07:23.559Z,1694185643.559 [transit:B] Running Loop=1
2023-09-08T15:07:23.559Z,1694185643.559 [transit:MassHold.Pitch] Running Loop=1
2023-09-08T15:07:23.560Z,1694185643.560 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-08T15:07:23.560Z,1694185643.560 [transit:BackseatDriver] Running Loop=1
2023-09-08T15:07:23.561Z,1694185643.561 [transit:StandardEnvelopes] Running Loop=1
2023-09-08T15:07:23.561Z,1694185643.561 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-08T15:07:23.573Z,1694185643.573 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-08T15:07:23.573Z,1694185643.573 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-08T15:07:23.574Z,1694185643.574 [transit:B] Stopped
2023-09-08T15:07:23.574Z,1694185643.574 [transit:C] Running Loop=1
2023-09-08T15:07:23.927Z,1694185643.927 [transit:C] Stopped
2023-09-08T15:07:23.927Z,1694185643.927 [transit:E] Running Loop=1
2023-09-08T15:07:24.343Z,1694185644.343 [transit:E] Stopped
2023-09-08T15:07:24.343Z,1694185644.343 [transit:F] Running Loop=1
2023-09-08T15:07:24.818Z,1694185644.818 [transit:F] Stopped
2023-09-08T15:07:24.818Z,1694185644.818 [transit:G] Running Loop=1
2023-09-08T15:07:25.148Z,1694185645.148 [transit:G] Stopped
2023-09-08T15:07:25.148Z,1694185645.148 [transit:Transit] Running Loop=1
2023-09-08T15:07:25.148Z,1694185645.148 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-09-08T15:07:25.148Z,1694185645.148 [transit:Transit:A.Pitch] Running Loop=1
2023-09-08T15:07:25.148Z,1694185645.148 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-09-08T15:07:25.148Z,1694185645.148 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-08T15:07:25.149Z,1694185645.149 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-09-08T15:07:25.149Z,1694185645.149 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-09-08T15:07:25.149Z,1694185645.149 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-09-08T15:07:25.150Z,1694185645.150 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 41.761070,-81.278572
2023-09-08T15:07:25.151Z,1694185645.151 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2023-09-08T15:07:25.546Z,1694185645.546 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-08T15:07:25.546Z,1694185645.546 [transit:Transit:A.Pitch] Running Loop=1
2023-09-08T15:08:10.985Z,1694185690.985 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T150810
2023-09-08T15:08:18.923Z,1694185698.923 [DataOverHttps](INFO): Received command: stop
2023-09-08T15:08:19.005Z,1694185699.005 [CommandExec](IMPORTANT): got command stop
2023-09-08T15:08:19.005Z,1694185699.005 [CommandExec](IMPORTANT): Scheduling is paused
2023-09-08T15:08:19.006Z,1694185699.006 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-09-08T15:08:19.229Z,1694185699.229 [MissionManager](INFO): MissionManager is completed.
2023-09-08T15:08:19.229Z,1694185699.229 [MissionManager](INFO): Uninitializing Mission transit
2023-09-08T15:08:19.229Z,1694185699.229 [transit] Stopped
2023-09-08T15:08:19.230Z,1694185699.230 [transit](DEBUG): Aggregate::uninitialize transit
2023-09-08T15:08:19.230Z,1694185699.230 [transit:StandardEnvelopes] Stopped
2023-09-08T15:08:19.230Z,1694185699.230 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2023-09-08T15:08:19.230Z,1694185699.230 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2023-09-08T15:08:19.230Z,1694185699.230 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2023-09-08T15:08:19.230Z,1694185699.230 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2023-09-08T15:08:19.230Z,1694185699.230 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2023-09-08T15:08:19.230Z,1694185699.230 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2023-09-08T15:08:19.230Z,1694185699.230 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2023-09-08T15:08:19.230Z,1694185699.230 [transit:BackseatDriver] Stopped
2023-09-08T15:08:19.230Z,1694185699.230 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2023-09-08T15:08:19.230Z,1694185699.230 [transit:BackseatDriver:A.BackseatDriver] Stopped
2023-09-08T15:08:19.230Z,1694185699.230 [transit:BuoyancyHold.Buoyancy] Stopped
2023-09-08T15:08:19.231Z,1694185699.231 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-09-08T15:08:19.231Z,1694185699.231 [transit:MassHold.Pitch] Stopped
2023-09-08T15:08:19.231Z,1694185699.231 [transit:Transit] Stopped
2023-09-08T15:08:19.231Z,1694185699.231 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2023-09-08T15:08:19.231Z,1694185699.231 [transit:Transit:A.Pitch] Stopped
2023-09-08T15:08:19.231Z,1694185699.231 [transit:Transit:B.SetSpeed] Stopped
2023-09-08T15:08:19.231Z,1694185699.231 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2023-09-08T15:08:19.231Z,1694185699.231 [transit:Transit:Wpt1.Waypoint] Stopped
2023-09-08T15:08:19.231Z,1694185699.231 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2023-09-08T15:08:19.635Z,1694185699.635 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T15:08:19.636Z,1694185699.636 [Default] Running Loop=1
2023-09-08T15:08:19.636Z,1694185699.636 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T15:08:19.636Z,1694185699.636 [Default:B.GoToSurface] Running Loop=1
2023-09-08T15:08:19.636Z,1694185699.636 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T15:08:19.636Z,1694185699.636 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T15:08:19.637Z,1694185699.637 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T15:08:19.637Z,1694185699.637 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T15:08:19.637Z,1694185699.637 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T15:08:19.638Z,1694185699.638 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T15:08:19.638Z,1694185699.638 [Default:A.Wait] Running Loop=1
2023-09-08T15:08:19.638Z,1694185699.638 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:08:32.977Z,1694185712.977 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T15:08:32.977Z,1694185712.977 [Default:A.Wait] Stopped
2023-09-08T15:08:32.977Z,1694185712.977 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:08:33.364Z,1694185713.364 [Default:CheckIn] Running Loop=1
2023-09-08T15:08:33.364Z,1694185713.364 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T15:08:33.365Z,1694185713.365 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T15:08:35.369Z,1694185715.369 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150835.00,A,4145.53142,N,08116.65360,W,0.039,355.94,080923,,,D*7A
2023-09-08T15:08:35.371Z,1694185715.371 [NAL9602](INFO): GPS fix at 20230908T150835: (41.758857, -81.277560)
2023-09-08T15:08:35.381Z,1694185715.381 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T15:08:35.382Z,1694185715.382 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T15:08:47.960Z,1694185727.960 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230908T135219/Courier0040.lzma
2023-09-08T15:08:48.962Z,1694185728.962 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Courier0040.lzma.bak
2023-09-08T15:08:48.962Z,1694185728.962 [DataOverHttps](INFO): SBD MOMSN=18750462
2023-09-08T15:08:51.943Z,1694185731.943 [NAL9602](INFO): SBD MO Status=2, MOMSN=9035, MT Status=2, MTMSN=0
2023-09-08T15:08:51.943Z,1694185731.943 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T15:08:57.599Z,1694185737.599 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-08T15:08:57.602Z,1694185737.602 [BPC1](INFO): Received data from all battery sticks.
2023-09-08T15:09:04.064Z,1694185744.064 [NAL9602](INFO): SBD MO Status=0, MOMSN=9035, MT Status=0, MTMSN=0
2023-09-08T15:09:04.065Z,1694185744.065 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:09:07.025Z,1694185747.025 [DataOverHttps](INFO): Sending 926 bytes from file Logs/20230908T135219/Express0041.lzma
2023-09-08T15:09:08.026Z,1694185748.026 [DataOverHttps](INFO): Moved sent file to Logs/20230908T135219/Express0041.lzma.bak
2023-09-08T15:09:08.026Z,1694185748.026 [DataOverHttps](INFO): SBD MOMSN=18750466
2023-09-08T15:09:10.969Z,1694185750.969 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T15:09:10.969Z,1694185750.969 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T15:09:10.969Z,1694185750.969 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T15:09:34.778Z,1694185774.778 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T15:10:29.038Z,1694185829.038 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T151028
2023-09-08T15:10:39.629Z,1694185839.629 [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" fzh9 1 2
2023-09-08T15:10:39.640Z,1694185839.640 [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" fzh9 1 2.000000
2023-09-08T15:10:39.641Z,1694185839.641 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=fzh9
2023-09-08T15:10:39.642Z,1694185839.642 [CommandExec](IMPORTANT): Scheduled #5 (#1 of 2 with id='fzh9'): "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 41.76107 degree;set transit.Longitude -81.27857 degree;set transit.Depth 1 m;set transit.MaxDepth 3 m", ASAP
2023-09-08T15:10:40.630Z,1694185840.630 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T151040
2023-09-08T15:10:50.896Z,1694185850.896 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore .01 km;run" fzh9 2 2
2023-09-08T15:10:50.907Z,1694185850.907 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore .01 km;run" fzh9 2 2.000000
2023-09-08T15:10:50.907Z,1694185850.907 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=fzh9
2023-09-08T15:10:50.908Z,1694185850.908 [CommandExec](IMPORTANT): Scheduled #6 (#2 of 2 with id='fzh9'): "set transit.MinOffshore .01 km;run", ASAP
2023-09-08T15:10:51.034Z,1694185851.034 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2023-09-08T15:10:51.035Z,1694185851.035 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2023-09-08T15:10:51.035Z,1694185851.035 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx
2023-09-08T15:10:51.186Z,1694185851.186 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2023-09-08T15:10:51.188Z,1694185851.188 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2023-09-08T15:10:51.190Z,1694185851.190 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2023-09-08T15:10:51.213Z,1694185851.213 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2023-09-08T15:10:51.215Z,1694185851.215 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2023-09-08T15:10:51.229Z,1694185851.229 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2023-09-08T15:10:51.240Z,1694185851.240 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2023-09-08T15:10:51.242Z,1694185851.242 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2023-09-08T15:10:51.252Z,1694185851.252 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2023-09-08T15:10:51.254Z,1694185851.254 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2023-09-08T15:10:51.263Z,1694185851.263 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2023-09-08T15:10:51.265Z,1694185851.265 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2023-09-08T15:10:51.267Z,1694185851.267 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2023-09-08T15:10:51.267Z,1694185851.267 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2023-09-08T15:10:51.275Z,1694185851.275 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/NeedComms.tx
2023-09-08T15:10:51.377Z,1694185851.377 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2023-09-08T15:10:51.379Z,1694185851.379 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2023-09-08T15:10:51.398Z,1694185851.398 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2023-09-08T15:10:51.404Z,1694185851.404 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2023-09-08T15:10:51.406Z,1694185851.406 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2023-09-08T15:10:51.415Z,1694185851.415 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2023-09-08T15:10:51.421Z,1694185851.421 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2023-09-08T15:10:51.423Z,1694185851.423 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2023-09-08T15:10:51.442Z,1694185851.442 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T15:10:51.489Z,1694185851.489 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2023-09-08T15:10:51.489Z,1694185851.489 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/StandardEnvelopes.tx
2023-09-08T15:10:51.510Z,1694185851.510 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2023-09-08T15:10:51.512Z,1694185851.512 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2023-09-08T15:10:51.515Z,1694185851.515 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2023-09-08T15:10:51.517Z,1694185851.517 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2023-09-08T15:10:51.518Z,1694185851.518 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2023-09-08T15:10:51.523Z,1694185851.523 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2023-09-08T15:10:51.530Z,1694185851.530 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2023-09-08T15:10:51.586Z,1694185851.586 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-08T15:10:51.586Z,1694185851.586 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-08T15:10:51.628Z,1694185851.628 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2023-09-08T15:10:51.630Z,1694185851.630 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-08T15:10:51.638Z,1694185851.638 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-08T15:10:51.650Z,1694185851.650 [transit:MassHold.Pitch](DEBUG): Construct.
2023-09-08T15:10:51.671Z,1694185851.671 [transit:Transit:A.Pitch](DEBUG): Construct.
2023-09-08T15:10:51.679Z,1694185851.679 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2023-09-08T15:10:51.687Z,1694185851.687 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2023-09-08T15:10:51.724Z,1694185851.724 [MissionManager](DEBUG): 
mission transit {
  """
  Vehicle transits to desired waypoint and can be commanded to use/hold
  mass or buoyancy.
  """

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

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

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

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

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

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

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

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

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

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

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

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

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

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  assign in sequence NeedComms:WaitForPitchUp = 1 second

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MinAltitude = MinAltitude

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

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

    set position = Control:VerticalControl.buoyancyNeutral
  }

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

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate Transit {
    run in sequence

    behavior Guidance:Pitch {
      run in parallel

      set depth = Depth
    }

    behavior Guidance:SetSpeed {
      run in parallel

      set speed = Speed
    }

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

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

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


2023-09-08T15:10:51.724Z,1694185851.724 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl
2023-09-08T15:11:02.201Z,1694185862.201 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute
2023-09-08T15:11:02.201Z,1694185862.201 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2023-09-08T15:11:02.202Z,1694185862.202 [CommandExec](IMPORTANT): got command set transit.Latitude 41.76107 degree
2023-09-08T15:11:02.203Z,1694185862.203 [CommandExec](IMPORTANT): got command set transit.Longitude -81.27857 degree
2023-09-08T15:11:02.203Z,1694185862.203 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2023-09-08T15:11:02.204Z,1694185862.204 [CommandExec](IMPORTANT): got command set transit.MaxDepth 3 meter
2023-09-08T15:11:03.752Z,1694185863.752 [CommandExec](IMPORTANT): got command set transit.MinOffshore .01 kilometer
2023-09-08T15:11:03.752Z,1694185863.752 [CommandExec](IMPORTANT): got command run
2023-09-08T15:11:03.754Z,1694185863.754 [CommandExec](IMPORTANT): Running 
2023-09-08T15:11:04.121Z,1694185864.121 [Default] Stopped
2023-09-08T15:11:04.121Z,1694185864.121 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T15:11:04.121Z,1694185864.121 [Default:B.GoToSurface] Stopped
2023-09-08T15:11:04.121Z,1694185864.121 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T15:11:04.121Z,1694185864.121 [Default:CheckIn] Stopped
2023-09-08T15:11:04.121Z,1694185864.121 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T15:11:04.121Z,1694185864.121 [Default:CheckIn:C.Wait] Stopped
2023-09-08T15:11:04.122Z,1694185864.122 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T15:11:04.122Z,1694185864.122 [MissionManager](IMPORTANT): Started mission transit
2023-09-08T15:11:04.122Z,1694185864.122 [transit] Running Loop=1
2023-09-08T15:11:04.122Z,1694185864.122 [transit](DEBUG): Aggregate::initialize transit
2023-09-08T15:11:04.122Z,1694185864.122 [transit:StandardEnvelopes] Running Loop=1
2023-09-08T15:11:04.122Z,1694185864.122 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2023-09-08T15:11:04.122Z,1694185864.122 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-08T15:11:04.122Z,1694185864.122 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2023-09-08T15:11:04.123Z,1694185864.123 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-08T15:11:04.123Z,1694185864.123 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2023-09-08T15:11:04.123Z,1694185864.123 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-08T15:11:04.123Z,1694185864.123 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2023-09-08T15:11:04.123Z,1694185864.123 [transit:BackseatDriver] Running Loop=1
2023-09-08T15:11:04.123Z,1694185864.123 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2023-09-08T15:11:04.123Z,1694185864.123 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-08T15:11:04.123Z,1694185864.123 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-08T15:11:04.123Z,1694185864.123 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-09-08T15:11:04.123Z,1694185864.123 [transit:MassHold.Pitch] Running Loop=1
2023-09-08T15:11:04.124Z,1694185864.124 [transit:MassHold.Pitch](DEBUG): Initialize.
2023-09-08T15:11:04.124Z,1694185864.124 [transit:B] Running Loop=1
2023-09-08T15:11:04.124Z,1694185864.124 [transit:MassHold.Pitch] Running Loop=1
2023-09-08T15:11:04.125Z,1694185864.125 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2023-09-08T15:11:04.125Z,1694185864.125 [transit:BackseatDriver] Running Loop=1
2023-09-08T15:11:04.125Z,1694185864.125 [transit:StandardEnvelopes] Running Loop=1
2023-09-08T15:11:04.125Z,1694185864.125 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2023-09-08T15:11:04.180Z,1694185864.180 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2023-09-08T15:11:04.180Z,1694185864.180 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2023-09-08T15:11:04.181Z,1694185864.181 [transit:B] Stopped
2023-09-08T15:11:04.182Z,1694185864.182 [transit:C] Running Loop=1
2023-09-08T15:11:04.534Z,1694185864.534 [transit:C] Stopped
2023-09-08T15:11:04.534Z,1694185864.534 [transit:E] Running Loop=1
2023-09-08T15:11:04.957Z,1694185864.957 [transit:E] Stopped
2023-09-08T15:11:04.957Z,1694185864.957 [transit:F] Running Loop=1
2023-09-08T15:11:05.331Z,1694185865.331 [transit:F] Stopped
2023-09-08T15:11:05.331Z,1694185865.331 [transit:G] Running Loop=1
2023-09-08T15:11:05.735Z,1694185865.735 [transit:G] Stopped
2023-09-08T15:11:05.735Z,1694185865.735 [transit:Transit] Running Loop=1
2023-09-08T15:11:05.735Z,1694185865.735 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2023-09-08T15:11:05.735Z,1694185865.735 [transit:Transit:A.Pitch] Running Loop=1
2023-09-08T15:11:05.736Z,1694185865.736 [transit:Transit:A.Pitch](DEBUG): Initialize.
2023-09-08T15:11:05.736Z,1694185865.736 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-08T15:11:05.736Z,1694185865.736 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2023-09-08T15:11:05.736Z,1694185865.736 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2023-09-08T15:11:05.736Z,1694185865.736 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2023-09-08T15:11:05.737Z,1694185865.737 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 41.761070,-81.278572
2023-09-08T15:11:06.169Z,1694185866.169 [transit:Transit:B.SetSpeed] Running Loop=1
2023-09-08T15:11:06.169Z,1694185866.169 [transit:Transit:A.Pitch] Running Loop=1
2023-09-08T15:12:12.676Z,1694185932.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:12:43.800Z,1694185963.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:13:14.924Z,1694185994.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:13:46.048Z,1694186026.048 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:14:17.176Z,1694186057.176 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:14:21.209Z,1694186061.209 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.742752
2023-09-08T15:15:18.436Z,1694186118.436 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:15:42.599Z,1694186142.599 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 41.761070,-81.278572
2023-09-08T15:15:42.599Z,1694186142.599 [transit:Transit:Wpt1.Waypoint] Stopped
2023-09-08T15:15:42.599Z,1694186142.599 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2023-09-08T15:15:42.599Z,1694186142.599 [transit:Transit:PhoneHome] Running Loop=1
2023-09-08T15:15:42.599Z,1694186142.599 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome
2023-09-08T15:15:43.020Z,1694186143.020 [transit:NeedComms] Running Loop=1
2023-09-08T15:15:43.020Z,1694186143.020 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2023-09-08T15:15:43.020Z,1694186143.020 [transit:NeedComms:B.GoToSurface] Running Loop=1
2023-09-08T15:15:43.020Z,1694186143.020 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T15:15:43.021Z,1694186143.021 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2023-09-08T15:15:43.021Z,1694186143.021 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2023-09-08T15:15:43.021Z,1694186143.021 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2023-09-08T15:15:43.022Z,1694186143.022 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2023-09-08T15:15:43.022Z,1694186143.022 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T15:15:43.022Z,1694186143.022 [transit:NeedComms:A] Running Loop=1
2023-09-08T15:15:43.023Z,1694186143.023 [transit:NeedComms:A](INFO): last time_fix was: 1694185715.000000 second since 1970/01/01T00:00:00Z 
2023-09-08T15:15:43.024Z,1694186143.024 [transit:NeedComms:A] Stopped
2023-09-08T15:15:43.402Z,1694186143.402 [transit:NeedComms:C] Running Loop=1
2023-09-08T15:15:43.807Z,1694186143.807 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-08T15:15:49.560Z,1694186149.560 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:16:14.533Z,1694186174.533 [NAL9602](INFO): SBD MO Status=0, MOMSN=9036, MT Status=0, MTMSN=0
2023-09-08T15:16:14.533Z,1694186174.533 [NAL9602](INFO): No messages in MT queue
2023-09-08T15:16:15.733Z,1694186175.733 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151615.00,A,4145.62980,N,08116.69064,W,0.758,344.63,080923,,,D*70
2023-09-08T15:16:15.735Z,1694186175.735 [NAL9602](INFO): GPS fix at 20230908T151615: (41.760497, -81.278177)
2023-09-08T15:16:15.749Z,1694186175.749 [transit:NeedComms:C] Stopped
2023-09-08T15:16:15.749Z,1694186175.749 [transit:NeedComms:D] Running Loop=1
2023-09-08T15:16:16.152Z,1694186176.152 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T15:16:20.684Z,1694186180.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:16:38.749Z,1694186198.749 [NAL9602](INFO): SBD MO Status=1, MOMSN=9037, MT Status=0, MTMSN=0
2023-09-08T15:16:38.792Z,1694186198.792 [NAL9602](INFO): Sent 90 bytes from file Logs/20230908T135219/Courier0043.lzma
2023-09-08T15:16:38.792Z,1694186198.792 [NAL9602](INFO): Packets left to send: 0
2023-09-08T15:16:44.686Z,1694186204.686 [DVL_micro](ERROR): Failed to parse:
:SA,-02.91,+08.94,022.6
2023-09-08T15:16:51.812Z,1694186211.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:17:11.486Z,1694186231.486 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=9038, MT Status=1, MTMSN=3129
2023-09-08T15:17:11.540Z,1694186231.540 [NAL9602](INFO): Sent 332 bytes from file Logs/20230908T135219/Express0045.lzma
2023-09-08T15:17:11.541Z,1694186231.541 [NAL9602](INFO): Packets left to send: 2
2023-09-08T15:17:12.189Z,1694186232.189 [NAL9602](INFO): Received command: sched "load Science/sci2_flat_and_level.tl;set sci2_flat_and_level.NeedCommsTime 10 min;set sci2_flat_and_level.Depth 2 m;set sci2_flat_and_level.Lat1 41.7644 degree;set sci2_flat_and_level.Lon1 -81.25188 degree" fzix 1 3
2023-09-08T15:17:12.300Z,1694186232.300 [CommandExec](IMPORTANT): got command schedule "load Science/sci2_flat_and_level.tl;set sci2_flat_and_level.NeedCommsTime 10 min;set sci2_flat_and_level.Depth 2 m;set sci2_flat_and_level.Lat1 41.7644 degree;set sci2_flat_and_level.Lon1 -81.25188 degree" fzix 1 3.000000
2023-09-08T15:17:12.301Z,1694186232.301 [CommandExec](IMPORTANT): Scheduling command #1 of 3 with id=fzix
2023-09-08T15:17:12.302Z,1694186232.302 [CommandExec](IMPORTANT): Scheduled #7 (#1 of 3 with id='fzix'): "load Science/sci2_flat_and_level.tl;set sci2_flat_and_level.NeedCommsTime 10 min;set sci2_flat_and_level.Depth 2 m;set sci2_flat_and_level.Lat1 41.7644 degree;set sci2_flat_and_level.Lon1 -81.25188 degree", AFTER MISSION
2023-09-08T15:17:12.302Z,1694186232.302 [CommandExec](FAULT): Scheduling was paused by a command at 2023-09-08T15:08:19Z
2023-09-08T15:17:22.948Z,1694186242.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-08T15:17:42.771Z,1694186262.771 [NAL9602](INFO): SBD MO Status=2, MOMSN=9039, MT Status=2, MTMSN=0
2023-09-08T15:17:42.771Z,1694186262.771 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T15:17:53.453Z,1694186273.453 [DVL_micro](ERROR): Failed to parse:
:SA,-05.48,+10.22,023.9
2023-09-08T15:17:53.797Z,1694186273.797 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T151753
2023-09-08T15:17:53.801Z,1694186273.801 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003637